{"level":"warn","ts":"2026-10-26T19:19:41.152341Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:19:41.15247Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-26T19:19:41.152568Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:19:41.152582Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-10-26T19:19:41.152617Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T19:19:41.153473Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-10-26T19:19:41.153611Z","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.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-26T19:19:41.161683Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.738413ms"} {"level":"info","ts":"2026-10-26T19:19:41.171462Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-10-26T19:19:41.17158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:19:41.171607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-10-26T19:19:41.171624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:19:41.171632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-10-26T19:19:41.171718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-10-26T19:19:41.178534Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:19:41.180845Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:19:41.182789Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-26T19:19:41.185478Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:19:41.1858Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:19:41.185899Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:19:41.186045Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:19:41.186074Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:19:41.187524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-10-26T19:19:41.1884Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-10-26T19:19:41.190629Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T19:19:41.190739Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-10-26T19:19:41.190791Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-10-26T19:19:41.190944Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:19:41.191Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:19:42.072301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:19:42.072359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:19:42.072377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-10-26T19:19:42.072391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:19:42.072399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-10-26T19:19:42.072409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-10-26T19:19:42.072418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-10-26T19:19:42.074479Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:19:42.074539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:19:42.074603Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:19:42.074602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:19:42.074761Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:19:42.074795Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:19:42.077076Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T19:19:42.077366Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:19:42.077453Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:19:42.077488Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:19:42.07802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"warn","ts":"2026-10-26T19:19:59.883696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.714423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T19:19:59.883794Z","caller":"traceutil/trace.go:171","msg":"trace[170621237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"241.910398ms","start":"2026-10-26T19:19:59.641865Z","end":"2026-10-26T19:19:59.883776Z","steps":["trace[170621237] 'range keys from in-memory index tree' (duration: 241.59362ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:00.029256Z","caller":"traceutil/trace.go:171","msg":"trace[580558619] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"141.815061ms","start":"2026-10-26T19:19:59.887417Z","end":"2026-10-26T19:20:00.029232Z","steps":["trace[580558619] 'process raft request' (duration: 141.661327ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:05.267437Z","caller":"traceutil/trace.go:171","msg":"trace[355999397] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"142.12352ms","start":"2026-10-26T19:20:05.125279Z","end":"2026-10-26T19:20:05.267403Z","steps":["trace[355999397] 'process raft request' (duration: 141.892054ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:34.316094Z","caller":"traceutil/trace.go:171","msg":"trace[1021094773] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"110.343279ms","start":"2026-10-26T19:20:34.205727Z","end":"2026-10-26T19:20:34.316071Z","steps":["trace[1021094773] 'read index received' (duration: 109.63299ms)","trace[1021094773] 'applied index is now lower than readState.Index' (duration: 709.569µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:34.316166Z","caller":"traceutil/trace.go:171","msg":"trace[1064765586] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"284.025273ms","start":"2026-10-26T19:20:34.032119Z","end":"2026-10-26T19:20:34.316144Z","steps":["trace[1064765586] 'process raft request' (duration: 283.55414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:20:34.316302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.566716ms","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-10-26T19:20:34.316345Z","caller":"traceutil/trace.go:171","msg":"trace[1098295307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"110.679007ms","start":"2026-10-26T19:20:34.205657Z","end":"2026-10-26T19:20:34.316336Z","steps":["trace[1098295307] 'agreement among raft nodes before linearized reading' (duration: 110.504492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:20:34.926083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.188222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:20:34.926245Z","caller":"traceutil/trace.go:171","msg":"trace[1363040243] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:645; }","duration":"527.385926ms","start":"2026-10-26T19:20:34.398798Z","end":"2026-10-26T19:20:34.926184Z","steps":["trace[1363040243] 'range keys from in-memory index tree' (duration: 527.005697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:20:34.926307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:20:34.398774Z","time spent":"527.51867ms","remote":"127.0.0.1:42668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-10-26T19:20:34.926368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.141383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nphs9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-26T19:20:34.926512Z","caller":"traceutil/trace.go:171","msg":"trace[108381153] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nphs9; range_end:; response_count:1; response_revision:645; }","duration":"266.350398ms","start":"2026-10-26T19:20:34.660136Z","end":"2026-10-26T19:20:34.926487Z","steps":["trace[108381153] 'range keys from in-memory index tree' (duration: 265.907937ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:35.302378Z","caller":"traceutil/trace.go:171","msg":"trace[1237997654] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"251.232011ms","start":"2026-10-26T19:20:35.051108Z","end":"2026-10-26T19:20:35.30234Z","steps":["trace[1237997654] 'read index received' (duration: 239.321958ms)","trace[1237997654] 'applied index is now lower than readState.Index' (duration: 11.908163ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:20:35.302564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.441346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:20:35.302614Z","caller":"traceutil/trace.go:171","msg":"trace[1552175714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"251.549529ms","start":"2026-10-26T19:20:35.051049Z","end":"2026-10-26T19:20:35.302599Z","steps":["trace[1552175714] 'agreement among raft nodes before linearized reading' (duration: 251.405395ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:35.302813Z","caller":"traceutil/trace.go:171","msg":"trace[805478854] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"353.340984ms","start":"2026-10-26T19:20:34.949429Z","end":"2026-10-26T19:20:35.30277Z","steps":["trace[805478854] 'process raft request' (duration: 340.946657ms)","trace[805478854] 'compare' (duration: 11.345029ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:20:35.303639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:20:34.949384Z","time spent":"353.504899ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:21:19.396817Z","caller":"traceutil/trace.go:171","msg":"trace[816164737] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"274.634266ms","start":"2026-10-26T19:21:19.122146Z","end":"2026-10-26T19:21:19.396781Z","steps":["trace[816164737] 'process raft request' (duration: 274.331928ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:19.433137Z","caller":"traceutil/trace.go:171","msg":"trace[621098318] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"283.843537ms","start":"2026-10-26T19:21:19.149266Z","end":"2026-10-26T19:21:19.43311Z","steps":["trace[621098318] 'process raft request' (duration: 283.673092ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:19.433371Z","caller":"traceutil/trace.go:171","msg":"trace[293079373] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"247.81047ms","start":"2026-10-26T19:21:19.185517Z","end":"2026-10-26T19:21:19.433327Z","steps":["trace[293079373] 'process raft request' (duration: 247.541073ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:39.601954Z","caller":"traceutil/trace.go:171","msg":"trace[147268960] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"102.428437ms","start":"2026-10-26T19:21:39.499504Z","end":"2026-10-26T19:21:39.601932Z","steps":["trace[147268960] 'process raft request' (duration: 102.334654ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:39.602004Z","caller":"traceutil/trace.go:171","msg":"trace[283374359] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"135.618942ms","start":"2026-10-26T19:21:39.466342Z","end":"2026-10-26T19:21:39.601961Z","steps":["trace[283374359] 'process raft request' (duration: 94.662173ms)","trace[283374359] 'compare' (duration: 40.579378ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:05.917762Z","caller":"traceutil/trace.go:171","msg":"trace[1723784540] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"209.847916ms","start":"2026-10-26T19:22:05.707893Z","end":"2026-10-26T19:22:05.917741Z","steps":["trace[1723784540] 'process raft request' (duration: 149.686091ms)","trace[1723784540] 'compare' (duration: 60.067453ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:06.61056Z","caller":"traceutil/trace.go:171","msg":"trace[1789338833] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"495.356677ms","start":"2026-10-26T19:22:06.11517Z","end":"2026-10-26T19:22:06.610526Z","steps":["trace[1789338833] 'read index received' (duration: 495.246424ms)","trace[1789338833] 'applied index is now lower than readState.Index' (duration: 109.053µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:06.610732Z","caller":"traceutil/trace.go:171","msg":"trace[818309516] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"504.217228ms","start":"2026-10-26T19:22:06.106481Z","end":"2026-10-26T19:22:06.610698Z","steps":["trace[818309516] 'process raft request' (duration: 503.839349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:06.610851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.655374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05cee89f-336a-471b-845d-6dc9832f362c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-26T19:22:06.610918Z","caller":"traceutil/trace.go:171","msg":"trace[114272121] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-05cee89f-336a-471b-845d-6dc9832f362c; range_end:; response_count:1; response_revision:1883; }","duration":"495.772687ms","start":"2026-10-26T19:22:06.11513Z","end":"2026-10-26T19:22:06.610903Z","steps":["trace[114272121] 'agreement among raft nodes before linearized reading' (duration: 495.555111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:06.610922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.106452Z","time spent":"504.393483ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:22:06.610961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.115106Z","time spent":"495.842417ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05cee89f-336a-471b-845d-6dc9832f362c\" "} {"level":"warn","ts":"2026-10-26T19:22:06.627729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.02502ms","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":"warn","ts":"2026-10-26T19:22:06.627757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.636597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05cee89f-336a-471b-845d-6dc9832f362c\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-26T19:22:06.627792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.328876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T19:22:06.627815Z","caller":"traceutil/trace.go:171","msg":"trace[343975574] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1884; }","duration":"467.160823ms","start":"2026-10-26T19:22:06.160635Z","end":"2026-10-26T19:22:06.627796Z","steps":["trace[343975574] 'agreement among raft nodes before linearized reading' (duration: 466.855475ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:06.62784Z","caller":"traceutil/trace.go:171","msg":"trace[1239911014] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-05cee89f-336a-471b-845d-6dc9832f362c; range_end:; response_count:1; response_revision:1884; }","duration":"134.768919ms","start":"2026-10-26T19:22:06.493049Z","end":"2026-10-26T19:22:06.627817Z","steps":["trace[1239911014] 'agreement among raft nodes before linearized reading' (duration: 134.463581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:06.62786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.160615Z","time spent":"467.234135ms","remote":"127.0.0.1:34780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:22:06.627856Z","caller":"traceutil/trace.go:171","msg":"trace[1808007750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1884; }","duration":"271.434968ms","start":"2026-10-26T19:22:06.356404Z","end":"2026-10-26T19:22:06.627839Z","steps":["trace[1808007750] 'agreement among raft nodes before linearized reading' (duration: 271.290465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:06.627859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.23202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T19:22:06.627922Z","caller":"traceutil/trace.go:171","msg":"trace[1241700993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1884; }","duration":"212.322731ms","start":"2026-10-26T19:22:06.415583Z","end":"2026-10-26T19:22:06.627906Z","steps":["trace[1241700993] 'agreement among raft nodes before linearized reading' (duration: 212.171227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:06.628338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.928151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-10-26T19:22:06.628564Z","caller":"traceutil/trace.go:171","msg":"trace[549245236] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1884; }","duration":"354.054245ms","start":"2026-10-26T19:22:06.274349Z","end":"2026-10-26T19:22:06.628403Z","steps":["trace[549245236] 'agreement among raft nodes before linearized reading' (duration: 353.451898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:06.628672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.274329Z","time spent":"354.326061ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-26T19:22:07.031316Z","caller":"traceutil/trace.go:171","msg":"trace[620923502] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"410.750577ms","start":"2026-10-26T19:22:06.620457Z","end":"2026-10-26T19:22:07.031208Z","steps":["trace[620923502] 'process raft request' (duration: 410.545272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:07.031523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.620433Z","time spent":"410.996163ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:22:07.104302Z","caller":"traceutil/trace.go:171","msg":"trace[1773042165] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"471.747404ms","start":"2026-10-26T19:22:06.632526Z","end":"2026-10-26T19:22:07.104274Z","steps":["trace[1773042165] 'process raft request' (duration: 471.489717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:07.104461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.632502Z","time spent":"471.878786ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:22:07.104642Z","caller":"traceutil/trace.go:171","msg":"trace[1093409851] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"471.824916ms","start":"2026-10-26T19:22:06.632787Z","end":"2026-10-26T19:22:07.104612Z","steps":["trace[1093409851] 'process raft request' (duration: 471.428405ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:07.104822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.632765Z","time spent":"471.967049ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:22:07.104887Z","caller":"traceutil/trace.go:171","msg":"trace[313590865] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"471.417295ms","start":"2026-10-26T19:22:06.63345Z","end":"2026-10-26T19:22:07.104867Z","steps":["trace[313590865] 'process raft request' (duration: 471.040325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:07.104969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.633424Z","time spent":"471.505238ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:22:07.105Z","caller":"traceutil/trace.go:171","msg":"trace[792712199] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1964; }","duration":"381.326312ms","start":"2026-10-26T19:22:06.723649Z","end":"2026-10-26T19:22:07.104975Z","steps":["trace[792712199] 'read index received' (duration: 307.381925ms)","trace[792712199] 'applied index is now lower than readState.Index' (duration: 73.943037ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:07.105061Z","caller":"traceutil/trace.go:171","msg":"trace[1068312021] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"420.822673ms","start":"2026-10-26T19:22:06.68422Z","end":"2026-10-26T19:22:07.105042Z","steps":["trace[1068312021] 'process raft request' (duration: 420.540065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:07.105163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.68418Z","time spent":"420.936336ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:22:07.105242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.282438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:22:07.105116Z","caller":"traceutil/trace.go:171","msg":"trace[108999326] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"411.652191ms","start":"2026-10-26T19:22:06.693424Z","end":"2026-10-26T19:22:07.105077Z","steps":["trace[108999326] 'process raft request' (duration: 411.497877ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:07.10529Z","caller":"traceutil/trace.go:171","msg":"trace[2081476432] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1890; }","duration":"128.412751ms","start":"2026-10-26T19:22:06.976856Z","end":"2026-10-26T19:22:07.105269Z","steps":["trace[2081476432] 'agreement among raft nodes before linearized reading' (duration: 128.286658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:07.10542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.782307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-10-26T19:22:07.105555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.693402Z","time spent":"411.923419ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:22:07.1056Z","caller":"traceutil/trace.go:171","msg":"trace[770927744] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"113.996313ms","start":"2026-10-26T19:22:06.991595Z","end":"2026-10-26T19:22:07.105591Z","steps":["trace[770927744] 'agreement among raft nodes before linearized reading' (duration: 113.708755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:07.105382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.713423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18e22aacd56fbd44\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-10-26T19:22:07.105697Z","caller":"traceutil/trace.go:171","msg":"trace[1734191478] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18e22aacd56fbd44; range_end:; response_count:1; response_revision:1890; }","duration":"382.043592ms","start":"2026-10-26T19:22:06.723634Z","end":"2026-10-26T19:22:07.105678Z","steps":["trace[1734191478] 'agreement among raft nodes before linearized reading' (duration: 381.482007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:07.105759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:06.723556Z","time spent":"382.185556ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1006,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18e22aacd56fbd44\" "} {"level":"info","ts":"2026-10-26T19:22:13.489158Z","caller":"traceutil/trace.go:171","msg":"trace[1846828724] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"131.312169ms","start":"2026-10-26T19:22:13.357825Z","end":"2026-10-26T19:22:13.489137Z","steps":["trace[1846828724] 'process raft request' (duration: 131.144805ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:20.109357Z","caller":"traceutil/trace.go:171","msg":"trace[1541691437] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"141.286161ms","start":"2026-10-26T19:22:19.968037Z","end":"2026-10-26T19:22:20.109323Z","steps":["trace[1541691437] 'process raft request' (duration: 140.753957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:57.831705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636553963815142691,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-26T19:22:58.081216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.963134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:22:58.081326Z","caller":"traceutil/trace.go:171","msg":"trace[145827103] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2206; }","duration":"812.16964ms","start":"2026-10-26T19:22:57.269127Z","end":"2026-10-26T19:22:58.081296Z","steps":["trace[145827103] 'count revisions from in-memory index tree' (duration: 811.80213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.081388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.269098Z","time spent":"812.272893ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:22:58.082258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.089465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:22:58.082368Z","caller":"traceutil/trace.go:171","msg":"trace[1110913560] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"797.237845ms","start":"2026-10-26T19:22:57.28511Z","end":"2026-10-26T19:22:58.082348Z","steps":["trace[1110913560] 'process raft request' (duration: 669.565654ms)","trace[1110913560] 'compare' (duration: 127.001503ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:22:58.082432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.285077Z","time spent":"797.326517ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:22:58.09839Z","caller":"traceutil/trace.go:171","msg":"trace[2043510306] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"767.147719ms","start":"2026-10-26T19:22:57.331221Z","end":"2026-10-26T19:22:58.098369Z","steps":["trace[2043510306] 'process raft request' (duration: 766.777509ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:58.098374Z","caller":"traceutil/trace.go:171","msg":"trace[1836023538] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2298; }","duration":"766.971744ms","start":"2026-10-26T19:22:57.331361Z","end":"2026-10-26T19:22:58.098332Z","steps":["trace[1836023538] 'read index received' (duration: 623.445394ms)","trace[1836023538] 'applied index is now lower than readState.Index' (duration: 143.5247ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:22:58.098473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.331187Z","time spent":"767.247412ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:22:58.098486Z","caller":"traceutil/trace.go:171","msg":"trace[1584028860] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"518.532877ms","start":"2026-10-26T19:22:57.579921Z","end":"2026-10-26T19:22:58.098454Z","steps":["trace[1584028860] 'process raft request' (duration: 518.343382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.098613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.537122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:22:58.09863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.579895Z","time spent":"518.659491ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:22:58.098647Z","caller":"traceutil/trace.go:171","msg":"trace[2038815235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2209; }","duration":"596.598514ms","start":"2026-10-26T19:22:57.50204Z","end":"2026-10-26T19:22:58.098639Z","steps":["trace[2038815235] 'agreement among raft nodes before linearized reading' (duration: 596.529962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.098672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.502014Z","time spent":"596.653055ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T19:22:58.098756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.603468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T19:22:58.098821Z","caller":"traceutil/trace.go:171","msg":"trace[195520612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2209; }","duration":"728.752832ms","start":"2026-10-26T19:22:57.370049Z","end":"2026-10-26T19:22:58.098802Z","steps":["trace[195520612] 'agreement among raft nodes before linearized reading' (duration: 728.510556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.098867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.383621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:22:58.098928Z","caller":"traceutil/trace.go:171","msg":"trace[2145207455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2209; }","duration":"391.448002ms","start":"2026-10-26T19:22:57.707466Z","end":"2026-10-26T19:22:58.098914Z","steps":["trace[2145207455] 'agreement among raft nodes before linearized reading' (duration: 391.273948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.098931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.029041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-26T19:22:58.098966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.707447Z","time spent":"391.511685ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T19:22:58.098968Z","caller":"traceutil/trace.go:171","msg":"trace[512229186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2209; }","duration":"672.107913ms","start":"2026-10-26T19:22:57.426849Z","end":"2026-10-26T19:22:58.098957Z","steps":["trace[512229186] 'agreement among raft nodes before linearized reading' (duration: 671.99691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.098983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.932423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-10-26T19:22:58.098994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.721994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-10-26T19:22:58.099001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.426824Z","time spent":"672.171175ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T19:22:58.099012Z","caller":"traceutil/trace.go:171","msg":"trace[5893032] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2209; }","duration":"610.991685ms","start":"2026-10-26T19:22:57.488011Z","end":"2026-10-26T19:22:58.099003Z","steps":["trace[5893032] 'agreement among raft nodes before linearized reading' (duration: 610.884762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.098874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.370018Z","time spent":"728.843885ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-26T19:22:58.099044Z","caller":"traceutil/trace.go:171","msg":"trace[169708636] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2209; }","duration":"676.809936ms","start":"2026-10-26T19:22:57.422217Z","end":"2026-10-26T19:22:58.099027Z","steps":["trace[169708636] 'agreement among raft nodes before linearized reading' (duration: 676.690393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.099089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.422163Z","time spent":"676.91604ms","remote":"127.0.0.1:42730","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-10-26T19:22:58.098827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.475734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T19:22:58.099043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.487992Z","time spent":"611.044097ms","remote":"127.0.0.1:51962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5724,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-26T19:22:58.099175Z","caller":"traceutil/trace.go:171","msg":"trace[968685200] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2209; }","duration":"303.908116ms","start":"2026-10-26T19:22:57.795237Z","end":"2026-10-26T19:22:58.099145Z","steps":["trace[968685200] 'agreement among raft nodes before linearized reading' (duration: 303.367151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.099127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.605951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-10-26T19:22:58.099262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.795208Z","time spent":"304.034979ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":22,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:22:58.099231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.948473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T19:22:58.09944Z","caller":"traceutil/trace.go:171","msg":"trace[730200295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2209; }","duration":"685.343643ms","start":"2026-10-26T19:22:57.414072Z","end":"2026-10-26T19:22:58.099416Z","steps":["trace[730200295] 'agreement among raft nodes before linearized reading' (duration: 684.87692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.099491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.414043Z","time spent":"685.438976ms","remote":"127.0.0.1:42730","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-10-26T19:22:58.099478Z","caller":"traceutil/trace.go:171","msg":"trace[1112994944] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2209; }","duration":"768.030742ms","start":"2026-10-26T19:22:57.331279Z","end":"2026-10-26T19:22:58.09931Z","steps":["trace[1112994944] 'agreement among raft nodes before linearized reading' (duration: 767.262901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:58.099608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:57.331249Z","time spent":"768.339541ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-10-26T19:24:32.761359Z","caller":"traceutil/trace.go:171","msg":"trace[1124578922] transaction","detail":"{read_only:false; response_revision:2917; number_of_response:1; }","duration":"145.760922ms","start":"2026-10-26T19:24:32.615575Z","end":"2026-10-26T19:24:32.761336Z","steps":["trace[1124578922] 'process raft request' (duration: 145.577097ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:24:32.777245Z","caller":"traceutil/trace.go:171","msg":"trace[1090831004] linearizableReadLoop","detail":"{readStateIndex:3036; appliedIndex:3034; }","duration":"116.46107ms","start":"2026-10-26T19:24:32.660764Z","end":"2026-10-26T19:24:32.777225Z","steps":["trace[1090831004] 'read index received' (duration: 100.397197ms)","trace[1090831004] 'applied index is now lower than readState.Index' (duration: 16.062643ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:24:32.777331Z","caller":"traceutil/trace.go:171","msg":"trace[855305446] transaction","detail":"{read_only:false; response_revision:2918; number_of_response:1; }","duration":"160.504561ms","start":"2026-10-26T19:24:32.616788Z","end":"2026-10-26T19:24:32.777293Z","steps":["trace[855305446] 'process raft request' (duration: 160.301975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:24:32.777499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.712517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-26T19:24:32.777685Z","caller":"traceutil/trace.go:171","msg":"trace[288216643] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2918; }","duration":"116.950882ms","start":"2026-10-26T19:24:32.660714Z","end":"2026-10-26T19:24:32.777665Z","steps":["trace[288216643] 'agreement among raft nodes before linearized reading' (duration: 116.622753ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:24:52.970356Z","caller":"traceutil/trace.go:171","msg":"trace[1890582324] transaction","detail":"{read_only:false; response_revision:3027; number_of_response:1; }","duration":"103.499059ms","start":"2026-10-26T19:24:52.866822Z","end":"2026-10-26T19:24:52.970321Z","steps":["trace[1890582324] 'process raft request' (duration: 103.297674ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:45.816282Z","caller":"traceutil/trace.go:171","msg":"trace[214199405] linearizableReadLoop","detail":"{readStateIndex:3903; appliedIndex:3902; }","duration":"117.741161ms","start":"2026-10-26T19:26:45.698514Z","end":"2026-10-26T19:26:45.816255Z","steps":["trace[214199405] 'read index received' (duration: 112.767481ms)","trace[214199405] 'applied index is now lower than readState.Index' (duration: 4.97227ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:26:45.816407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.880344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rsakeys.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:26:45.816441Z","caller":"traceutil/trace.go:171","msg":"trace[79573503] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rsakeys.secretgen.k14s.io; range_end:; response_count:0; response_revision:3752; }","duration":"117.959226ms","start":"2026-10-26T19:26:45.698471Z","end":"2026-10-26T19:26:45.81643Z","steps":["trace[79573503] 'agreement among raft nodes before linearized reading' (duration: 117.886454ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:45.816455Z","caller":"traceutil/trace.go:171","msg":"trace[248846433] transaction","detail":"{read_only:false; response_revision:3752; number_of_response:1; }","duration":"119.609509ms","start":"2026-10-26T19:26:45.696802Z","end":"2026-10-26T19:26:45.816412Z","steps":["trace[248846433] 'process raft request' (duration: 114.497665ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:45.816943Z","caller":"traceutil/trace.go:171","msg":"trace[1411389228] transaction","detail":"{read_only:false; response_revision:3753; number_of_response:1; }","duration":"117.031392ms","start":"2026-10-26T19:26:45.699885Z","end":"2026-10-26T19:26:45.816917Z","steps":["trace[1411389228] 'process raft request' (duration: 116.753744ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:43.090275Z","caller":"traceutil/trace.go:171","msg":"trace[2016380187] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"116.438933ms","start":"2026-10-26T19:28:42.9738Z","end":"2026-10-26T19:28:43.090239Z","steps":["trace[2016380187] 'process raft request' (duration: 90.005531ms)","trace[2016380187] 'compare' (duration: 26.235617ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:43.090387Z","caller":"traceutil/trace.go:171","msg":"trace[1115467579] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"116.497896ms","start":"2026-10-26T19:28:42.973825Z","end":"2026-10-26T19:28:43.090323Z","steps":["trace[1115467579] 'process raft request' (duration: 116.369732ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:43.090469Z","caller":"traceutil/trace.go:171","msg":"trace[1273495449] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"114.333658ms","start":"2026-10-26T19:28:42.976128Z","end":"2026-10-26T19:28:43.090461Z","steps":["trace[1273495449] 'process raft request' (duration: 114.189874ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:43.090289Z","caller":"traceutil/trace.go:171","msg":"trace[117618816] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"116.437814ms","start":"2026-10-26T19:28:42.973825Z","end":"2026-10-26T19:28:43.090263Z","steps":["trace[117618816] 'process raft request' (duration: 116.341281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:43.090614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.68609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-memcached-chunks-cache.18e22b09d9d139cc\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-10-26T19:28:43.09043Z","caller":"traceutil/trace.go:171","msg":"trace[758737302] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"116.164486ms","start":"2026-10-26T19:28:42.974258Z","end":"2026-10-26T19:28:43.090422Z","steps":["trace[758737302] 'process raft request' (duration: 115.984021ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:43.090668Z","caller":"traceutil/trace.go:171","msg":"trace[105190242] range","detail":"{range_begin:/registry/events/monitoring/loki-memcached-chunks-cache.18e22b09d9d139cc; range_end:; response_count:1; response_revision:5065; }","duration":"116.809704ms","start":"2026-10-26T19:28:42.973848Z","end":"2026-10-26T19:28:43.090657Z","steps":["trace[105190242] 'agreement among raft nodes before linearized reading' (duration: 116.614788ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:43.09042Z","caller":"traceutil/trace.go:171","msg":"trace[684999404] linearizableReadLoop","detail":"{readStateIndex:5263; appliedIndex:5260; }","duration":"116.501086ms","start":"2026-10-26T19:28:42.9739Z","end":"2026-10-26T19:28:43.090401Z","steps":["trace[684999404] 'read index received' (duration: 89.95509ms)","trace[684999404] 'applied index is now lower than readState.Index' (duration: 26.545016ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:28:46.212092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.085478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:28:46.212175Z","caller":"traceutil/trace.go:171","msg":"trace[777268618] linearizableReadLoop","detail":"{readStateIndex:5389; appliedIndex:5388; }","duration":"245.433787ms","start":"2026-10-26T19:28:45.966726Z","end":"2026-10-26T19:28:46.212159Z","steps":["trace[777268618] 'read index received' (duration: 77.166563ms)","trace[777268618] 'applied index is now lower than readState.Index' (duration: 168.266404ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:46.212342Z","caller":"traceutil/trace.go:171","msg":"trace[1663083080] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"340.87194ms","start":"2026-10-26T19:28:45.87146Z","end":"2026-10-26T19:28:46.212332Z","steps":["trace[1663083080] 'process raft request' (duration: 172.480774ms)","trace[1663083080] 'compare' (duration: 167.873423ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:28:46.212381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:28:45.871437Z","time spent":"340.923131ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:28:46.212562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.835158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e4f9706-58f4-41d6-b5c0-1092d16ce588\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-26T19:28:46.212582Z","caller":"traceutil/trace.go:171","msg":"trace[599446774] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e4f9706-58f4-41d6-b5c0-1092d16ce588; range_end:; response_count:1; response_revision:5186; }","duration":"245.88798ms","start":"2026-10-26T19:28:45.966686Z","end":"2026-10-26T19:28:46.212574Z","steps":["trace[599446774] 'agreement among raft nodes before linearized reading' (duration: 245.801788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:46.212638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.850052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:28:46.21271Z","caller":"traceutil/trace.go:171","msg":"trace[1997720127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5186; }","duration":"162.972535ms","start":"2026-10-26T19:28:46.04972Z","end":"2026-10-26T19:28:46.212693Z","steps":["trace[1997720127] 'agreement among raft nodes before linearized reading' (duration: 162.838571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:46.21287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.861703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e4f9706-58f4-41d6-b5c0-1092d16ce588\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-26T19:28:46.212897Z","caller":"traceutil/trace.go:171","msg":"trace[1793940866] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e4f9706-58f4-41d6-b5c0-1092d16ce588; range_end:; response_count:1; response_revision:5186; }","duration":"159.934415ms","start":"2026-10-26T19:28:46.052956Z","end":"2026-10-26T19:28:46.212891Z","steps":["trace[1793940866] 'agreement among raft nodes before linearized reading' (duration: 159.868074ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:46.367719Z","caller":"traceutil/trace.go:171","msg":"trace[198608560] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"143.828063ms","start":"2026-10-26T19:28:46.22387Z","end":"2026-10-26T19:28:46.367698Z","steps":["trace[198608560] 'process raft request' (duration: 121.694632ms)","trace[198608560] 'compare' (duration: 22.021378ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:46.38352Z","caller":"traceutil/trace.go:171","msg":"trace[12061236] linearizableReadLoop","detail":"{readStateIndex:5391; appliedIndex:5389; }","duration":"155.15435ms","start":"2026-10-26T19:28:46.228348Z","end":"2026-10-26T19:28:46.383503Z","steps":["trace[12061236] 'read index received' (duration: 117.04482ms)","trace[12061236] 'applied index is now lower than readState.Index' (duration: 38.1088ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:46.383595Z","caller":"traceutil/trace.go:171","msg":"trace[1449797133] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"159.074743ms","start":"2026-10-26T19:28:46.224483Z","end":"2026-10-26T19:28:46.383557Z","steps":["trace[1449797133] 'process raft request' (duration: 158.894308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:46.383701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.331785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1984"} {"level":"warn","ts":"2026-10-26T19:28:46.38372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.840952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T19:28:46.383737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.979157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-26T19:28:46.383763Z","caller":"traceutil/trace.go:171","msg":"trace[620235515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5188; }","duration":"122.913974ms","start":"2026-10-26T19:28:46.260837Z","end":"2026-10-26T19:28:46.383751Z","steps":["trace[620235515] 'agreement among raft nodes before linearized reading' (duration: 122.803051ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:46.383785Z","caller":"traceutil/trace.go:171","msg":"trace[852802507] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5188; }","duration":"112.060319ms","start":"2026-10-26T19:28:46.271711Z","end":"2026-10-26T19:28:46.383772Z","steps":["trace[852802507] 'agreement among raft nodes before linearized reading' (duration: 111.952746ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:46.383744Z","caller":"traceutil/trace.go:171","msg":"trace[1080048401] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5188; }","duration":"155.410187ms","start":"2026-10-26T19:28:46.228321Z","end":"2026-10-26T19:28:46.383731Z","steps":["trace[1080048401] 'agreement among raft nodes before linearized reading' (duration: 155.268963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:46.38387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.743944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:28:46.383946Z","caller":"traceutil/trace.go:171","msg":"trace[1188286273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5188; }","duration":"121.839917ms","start":"2026-10-26T19:28:46.262092Z","end":"2026-10-26T19:28:46.383932Z","steps":["trace[1188286273] 'agreement among raft nodes before linearized reading' (duration: 121.60649ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:46.68934Z","caller":"traceutil/trace.go:171","msg":"trace[101399599] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"154.200434ms","start":"2026-10-26T19:28:46.535109Z","end":"2026-10-26T19:28:46.68931Z","steps":["trace[101399599] 'process raft request' (duration: 79.290689ms)","trace[101399599] 'compare' (duration: 74.777572ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:48.526373Z","caller":"traceutil/trace.go:171","msg":"trace[957263099] linearizableReadLoop","detail":"{readStateIndex:5439; appliedIndex:5438; }","duration":"154.916123ms","start":"2026-10-26T19:28:48.371425Z","end":"2026-10-26T19:28:48.526341Z","steps":["trace[957263099] 'read index received' (duration: 95.011312ms)","trace[957263099] 'applied index is now lower than readState.Index' (duration: 59.903651ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:48.526795Z","caller":"traceutil/trace.go:171","msg":"trace[1674845663] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"164.578587ms","start":"2026-10-26T19:28:48.362174Z","end":"2026-10-26T19:28:48.526753Z","steps":["trace[1674845663] 'process raft request' (duration: 104.305827ms)","trace[1674845663] 'compare' (duration: 59.682915ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:28:48.526829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.387355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T19:28:48.526895Z","caller":"traceutil/trace.go:171","msg":"trace[2068170813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5235; }","duration":"155.494598ms","start":"2026-10-26T19:28:48.371385Z","end":"2026-10-26T19:28:48.52688Z","steps":["trace[2068170813] 'agreement among raft nodes before linearized reading' (duration: 155.272382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:48.526896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.882745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T19:28:48.526946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.560426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T19:28:48.526963Z","caller":"traceutil/trace.go:171","msg":"trace[670486125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5235; }","duration":"111.990588ms","start":"2026-10-26T19:28:48.414955Z","end":"2026-10-26T19:28:48.526946Z","steps":["trace[670486125] 'agreement among raft nodes before linearized reading' (duration: 111.773912ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:48.527011Z","caller":"traceutil/trace.go:171","msg":"trace[1731160300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5235; }","duration":"111.687929ms","start":"2026-10-26T19:28:48.415303Z","end":"2026-10-26T19:28:48.526991Z","steps":["trace[1731160300] 'agreement among raft nodes before linearized reading' (duration: 111.462283ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:06.01886Z","caller":"traceutil/trace.go:171","msg":"trace[671227007] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"106.76071ms","start":"2026-10-26T19:29:05.912073Z","end":"2026-10-26T19:29:06.018834Z","steps":["trace[671227007] 'process raft request' (duration: 106.590445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:29:06.397606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.814964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b8d0fb2-d0fe-43f7-b3c0-9470b4a52d3f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-26T19:29:06.397674Z","caller":"traceutil/trace.go:171","msg":"trace[1246566632] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b8d0fb2-d0fe-43f7-b3c0-9470b4a52d3f; range_end:; response_count:1; response_revision:5561; }","duration":"161.937118ms","start":"2026-10-26T19:29:06.235722Z","end":"2026-10-26T19:29:06.397659Z","steps":["trace[1246566632] 'range keys from in-memory index tree' (duration: 161.635949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:29:20.668285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.847972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-26T19:29:20.668419Z","caller":"traceutil/trace.go:171","msg":"trace[1107901465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5770; }","duration":"130.997316ms","start":"2026-10-26T19:29:20.537396Z","end":"2026-10-26T19:29:20.668394Z","steps":["trace[1107901465] 'range keys from in-memory index tree' (duration: 130.635316ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:20.864801Z","caller":"traceutil/trace.go:171","msg":"trace[173871703] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"101.487622ms","start":"2026-10-26T19:29:20.763292Z","end":"2026-10-26T19:29:20.86478Z","steps":["trace[173871703] 'process raft request' (duration: 51.753608ms)","trace[173871703] 'compare' (duration: 49.325674ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:29:42.097822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-10-26T19:29:42.171149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"72.180584ms","hash":3397085042,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":32350208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:29:42.171236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3397085042,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:34:42.121945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5915} {"level":"info","ts":"2026-10-26T19:34:42.259546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5915,"took":"135.559054ms","hash":4046330135,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":25894912,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:34:42.259621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4046330135,"revision":5915,"compact-revision":2967} {"level":"info","ts":"2026-10-26T19:35:15.125775Z","caller":"traceutil/trace.go:171","msg":"trace[533227003] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"383.342481ms","start":"2026-10-26T19:35:14.742406Z","end":"2026-10-26T19:35:15.125748Z","steps":["trace[533227003] 'process raft request' (duration: 383.180307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:15.125949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:14.742393Z","time spent":"383.476004ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:35:15.127015Z","caller":"traceutil/trace.go:171","msg":"trace[1506142130] linearizableReadLoop","detail":"{readStateIndex:8749; appliedIndex:8749; }","duration":"360.596434ms","start":"2026-10-26T19:35:14.766403Z","end":"2026-10-26T19:35:15.126999Z","steps":["trace[1506142130] 'read index received' (duration: 360.590094ms)","trace[1506142130] 'applied index is now lower than readState.Index' (duration: 5.28µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:35:15.12722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.81639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T19:35:15.127291Z","caller":"traceutil/trace.go:171","msg":"trace[1728815300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8445; }","duration":"360.939874ms","start":"2026-10-26T19:35:14.766339Z","end":"2026-10-26T19:35:15.127279Z","steps":["trace[1728815300] 'agreement among raft nodes before linearized reading' (duration: 360.762549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:15.127328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:14.766314Z","time spent":"361.004435ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-26T19:35:15.384445Z","caller":"traceutil/trace.go:171","msg":"trace[25831032] linearizableReadLoop","detail":"{readStateIndex:8750; appliedIndex:8749; }","duration":"257.345098ms","start":"2026-10-26T19:35:15.127079Z","end":"2026-10-26T19:35:15.384424Z","steps":["trace[25831032] 'read index received' (duration: 257.216195ms)","trace[25831032] 'applied index is now lower than readState.Index' (duration: 128.083µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:35:15.384553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.795376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:35:15.384585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.919088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T19:35:15.384601Z","caller":"traceutil/trace.go:171","msg":"trace[841488701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8446; }","duration":"332.888058ms","start":"2026-10-26T19:35:15.051699Z","end":"2026-10-26T19:35:15.384587Z","steps":["trace[841488701] 'agreement among raft nodes before linearized reading' (duration: 332.800136ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:15.384612Z","caller":"traceutil/trace.go:171","msg":"trace[1533197891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8446; }","duration":"573.98963ms","start":"2026-10-26T19:35:14.810614Z","end":"2026-10-26T19:35:15.384604Z","steps":["trace[1533197891] 'agreement among raft nodes before linearized reading' (duration: 573.891707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:15.384634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:14.810583Z","time spent":"574.045531ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T19:35:15.384633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:15.051679Z","time spent":"332.946619ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:35:15.384655Z","caller":"traceutil/trace.go:171","msg":"trace[724338837] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"393.62004ms","start":"2026-10-26T19:35:14.99095Z","end":"2026-10-26T19:35:15.38457Z","steps":["trace[724338837] 'process raft request' (duration: 393.331992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:15.384716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.243638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:35:15.38476Z","caller":"traceutil/trace.go:171","msg":"trace[1699794591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8446; }","duration":"539.335781ms","start":"2026-10-26T19:35:14.84541Z","end":"2026-10-26T19:35:15.384746Z","steps":["trace[1699794591] 'agreement among raft nodes before linearized reading' (duration: 539.181326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:15.384744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.913173ms","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-10-26T19:35:15.384794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:14.845388Z","time spent":"539.397483ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T19:35:15.384812Z","caller":"traceutil/trace.go:171","msg":"trace[1424956638] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8446; }","duration":"414.023896ms","start":"2026-10-26T19:35:14.97077Z","end":"2026-10-26T19:35:15.384794Z","steps":["trace[1424956638] 'agreement among raft nodes before linearized reading' (duration: 413.81821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:15.384836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:14.99093Z","time spent":"393.820666ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:35:15.38485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:14.970752Z","time spent":"414.089427ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-26T19:35:15.38633Z","caller":"traceutil/trace.go:171","msg":"trace[1059970182] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"225.346818ms","start":"2026-10-26T19:35:15.160968Z","end":"2026-10-26T19:35:15.386315Z","steps":["trace[1059970182] 'process raft request' (duration: 225.233365ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:15.386331Z","caller":"traceutil/trace.go:171","msg":"trace[2115112883] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"255.667893ms","start":"2026-10-26T19:35:15.130633Z","end":"2026-10-26T19:35:15.386301Z","steps":["trace[2115112883] 'process raft request' (duration: 255.468908ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:26.938698Z","caller":"traceutil/trace.go:171","msg":"trace[1096389998] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"361.54714ms","start":"2026-10-26T19:35:26.577115Z","end":"2026-10-26T19:35:26.938662Z","steps":["trace[1096389998] 'process raft request' (duration: 361.141679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:26.938886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:26.5771Z","time spent":"361.695744ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:35:27.86143Z","caller":"traceutil/trace.go:171","msg":"trace[1509929590] transaction","detail":"{read_only:false; response_revision:8512; number_of_response:1; }","duration":"346.770761ms","start":"2026-10-26T19:35:27.514624Z","end":"2026-10-26T19:35:27.861395Z","steps":["trace[1509929590] 'process raft request' (duration: 298.67649ms)","trace[1509929590] 'compare' (duration: 47.968778ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:35:27.861643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:27.514612Z","time spent":"346.900566ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:35:27.886327Z","caller":"traceutil/trace.go:171","msg":"trace[1459688449] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"370.971496ms","start":"2026-10-26T19:35:27.515319Z","end":"2026-10-26T19:35:27.886291Z","steps":["trace[1459688449] 'process raft request' (duration: 370.682159ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:27.886418Z","caller":"traceutil/trace.go:171","msg":"trace[1395471110] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"370.769271ms","start":"2026-10-26T19:35:27.515612Z","end":"2026-10-26T19:35:27.886381Z","steps":["trace[1395471110] 'process raft request' (duration: 370.522314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:27.886481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:27.515296Z","time spent":"371.10722ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:35:27.88648Z","caller":"traceutil/trace.go:171","msg":"trace[574066905] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"309.910255ms","start":"2026-10-26T19:35:27.576524Z","end":"2026-10-26T19:35:27.886434Z","steps":["trace[574066905] 'process raft request' (duration: 309.775581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:27.886588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:27.51558Z","time spent":"370.918695ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:35:27.88664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:27.576496Z","time spent":"310.07645ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:35:27.886705Z","caller":"traceutil/trace.go:171","msg":"trace[1430354764] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"370.443342ms","start":"2026-10-26T19:35:27.516243Z","end":"2026-10-26T19:35:27.886687Z","steps":["trace[1430354764] 'process raft request' (duration: 370.021341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:27.886957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:27.516218Z","time spent":"370.661338ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:35:57.135055Z","caller":"traceutil/trace.go:171","msg":"trace[1434765525] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"454.347673ms","start":"2026-10-26T19:35:56.680683Z","end":"2026-10-26T19:35:57.135031Z","steps":["trace[1434765525] 'process raft request' (duration: 454.164948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:57.135188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:56.68066Z","time spent":"454.466616ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:35:57.627501Z","caller":"traceutil/trace.go:171","msg":"trace[197329178] linearizableReadLoop","detail":"{readStateIndex:9040; appliedIndex:9039; }","duration":"417.883867ms","start":"2026-10-26T19:35:57.209591Z","end":"2026-10-26T19:35:57.627475Z","steps":["trace[197329178] 'read index received' (duration: 417.870777ms)","trace[197329178] 'applied index is now lower than readState.Index' (duration: 10.83µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:35:57.627504Z","caller":"traceutil/trace.go:171","msg":"trace[37983641] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"465.999909ms","start":"2026-10-26T19:35:57.161482Z","end":"2026-10-26T19:35:57.627482Z","steps":["trace[37983641] 'process raft request' (duration: 465.841544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:57.627786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.163295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-26T19:35:57.627814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:57.161461Z","time spent":"466.286447ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:35:57.627844Z","caller":"traceutil/trace.go:171","msg":"trace[499547879] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8724; }","duration":"418.277018ms","start":"2026-10-26T19:35:57.20955Z","end":"2026-10-26T19:35:57.627827Z","steps":["trace[499547879] 'agreement among raft nodes before linearized reading' (duration: 418.055722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:57.627896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:57.20953Z","time spent":"418.352749ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-10-26T19:35:57.705437Z","caller":"traceutil/trace.go:171","msg":"trace[734012642] transaction","detail":"{read_only:false; response_revision:8725; number_of_response:1; }","duration":"109.730357ms","start":"2026-10-26T19:35:57.595683Z","end":"2026-10-26T19:35:57.705414Z","steps":["trace[734012642] 'process raft request' (duration: 89.614029ms)","trace[734012642] 'compare' (duration: 19.954213ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:35:57.705663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.459119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:35:57.705821Z","caller":"traceutil/trace.go:171","msg":"trace[236026578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8727; }","duration":"313.584642ms","start":"2026-10-26T19:35:57.392134Z","end":"2026-10-26T19:35:57.705719Z","steps":["trace[236026578] 'agreement among raft nodes before linearized reading' (duration: 313.340455ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:57.705873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:57.392111Z","time spent":"313.750627ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T19:35:57.705866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.714051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:35:57.70596Z","caller":"traceutil/trace.go:171","msg":"trace[492651701] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8727; }","duration":"100.862575ms","start":"2026-10-26T19:35:57.605077Z","end":"2026-10-26T19:35:57.705939Z","steps":["trace[492651701] 'agreement among raft nodes before linearized reading' (duration: 100.68677ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:59.833526Z","caller":"traceutil/trace.go:171","msg":"trace[455059036] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"196.40696ms","start":"2026-10-26T19:35:59.637094Z","end":"2026-10-26T19:35:59.833501Z","steps":["trace[455059036] 'process raft request' (duration: 196.247446ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:59.861201Z","caller":"traceutil/trace.go:171","msg":"trace[1337341583] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"151.502533ms","start":"2026-10-26T19:35:59.709675Z","end":"2026-10-26T19:35:59.861177Z","steps":["trace[1337341583] 'process raft request' (duration: 151.318648ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:59.861422Z","caller":"traceutil/trace.go:171","msg":"trace[723033029] linearizableReadLoop","detail":"{readStateIndex:9053; appliedIndex:9050; }","duration":"118.723223ms","start":"2026-10-26T19:35:59.742687Z","end":"2026-10-26T19:35:59.86141Z","steps":["trace[723033029] 'read index received' (duration: 90.710169ms)","trace[723033029] 'applied index is now lower than readState.Index' (duration: 28.012174ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:35:59.861524Z","caller":"traceutil/trace.go:171","msg":"trace[1860081496] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"151.635946ms","start":"2026-10-26T19:35:59.70985Z","end":"2026-10-26T19:35:59.861486Z","steps":["trace[1860081496] 'process raft request' (duration: 151.288217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:59.861576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.880718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:35:59.861606Z","caller":"traceutil/trace.go:171","msg":"trace[1055639045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8737; }","duration":"118.95738ms","start":"2026-10-26T19:35:59.742638Z","end":"2026-10-26T19:35:59.861596Z","steps":["trace[1055639045] 'agreement among raft nodes before linearized reading' (duration: 118.810826ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:00.242969Z","caller":"traceutil/trace.go:171","msg":"trace[95880222] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"165.430918ms","start":"2026-10-26T19:36:00.077515Z","end":"2026-10-26T19:36:00.242946Z","steps":["trace[95880222] 'process raft request' (duration: 165.297605ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:00.243357Z","caller":"traceutil/trace.go:171","msg":"trace[228965802] linearizableReadLoop","detail":"{readStateIndex:9055; appliedIndex:9055; }","duration":"164.964025ms","start":"2026-10-26T19:36:00.078359Z","end":"2026-10-26T19:36:00.243323Z","steps":["trace[228965802] 'read index received' (duration: 164.956665ms)","trace[228965802] 'applied index is now lower than readState.Index' (duration: 6.39µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:36:00.243491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.114119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-26T19:36:00.243553Z","caller":"traceutil/trace.go:171","msg":"trace[965198439] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8739; }","duration":"165.209702ms","start":"2026-10-26T19:36:00.078326Z","end":"2026-10-26T19:36:00.243536Z","steps":["trace[965198439] 'agreement among raft nodes before linearized reading' (duration: 165.042117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:00.265281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.84167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-26T19:36:00.265301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.952712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nhjtb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:36:00.265351Z","caller":"traceutil/trace.go:171","msg":"trace[1342658230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8740; }","duration":"186.921942ms","start":"2026-10-26T19:36:00.078413Z","end":"2026-10-26T19:36:00.265335Z","steps":["trace[1342658230] 'agreement among raft nodes before linearized reading' (duration: 186.696566ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:00.265356Z","caller":"traceutil/trace.go:171","msg":"trace[472641876] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nhjtb; range_end:; response_count:1; response_revision:8740; }","duration":"166.049025ms","start":"2026-10-26T19:36:00.099294Z","end":"2026-10-26T19:36:00.265343Z","steps":["trace[472641876] 'agreement among raft nodes before linearized reading' (duration: 165.86981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:00.265372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.592978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:36:00.265448Z","caller":"traceutil/trace.go:171","msg":"trace[624685871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8740; }","duration":"174.711112ms","start":"2026-10-26T19:36:00.090719Z","end":"2026-10-26T19:36:00.26543Z","steps":["trace[624685871] 'agreement among raft nodes before linearized reading' (duration: 174.466875ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:00.265699Z","caller":"traceutil/trace.go:171","msg":"trace[1914382] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"186.12566ms","start":"2026-10-26T19:36:00.079557Z","end":"2026-10-26T19:36:00.265683Z","steps":["trace[1914382] 'process raft request' (duration: 185.411742ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:00.377354Z","caller":"traceutil/trace.go:171","msg":"trace[825618463] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"129.517356ms","start":"2026-10-26T19:36:00.247819Z","end":"2026-10-26T19:36:00.377336Z","steps":["trace[825618463] 'process raft request' (duration: 129.385552ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:00.400281Z","caller":"traceutil/trace.go:171","msg":"trace[773751510] linearizableReadLoop","detail":"{readStateIndex:9059; appliedIndex:9056; }","duration":"130.817149ms","start":"2026-10-26T19:36:00.269448Z","end":"2026-10-26T19:36:00.400265Z","steps":["trace[773751510] 'read index received' (duration: 107.833807ms)","trace[773751510] 'applied index is now lower than readState.Index' (duration: 22.982742ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:36:00.400563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.947783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:36:00.400593Z","caller":"traceutil/trace.go:171","msg":"trace[1319085135] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8743; }","duration":"123.025046ms","start":"2026-10-26T19:36:00.277561Z","end":"2026-10-26T19:36:00.400586Z","steps":["trace[1319085135] 'agreement among raft nodes before linearized reading' (duration: 122.955564ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:00.400581Z","caller":"traceutil/trace.go:171","msg":"trace[491318157] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"131.510788ms","start":"2026-10-26T19:36:00.269042Z","end":"2026-10-26T19:36:00.400553Z","steps":["trace[491318157] 'process raft request' (duration: 131.065866ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:00.400639Z","caller":"traceutil/trace.go:171","msg":"trace[405656870] transaction","detail":"{read_only:false; response_revision:8743; number_of_response:1; }","duration":"131.510978ms","start":"2026-10-26T19:36:00.269113Z","end":"2026-10-26T19:36:00.400624Z","steps":["trace[405656870] 'process raft request' (duration: 131.091867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:00.400648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.508642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:36:00.400711Z","caller":"traceutil/trace.go:171","msg":"trace[613549113] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8743; }","duration":"106.634316ms","start":"2026-10-26T19:36:00.294064Z","end":"2026-10-26T19:36:00.400699Z","steps":["trace[613549113] 'agreement among raft nodes before linearized reading' (duration: 106.463311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:00.401111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.658531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229947"} {"level":"info","ts":"2026-10-26T19:36:00.401142Z","caller":"traceutil/trace.go:171","msg":"trace[1773768484] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8743; }","duration":"131.730484ms","start":"2026-10-26T19:36:00.269404Z","end":"2026-10-26T19:36:00.401135Z","steps":["trace[1773768484] 'agreement among raft nodes before linearized reading' (duration: 130.950484ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:01.032129Z","caller":"traceutil/trace.go:171","msg":"trace[1420323646] linearizableReadLoop","detail":"{readStateIndex:9060; appliedIndex:9059; }","duration":"144.845338ms","start":"2026-10-26T19:36:00.887257Z","end":"2026-10-26T19:36:01.032102Z","steps":["trace[1420323646] 'read index received' (duration: 90.332509ms)","trace[1420323646] 'applied index is now lower than readState.Index' (duration: 54.511219ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:36:01.03237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.123426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6c46f4b47c-l2vmb.18e22b62332e33e5\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-26T19:36:01.032425Z","caller":"traceutil/trace.go:171","msg":"trace[1202819746] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6c46f4b47c-l2vmb.18e22b62332e33e5; range_end:; response_count:1; response_revision:8743; }","duration":"145.213908ms","start":"2026-10-26T19:36:00.887185Z","end":"2026-10-26T19:36:01.032399Z","steps":["trace[1202819746] 'agreement among raft nodes before linearized reading' (duration: 145.016483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:01.61874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.579585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T19:36:01.618836Z","caller":"traceutil/trace.go:171","msg":"trace[1630191396] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8744; }","duration":"196.719079ms","start":"2026-10-26T19:36:01.422091Z","end":"2026-10-26T19:36:01.61881Z","steps":["trace[1630191396] 'count revisions from in-memory index tree' (duration: 196.505243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.394074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636553963815159461,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T19:36:02.686287Z","caller":"traceutil/trace.go:171","msg":"trace[1505811922] transaction","detail":"{read_only:false; response_revision:8745; number_of_response:1; }","duration":"845.221041ms","start":"2026-10-26T19:36:01.841038Z","end":"2026-10-26T19:36:02.686259Z","steps":["trace[1505811922] 'process raft request' (duration: 845.009846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.686455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:36:01.841016Z","time spent":"845.354095ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:36:02.695222Z","caller":"traceutil/trace.go:171","msg":"trace[940740722] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"828.702498ms","start":"2026-10-26T19:36:01.866502Z","end":"2026-10-26T19:36:02.695204Z","steps":["trace[940740722] 'process raft request' (duration: 828.541204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.695387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:36:01.866474Z","time spent":"828.824002ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:36:02.695528Z","caller":"traceutil/trace.go:171","msg":"trace[452314161] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"528.758654ms","start":"2026-10-26T19:36:02.166754Z","end":"2026-10-26T19:36:02.695513Z","steps":["trace[452314161] 'process raft request' (duration: 528.655443ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:02.695532Z","caller":"traceutil/trace.go:171","msg":"trace[1600504433] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"828.284787ms","start":"2026-10-26T19:36:01.867222Z","end":"2026-10-26T19:36:02.695507Z","steps":["trace[1600504433] 'process raft request' (duration: 827.918088ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:02.695582Z","caller":"traceutil/trace.go:171","msg":"trace[672514619] linearizableReadLoop","detail":"{readStateIndex:9066; appliedIndex:9061; }","duration":"801.988318ms","start":"2026-10-26T19:36:01.893575Z","end":"2026-10-26T19:36:02.695564Z","steps":["trace[672514619] 'read index received' (duration: 792.444008ms)","trace[672514619] 'applied index is now lower than readState.Index' (duration: 9.54369ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:36:02.695649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:36:02.166722Z","time spent":"528.864497ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:36:02.695675Z","caller":"traceutil/trace.go:171","msg":"trace[1294874254] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"425.454195ms","start":"2026-10-26T19:36:02.270214Z","end":"2026-10-26T19:36:02.695668Z","steps":["trace[1294874254] 'process raft request' (duration: 425.269972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.695661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:36:01.867211Z","time spent":"828.386931ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:36:02.695752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:36:02.270187Z","time spent":"425.515787ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:36:02.695884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.369566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T19:36:02.695919Z","caller":"traceutil/trace.go:171","msg":"trace[146320325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8749; }","duration":"448.437417ms","start":"2026-10-26T19:36:02.247474Z","end":"2026-10-26T19:36:02.695911Z","steps":["trace[146320325] 'agreement among raft nodes before linearized reading' (duration: 448.334794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.695931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.270988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nhjtb\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-26T19:36:02.695944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:36:02.247458Z","time spent":"448.477949ms","remote":"127.0.0.1:42730","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-10-26T19:36:02.695947Z","caller":"traceutil/trace.go:171","msg":"trace[50744197] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nhjtb; range_end:; response_count:1; response_revision:8749; }","duration":"288.31565ms","start":"2026-10-26T19:36:02.407626Z","end":"2026-10-26T19:36:02.695942Z","steps":["trace[50744197] 'agreement among raft nodes before linearized reading' (duration: 288.274119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.69593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.345087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:36:02.695995Z","caller":"traceutil/trace.go:171","msg":"trace[282208614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8749; }","duration":"802.44533ms","start":"2026-10-26T19:36:01.893531Z","end":"2026-10-26T19:36:02.695976Z","steps":["trace[282208614] 'agreement among raft nodes before linearized reading' (duration: 802.270375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.696201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:36:01.893512Z","time spent":"802.672846ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T19:36:02.696039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.562091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T19:36:02.69639Z","caller":"traceutil/trace.go:171","msg":"trace[1175887804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8749; }","duration":"292.93463ms","start":"2026-10-26T19:36:02.403439Z","end":"2026-10-26T19:36:02.696374Z","steps":["trace[1175887804] 'agreement among raft nodes before linearized reading' (duration: 292.55525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.69609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.873966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-26T19:36:02.696491Z","caller":"traceutil/trace.go:171","msg":"trace[1545126089] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8749; }","duration":"315.334258ms","start":"2026-10-26T19:36:02.381146Z","end":"2026-10-26T19:36:02.69648Z","steps":["trace[1545126089] 'agreement among raft nodes before linearized reading' (duration: 314.904367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:36:02.696513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:36:02.381114Z","time spent":"315.39236ms","remote":"127.0.0.1:42730","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-10-26T19:36:02.69589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.039207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:36:02.696555Z","caller":"traceutil/trace.go:171","msg":"trace[1645509037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8749; }","duration":"292.717065ms","start":"2026-10-26T19:36:02.403834Z","end":"2026-10-26T19:36:02.696551Z","steps":["trace[1645509037] 'agreement among raft nodes before linearized reading' (duration: 291.994186ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:10.313484Z","caller":"traceutil/trace.go:171","msg":"trace[1706341970] transaction","detail":"{read_only:false; response_revision:8787; number_of_response:1; }","duration":"113.246229ms","start":"2026-10-26T19:36:10.200207Z","end":"2026-10-26T19:36:10.313453Z","steps":["trace[1706341970] 'process raft request' (duration: 112.974812ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:10.831161Z","caller":"traceutil/trace.go:171","msg":"trace[1152568420] transaction","detail":"{read_only:false; response_revision:8790; number_of_response:1; }","duration":"107.56851ms","start":"2026-10-26T19:36:10.723558Z","end":"2026-10-26T19:36:10.831127Z","steps":["trace[1152568420] 'process raft request' (duration: 107.128629ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:10.831447Z","caller":"traceutil/trace.go:171","msg":"trace[563947379] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"106.549114ms","start":"2026-10-26T19:36:10.724878Z","end":"2026-10-26T19:36:10.831427Z","steps":["trace[563947379] 'process raft request' (duration: 105.952049ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:10.831445Z","caller":"traceutil/trace.go:171","msg":"trace[921513124] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"111.599025ms","start":"2026-10-26T19:36:10.719804Z","end":"2026-10-26T19:36:10.831403Z","steps":["trace[921513124] 'process raft request' (duration: 95.689028ms)","trace[921513124] 'compare' (duration: 15.015204ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:36:18.318052Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002404.wal"} {"level":"info","ts":"2026-10-26T19:36:30.965201Z","caller":"traceutil/trace.go:171","msg":"trace[221944522] transaction","detail":"{read_only:false; response_revision:8994; number_of_response:1; }","duration":"140.973117ms","start":"2026-10-26T19:36:30.824186Z","end":"2026-10-26T19:36:30.965159Z","steps":["trace[221944522] 'process raft request' (duration: 140.795302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:37:40.720746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.182998ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-26T19:37:40.920104Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:37:41.009442Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T19:37:41.00959Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T19:39:42.131034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8096} {"level":"info","ts":"2026-10-26T19:39:42.217419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8096,"took":"84.682647ms","hash":2712928709,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":28880896,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T19:39:42.217494Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2712928709,"revision":8096,"compact-revision":5915} {"level":"info","ts":"2026-10-26T19:41:00.89488Z","caller":"traceutil/trace.go:171","msg":"trace[1108778434] transaction","detail":"{read_only:false; response_revision:11371; number_of_response:1; }","duration":"246.356921ms","start":"2026-10-26T19:41:00.648501Z","end":"2026-10-26T19:41:00.894858Z","steps":["trace[1108778434] 'process raft request' (duration: 246.216707ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:00.908731Z","caller":"traceutil/trace.go:171","msg":"trace[931038535] transaction","detail":"{read_only:false; response_revision:11372; number_of_response:1; }","duration":"260.156183ms","start":"2026-10-26T19:41:00.648551Z","end":"2026-10-26T19:41:00.908707Z","steps":["trace[931038535] 'process raft request' (duration: 259.979378ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:00.908997Z","caller":"traceutil/trace.go:171","msg":"trace[1765684423] transaction","detail":"{read_only:false; response_revision:11373; number_of_response:1; }","duration":"260.393449ms","start":"2026-10-26T19:41:00.648588Z","end":"2026-10-26T19:41:00.908982Z","steps":["trace[1765684423] 'process raft request' (duration: 260.080711ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:00.909235Z","caller":"traceutil/trace.go:171","msg":"trace[2088599249] transaction","detail":"{read_only:false; response_revision:11374; number_of_response:1; }","duration":"260.583123ms","start":"2026-10-26T19:41:00.64864Z","end":"2026-10-26T19:41:00.909223Z","steps":["trace[2088599249] 'process raft request' (duration: 260.381998ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:00.911058Z","caller":"traceutil/trace.go:171","msg":"trace[806535410] linearizableReadLoop","detail":"{readStateIndex:11768; appliedIndex:11764; }","duration":"261.726003ms","start":"2026-10-26T19:41:00.649314Z","end":"2026-10-26T19:41:00.91104Z","steps":["trace[806535410] 'read index received' (duration: 245.460297ms)","trace[806535410] 'applied index is now lower than readState.Index' (duration: 16.264676ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:41:00.911242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.908757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19253"} {"level":"info","ts":"2026-10-26T19:41:00.911284Z","caller":"traceutil/trace.go:171","msg":"trace[545312312] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11374; }","duration":"261.98584ms","start":"2026-10-26T19:41:00.649291Z","end":"2026-10-26T19:41:00.911277Z","steps":["trace[545312312] 'agreement among raft nodes before linearized reading' (duration: 261.847926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:00.911416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.974607ms","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-10-26T19:41:00.911498Z","caller":"traceutil/trace.go:171","msg":"trace[1475013949] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11374; }","duration":"166.10639ms","start":"2026-10-26T19:41:00.745372Z","end":"2026-10-26T19:41:00.911478Z","steps":["trace[1475013949] 'agreement among raft nodes before linearized reading' (duration: 165.809252ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:21.163063Z","caller":"traceutil/trace.go:171","msg":"trace[38916983] transaction","detail":"{read_only:false; response_revision:11484; number_of_response:1; }","duration":"119.810377ms","start":"2026-10-26T19:41:21.043214Z","end":"2026-10-26T19:41:21.163024Z","steps":["trace[38916983] 'process raft request' (duration: 105.748058ms)","trace[38916983] 'compare' (duration: 13.71894ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:41:21.184042Z","caller":"traceutil/trace.go:171","msg":"trace[888387468] transaction","detail":"{read_only:false; response_revision:11485; number_of_response:1; }","duration":"140.539551ms","start":"2026-10-26T19:41:21.043475Z","end":"2026-10-26T19:41:21.184015Z","steps":["trace[888387468] 'process raft request' (duration: 140.363916ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:21.184231Z","caller":"traceutil/trace.go:171","msg":"trace[1913432301] transaction","detail":"{read_only:false; response_revision:11486; number_of_response:1; }","duration":"140.087858ms","start":"2026-10-26T19:41:21.044131Z","end":"2026-10-26T19:41:21.184219Z","steps":["trace[1913432301] 'process raft request' (duration: 139.841292ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:21.184395Z","caller":"traceutil/trace.go:171","msg":"trace[932692854] transaction","detail":"{read_only:false; response_revision:11487; number_of_response:1; }","duration":"139.845002ms","start":"2026-10-26T19:41:21.044524Z","end":"2026-10-26T19:41:21.184369Z","steps":["trace[932692854] 'process raft request' (duration: 139.662048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:21.479961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.564751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-26T19:41:21.480101Z","caller":"traceutil/trace.go:171","msg":"trace[1026660107] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11487; }","duration":"119.741526ms","start":"2026-10-26T19:41:21.360321Z","end":"2026-10-26T19:41:21.480063Z","steps":["trace[1026660107] 'range keys from in-memory index tree' (duration: 119.377946ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:44:42.138134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10534} {"level":"info","ts":"2026-10-26T19:44:42.232873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10534,"took":"92.333459ms","hash":1933481511,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":27459584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T19:44:42.232939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1933481511,"revision":10534,"compact-revision":8096} {"level":"info","ts":"2026-10-26T19:47:10.954692Z","caller":"traceutil/trace.go:171","msg":"trace[1795927433] transaction","detail":"{read_only:false; response_revision:13866; number_of_response:1; }","duration":"197.095763ms","start":"2026-10-26T19:47:10.757571Z","end":"2026-10-26T19:47:10.954667Z","steps":["trace[1795927433] 'process raft request' (duration: 196.885408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:35.826743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.05021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-10-26T19:47:35.826846Z","caller":"traceutil/trace.go:171","msg":"trace[2096834748] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:14001; }","duration":"309.165383ms","start":"2026-10-26T19:47:35.517661Z","end":"2026-10-26T19:47:35.826827Z","steps":["trace[2096834748] 'range keys from in-memory index tree' (duration: 308.839504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:35.826885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:47:35.517592Z","time spent":"309.283316ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"info","ts":"2026-10-26T19:47:35.870507Z","caller":"traceutil/trace.go:171","msg":"trace[503806560] transaction","detail":"{read_only:false; response_revision:14002; number_of_response:1; }","duration":"245.182529ms","start":"2026-10-26T19:47:35.625293Z","end":"2026-10-26T19:47:35.870475Z","steps":["trace[503806560] 'process raft request' (duration: 244.994534ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:35.872794Z","caller":"traceutil/trace.go:171","msg":"trace[180759155] transaction","detail":"{read_only:false; response_revision:14003; number_of_response:1; }","duration":"142.727696ms","start":"2026-10-26T19:47:35.730039Z","end":"2026-10-26T19:47:35.872767Z","steps":["trace[180759155] 'process raft request' (duration: 142.50753ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:35.872857Z","caller":"traceutil/trace.go:171","msg":"trace[1243428277] transaction","detail":"{read_only:false; response_revision:14004; number_of_response:1; }","duration":"133.096395ms","start":"2026-10-26T19:47:35.739726Z","end":"2026-10-26T19:47:35.872822Z","steps":["trace[1243428277] 'process raft request' (duration: 132.955991ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:52.104554Z","caller":"traceutil/trace.go:171","msg":"trace[42151618] transaction","detail":"{read_only:false; response_revision:14102; number_of_response:1; }","duration":"177.20364ms","start":"2026-10-26T19:47:51.927322Z","end":"2026-10-26T19:47:52.104526Z","steps":["trace[42151618] 'process raft request' (duration: 177.031126ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:52.149913Z","caller":"traceutil/trace.go:171","msg":"trace[1369331822] transaction","detail":"{read_only:false; response_revision:14104; number_of_response:1; }","duration":"214.720941ms","start":"2026-10-26T19:47:51.935164Z","end":"2026-10-26T19:47:52.149885Z","steps":["trace[1369331822] 'process raft request' (duration: 214.570927ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:52.149891Z","caller":"traceutil/trace.go:171","msg":"trace[202021159] transaction","detail":"{read_only:false; response_revision:14103; number_of_response:1; }","duration":"218.138741ms","start":"2026-10-26T19:47:51.931708Z","end":"2026-10-26T19:47:52.149846Z","steps":["trace[202021159] 'process raft request' (duration: 191.416031ms)","trace[202021159] 'compare' (duration: 26.487813ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:47:52.709673Z","caller":"traceutil/trace.go:171","msg":"trace[494567162] transaction","detail":"{read_only:false; response_revision:14106; number_of_response:1; }","duration":"206.915307ms","start":"2026-10-26T19:47:52.502719Z","end":"2026-10-26T19:47:52.709634Z","steps":["trace[494567162] 'process raft request' (duration: 206.720552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:53.113034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.830324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:47:53.113156Z","caller":"traceutil/trace.go:171","msg":"trace[1262378699] transaction","detail":"{read_only:false; response_revision:14107; number_of_response:1; }","duration":"398.212516ms","start":"2026-10-26T19:47:52.714928Z","end":"2026-10-26T19:47:53.113141Z","steps":["trace[1262378699] 'process raft request' (duration: 167.203188ms)","trace[1262378699] 'compare' (duration: 230.359651ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:47:53.113207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:47:52.714876Z","time spent":"398.306138ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:47:54.845174Z","caller":"traceutil/trace.go:171","msg":"trace[1441431880] transaction","detail":"{read_only:false; response_revision:14123; number_of_response:1; }","duration":"120.458724ms","start":"2026-10-26T19:47:54.724685Z","end":"2026-10-26T19:47:54.845144Z","steps":["trace[1441431880] 'process raft request' (duration: 120.31094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:04.465083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.063652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:48:04.465227Z","caller":"traceutil/trace.go:171","msg":"trace[1124229902] transaction","detail":"{read_only:false; response_revision:14286; number_of_response:1; }","duration":"318.422126ms","start":"2026-10-26T19:48:04.146782Z","end":"2026-10-26T19:48:04.465204Z","steps":["trace[1124229902] 'process raft request' (duration: 208.118808ms)","trace[1124229902] 'compare' (duration: 109.916117ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:48:04.465293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:04.146755Z","time spent":"318.507728ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:48:04.730008Z","caller":"traceutil/trace.go:171","msg":"trace[953067607] transaction","detail":"{read_only:false; response_revision:14287; number_of_response:1; }","duration":"536.935816ms","start":"2026-10-26T19:48:04.193026Z","end":"2026-10-26T19:48:04.729962Z","steps":["trace[953067607] 'process raft request' (duration: 536.70696ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:04.730107Z","caller":"traceutil/trace.go:171","msg":"trace[2085855684] linearizableReadLoop","detail":"{readStateIndex:14782; appliedIndex:14780; }","duration":"259.985207ms","start":"2026-10-26T19:48:04.4701Z","end":"2026-10-26T19:48:04.730085Z","steps":["trace[2085855684] 'read index received' (duration: 259.574726ms)","trace[2085855684] 'applied index is now lower than readState.Index' (duration: 409.091µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:48:04.730166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:04.192998Z","time spent":"537.087651ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:48:04.73028Z","caller":"traceutil/trace.go:171","msg":"trace[1481509469] transaction","detail":"{read_only:false; response_revision:14288; number_of_response:1; }","duration":"536.362031ms","start":"2026-10-26T19:48:04.193894Z","end":"2026-10-26T19:48:04.730256Z","steps":["trace[1481509469] 'process raft request' (duration: 536.004182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:04.730447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:04.193858Z","time spent":"536.502825ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:48:04.730602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.49742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-2ldj8.18e22c17ee657462\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-10-26T19:48:04.730627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.242694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:48:04.730658Z","caller":"traceutil/trace.go:171","msg":"trace[1076737798] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-2ldj8.18e22c17ee657462; range_end:; response_count:1; response_revision:14288; }","duration":"260.585602ms","start":"2026-10-26T19:48:04.470062Z","end":"2026-10-26T19:48:04.730647Z","steps":["trace[1076737798] 'agreement among raft nodes before linearized reading' (duration: 260.379537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:04.730666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.997186ms","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:7"} {"level":"info","ts":"2026-10-26T19:48:04.730681Z","caller":"traceutil/trace.go:171","msg":"trace[1361269086] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:14288; }","duration":"147.399118ms","start":"2026-10-26T19:48:04.583267Z","end":"2026-10-26T19:48:04.730666Z","steps":["trace[1361269086] 'agreement among raft nodes before linearized reading' (duration: 147.306006ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:04.730693Z","caller":"traceutil/trace.go:171","msg":"trace[2141655601] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:14288; }","duration":"192.050967ms","start":"2026-10-26T19:48:04.538632Z","end":"2026-10-26T19:48:04.730683Z","steps":["trace[2141655601] 'agreement among raft nodes before linearized reading' (duration: 192.011506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:04.730626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.26716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nhjtb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:48:04.730858Z","caller":"traceutil/trace.go:171","msg":"trace[882446361] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nhjtb; range_end:; response_count:1; response_revision:14288; }","duration":"193.552118ms","start":"2026-10-26T19:48:04.537289Z","end":"2026-10-26T19:48:04.730841Z","steps":["trace[882446361] 'agreement among raft nodes before linearized reading' (duration: 193.27662ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:36.19664Z","caller":"traceutil/trace.go:171","msg":"trace[1506672252] transaction","detail":"{read_only:false; response_revision:15333; number_of_response:1; }","duration":"201.896956ms","start":"2026-10-26T19:49:35.994724Z","end":"2026-10-26T19:49:36.196621Z","steps":["trace[1506672252] 'process raft request' (duration: 201.699501ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:36.196694Z","caller":"traceutil/trace.go:171","msg":"trace[1289245986] transaction","detail":"{read_only:false; response_revision:15334; number_of_response:1; }","duration":"201.840415ms","start":"2026-10-26T19:49:35.994818Z","end":"2026-10-26T19:49:36.196658Z","steps":["trace[1289245986] 'process raft request' (duration: 201.746672ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:36.196635Z","caller":"traceutil/trace.go:171","msg":"trace[979828792] linearizableReadLoop","detail":"{readStateIndex:15859; appliedIndex:15856; }","duration":"201.30856ms","start":"2026-10-26T19:49:35.995296Z","end":"2026-10-26T19:49:36.196604Z","steps":["trace[979828792] 'read index received' (duration: 35.313065ms)","trace[979828792] 'applied index is now lower than readState.Index' (duration: 165.994545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:49:36.196806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.487845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:49:36.196874Z","caller":"traceutil/trace.go:171","msg":"trace[1035954291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15334; }","duration":"201.577327ms","start":"2026-10-26T19:49:35.99528Z","end":"2026-10-26T19:49:36.196857Z","steps":["trace[1035954291] 'agreement among raft nodes before linearized reading' (duration: 201.376792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:49:36.19699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.679511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T19:49:36.197022Z","caller":"traceutil/trace.go:171","msg":"trace[1419427041] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15334; }","duration":"101.735843ms","start":"2026-10-26T19:49:36.095278Z","end":"2026-10-26T19:49:36.197014Z","steps":["trace[1419427041] 'agreement among raft nodes before linearized reading' (duration: 101.657371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:49:36.197004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.213045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:49:36.197079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.211975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:49:36.197099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.225306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:49:36.197141Z","caller":"traceutil/trace.go:171","msg":"trace[1883508408] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15334; }","duration":"102.320578ms","start":"2026-10-26T19:49:36.094803Z","end":"2026-10-26T19:49:36.197123Z","steps":["trace[1883508408] 'agreement among raft nodes before linearized reading' (duration: 102.186745ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:36.197104Z","caller":"traceutil/trace.go:171","msg":"trace[1334621509] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15334; }","duration":"102.356219ms","start":"2026-10-26T19:49:36.094726Z","end":"2026-10-26T19:49:36.197082Z","steps":["trace[1334621509] 'agreement among raft nodes before linearized reading' (duration: 102.193095ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:36.197186Z","caller":"traceutil/trace.go:171","msg":"trace[1692456095] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15334; }","duration":"102.352299ms","start":"2026-10-26T19:49:36.094806Z","end":"2026-10-26T19:49:36.197158Z","steps":["trace[1692456095] 'agreement among raft nodes before linearized reading' (duration: 102.187555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:49:36.46921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.452033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fbbgl\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-10-26T19:49:36.469341Z","caller":"traceutil/trace.go:171","msg":"trace[316852775] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fbbgl; range_end:; response_count:1; response_revision:15336; }","duration":"133.612518ms","start":"2026-10-26T19:49:36.335687Z","end":"2026-10-26T19:49:36.469299Z","steps":["trace[316852775] 'range keys from in-memory index tree' (duration: 133.244338ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:37.906283Z","caller":"traceutil/trace.go:171","msg":"trace[2127595385] transaction","detail":"{read_only:false; response_revision:15340; number_of_response:1; }","duration":"101.689873ms","start":"2026-10-26T19:49:37.804564Z","end":"2026-10-26T19:49:37.906254Z","steps":["trace[2127595385] 'process raft request' (duration: 101.549949ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:42.145238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12779} {"level":"info","ts":"2026-10-26T19:49:42.229435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12779,"took":"81.693328ms","hash":1786850534,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":30683136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T19:49:42.229501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1786850534,"revision":12779,"compact-revision":10534} {"level":"info","ts":"2026-10-26T19:54:42.152686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15362} {"level":"info","ts":"2026-10-26T19:54:42.272962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15362,"took":"116.709114ms","hash":4248454625,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:54:42.273113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4248454625,"revision":15362,"compact-revision":12779} {"level":"info","ts":"2026-10-26T19:55:28.215918Z","caller":"traceutil/trace.go:171","msg":"trace[819581006] linearizableReadLoop","detail":"{readStateIndex:18428; appliedIndex:18427; }","duration":"166.97582ms","start":"2026-10-26T19:55:28.048916Z","end":"2026-10-26T19:55:28.215892Z","steps":["trace[819581006] 'read index received' (duration: 166.711303ms)","trace[819581006] 'applied index is now lower than readState.Index' (duration: 263.627µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:55:28.216012Z","caller":"traceutil/trace.go:171","msg":"trace[804912795] transaction","detail":"{read_only:false; response_revision:17820; number_of_response:1; }","duration":"184.096359ms","start":"2026-10-26T19:55:28.031885Z","end":"2026-10-26T19:55:28.215981Z","steps":["trace[804912795] 'process raft request' (duration: 183.823581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:55:28.216204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.491627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-rxzsk\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-26T19:55:28.21624Z","caller":"traceutil/trace.go:171","msg":"trace[1907182231] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-rxzsk; range_end:; response_count:1; response_revision:17820; }","duration":"132.597649ms","start":"2026-10-26T19:55:28.083633Z","end":"2026-10-26T19:55:28.216231Z","steps":["trace[1907182231] 'agreement among raft nodes before linearized reading' (duration: 132.393044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:55:28.216146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.205606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:55:28.216811Z","caller":"traceutil/trace.go:171","msg":"trace[1544214568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17820; }","duration":"167.915835ms","start":"2026-10-26T19:55:28.048881Z","end":"2026-10-26T19:55:28.216796Z","steps":["trace[1544214568] 'agreement among raft nodes before linearized reading' (duration: 167.153265ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:55:36.506194Z","caller":"traceutil/trace.go:171","msg":"trace[237168933] transaction","detail":"{read_only:false; response_revision:17880; number_of_response:1; }","duration":"108.142439ms","start":"2026-10-26T19:55:36.398017Z","end":"2026-10-26T19:55:36.506159Z","steps":["trace[237168933] 'process raft request' (duration: 99.835402ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:58:19.418539Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:58:19.428117Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T19:58:19.428246Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T19:59:42.16404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17533} {"level":"info","ts":"2026-10-26T19:59:42.26587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17533,"took":"98.96461ms","hash":1502907118,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":33763328,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T19:59:42.265948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1502907118,"revision":17533,"compact-revision":15362} {"level":"info","ts":"2026-10-26T20:02:53.380303Z","caller":"traceutil/trace.go:171","msg":"trace[386324027] linearizableReadLoop","detail":"{readStateIndex:21893; appliedIndex:21892; }","duration":"213.312641ms","start":"2026-10-26T20:02:53.166966Z","end":"2026-10-26T20:02:53.380279Z","steps":["trace[386324027] 'read index received' (duration: 213.119246ms)","trace[386324027] 'applied index is now lower than readState.Index' (duration: 192.535µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:02:53.380354Z","caller":"traceutil/trace.go:171","msg":"trace[314434871] transaction","detail":"{read_only:false; response_revision:21173; number_of_response:1; }","duration":"216.234207ms","start":"2026-10-26T20:02:53.164088Z","end":"2026-10-26T20:02:53.380322Z","steps":["trace[314434871] 'process raft request' (duration: 216.048532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:53.380578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.168314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:02:53.380617Z","caller":"traceutil/trace.go:171","msg":"trace[2028301478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21173; }","duration":"159.280387ms","start":"2026-10-26T20:02:53.221325Z","end":"2026-10-26T20:02:53.380606Z","steps":["trace[2028301478] 'agreement among raft nodes before linearized reading' (duration: 159.122873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:53.380615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.428259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T20:02:53.380598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.604949ms","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-10-26T20:02:53.380653Z","caller":"traceutil/trace.go:171","msg":"trace[1214069216] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:21173; }","duration":"157.510252ms","start":"2026-10-26T20:02:53.223131Z","end":"2026-10-26T20:02:53.380642Z","steps":["trace[1214069216] 'agreement among raft nodes before linearized reading' (duration: 157.398009ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:53.380677Z","caller":"traceutil/trace.go:171","msg":"trace[1420359854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21173; }","duration":"213.719492ms","start":"2026-10-26T20:02:53.166939Z","end":"2026-10-26T20:02:53.380659Z","steps":["trace[1420359854] 'agreement among raft nodes before linearized reading' (duration: 213.457145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:54.025046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.717824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:02:54.026163Z","caller":"traceutil/trace.go:171","msg":"trace[2073162494] transaction","detail":"{read_only:false; response_revision:21174; number_of_response:1; }","duration":"641.556115ms","start":"2026-10-26T20:02:53.384559Z","end":"2026-10-26T20:02:54.026115Z","steps":["trace[2073162494] 'process raft request' (duration: 484.63887ms)","trace[2073162494] 'compare' (duration: 155.387256ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:02:54.026193Z","caller":"traceutil/trace.go:171","msg":"trace[1843141990] transaction","detail":"{read_only:false; response_revision:21175; number_of_response:1; }","duration":"641.363661ms","start":"2026-10-26T20:02:53.384807Z","end":"2026-10-26T20:02:54.026171Z","steps":["trace[1843141990] 'process raft request' (duration: 641.257288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:54.026319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:53.384524Z","time spent":"641.71859ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:02:54.026324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:53.384798Z","time spent":"641.468433ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:02:54.062657Z","caller":"traceutil/trace.go:171","msg":"trace[1078971986] linearizableReadLoop","detail":"{readStateIndex:21896; appliedIndex:21893; }","duration":"201.531843ms","start":"2026-10-26T20:02:53.8611Z","end":"2026-10-26T20:02:54.062631Z","steps":["trace[1078971986] 'read index received' (duration: 8.111282ms)","trace[1078971986] 'applied index is now lower than readState.Index' (duration: 193.419341ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:02:54.06276Z","caller":"traceutil/trace.go:171","msg":"trace[1643803980] transaction","detail":"{read_only:false; response_revision:21176; number_of_response:1; }","duration":"677.395043ms","start":"2026-10-26T20:02:53.385318Z","end":"2026-10-26T20:02:54.062713Z","steps":["trace[1643803980] 'process raft request' (duration: 677.184188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:54.062846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.732818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T20:02:54.062927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:53.385309Z","time spent":"677.528686ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:02:54.064594Z","caller":"traceutil/trace.go:171","msg":"trace[195192604] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:21176; }","duration":"203.530155ms","start":"2026-10-26T20:02:53.861048Z","end":"2026-10-26T20:02:54.064578Z","steps":["trace[195192604] 'agreement among raft nodes before linearized reading' (duration: 201.700007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:54.680025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.684512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T20:02:54.680086Z","caller":"traceutil/trace.go:171","msg":"trace[759455188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21176; }","duration":"317.797285ms","start":"2026-10-26T20:02:54.362273Z","end":"2026-10-26T20:02:54.68007Z","steps":["trace[759455188] 'range keys from in-memory index tree' (duration: 317.531718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:54.680077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.648558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"warn","ts":"2026-10-26T20:02:54.680116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.362257Z","time spent":"317.850617ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-26T20:02:54.680133Z","caller":"traceutil/trace.go:171","msg":"trace[1099628022] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21176; }","duration":"399.76863ms","start":"2026-10-26T20:02:54.280351Z","end":"2026-10-26T20:02:54.680119Z","steps":["trace[1099628022] 'range keys from in-memory index tree' (duration: 399.501993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:54.680161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.280335Z","time spent":"399.816551ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12456,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-26T20:02:54.689441Z","caller":"traceutil/trace.go:171","msg":"trace[1828439029] linearizableReadLoop","detail":"{readStateIndex:21897; appliedIndex:21896; }","duration":"239.29333ms","start":"2026-10-26T20:02:54.45013Z","end":"2026-10-26T20:02:54.689423Z","steps":["trace[1828439029] 'read index received' (duration: 239.188187ms)","trace[1828439029] 'applied index is now lower than readState.Index' (duration: 104.273µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:02:54.689522Z","caller":"traceutil/trace.go:171","msg":"trace[4673544] transaction","detail":"{read_only:false; response_revision:21177; number_of_response:1; }","duration":"285.751176ms","start":"2026-10-26T20:02:54.403765Z","end":"2026-10-26T20:02:54.689516Z","steps":["trace[4673544] 'process raft request' (duration: 285.525741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:54.689713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.557547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-26T20:02:54.689793Z","caller":"traceutil/trace.go:171","msg":"trace[844676113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21177; }","duration":"239.713381ms","start":"2026-10-26T20:02:54.450062Z","end":"2026-10-26T20:02:54.689775Z","steps":["trace[844676113] 'agreement among raft nodes before linearized reading' (duration: 239.462865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:55.608014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.830902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:02:55.608246Z","caller":"traceutil/trace.go:171","msg":"trace[1675705284] transaction","detail":"{read_only:false; response_revision:21178; number_of_response:1; }","duration":"1.090030959s","start":"2026-10-26T20:02:54.518198Z","end":"2026-10-26T20:02:55.608229Z","steps":["trace[1675705284] 'process raft request' (duration: 624.92048ms)","trace[1675705284] 'compare' (duration: 464.527734ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:02:55.608308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.51818Z","time spent":"1.090098212s","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:02:55.608317Z","caller":"traceutil/trace.go:171","msg":"trace[2128982171] transaction","detail":"{read_only:false; response_revision:21181; number_of_response:1; }","duration":"915.451252ms","start":"2026-10-26T20:02:54.692856Z","end":"2026-10-26T20:02:55.608307Z","steps":["trace[2128982171] 'process raft request' (duration: 915.417921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:55.608362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.692838Z","time spent":"915.501253ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:02:55.608457Z","caller":"traceutil/trace.go:171","msg":"trace[1323817556] transaction","detail":"{read_only:false; response_revision:21180; number_of_response:1; }","duration":"925.33672ms","start":"2026-10-26T20:02:54.683111Z","end":"2026-10-26T20:02:55.608448Z","steps":["trace[1323817556] 'process raft request' (duration: 925.024292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:55.608497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.683101Z","time spent":"925.373952ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:02:55.608552Z","caller":"traceutil/trace.go:171","msg":"trace[648262652] linearizableReadLoop","detail":"{readStateIndex:21900; appliedIndex:21897; }","duration":"919.053706ms","start":"2026-10-26T20:02:54.68949Z","end":"2026-10-26T20:02:55.608543Z","steps":["trace[648262652] 'read index received' (duration: 453.569497ms)","trace[648262652] 'applied index is now lower than readState.Index' (duration: 465.483409ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:02:55.608636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062909929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T20:02:55.608677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.22904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:02:55.608705Z","caller":"traceutil/trace.go:171","msg":"trace[1013301822] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:21181; }","duration":"695.296002ms","start":"2026-10-26T20:02:54.913398Z","end":"2026-10-26T20:02:55.608694Z","steps":["trace[1013301822] 'agreement among raft nodes before linearized reading' (duration: 695.253181ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:55.608722Z","caller":"traceutil/trace.go:171","msg":"trace[1287655385] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21181; }","duration":"1.062967501s","start":"2026-10-26T20:02:54.545684Z","end":"2026-10-26T20:02:55.608652Z","steps":["trace[1287655385] 'agreement among raft nodes before linearized reading' (duration: 1.062920299s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:55.60873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.913357Z","time spent":"695.365584ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":32,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:02:55.608751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.545664Z","time spent":"1.063076834s","remote":"127.0.0.1:51922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:02:55.608755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.780622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T20:02:55.608803Z","caller":"traceutil/trace.go:171","msg":"trace[1124854496] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21181; }","duration":"925.863053ms","start":"2026-10-26T20:02:54.68293Z","end":"2026-10-26T20:02:55.608793Z","steps":["trace[1124854496] 'agreement among raft nodes before linearized reading' (duration: 925.654858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:55.608835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.682915Z","time spent":"925.911705ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12456,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-26T20:02:55.608835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.092733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T20:02:55.608897Z","caller":"traceutil/trace.go:171","msg":"trace[952705276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21181; }","duration":"224.237987ms","start":"2026-10-26T20:02:55.384643Z","end":"2026-10-26T20:02:55.608881Z","steps":["trace[952705276] 'agreement among raft nodes before linearized reading' (duration: 224.028571ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:55.608322Z","caller":"traceutil/trace.go:171","msg":"trace[1726912351] transaction","detail":"{read_only:false; response_revision:21179; number_of_response:1; }","duration":"963.301333ms","start":"2026-10-26T20:02:54.644992Z","end":"2026-10-26T20:02:55.608293Z","steps":["trace[1726912351] 'process raft request' (duration: 963.102768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:55.609777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:54.644972Z","time spent":"964.747101ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:02:56.204269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.326944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:02:56.204426Z","caller":"traceutil/trace.go:171","msg":"trace[1452367871] linearizableReadLoop","detail":"{readStateIndex:21904; appliedIndex:21902; }","duration":"529.095623ms","start":"2026-10-26T20:02:55.675319Z","end":"2026-10-26T20:02:56.204414Z","steps":["trace[1452367871] 'read index received' (duration: 162.534862ms)","trace[1452367871] 'applied index is now lower than readState.Index' (duration: 366.560151ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:02:56.204529Z","caller":"traceutil/trace.go:171","msg":"trace[1038603709] transaction","detail":"{read_only:false; response_revision:21183; number_of_response:1; }","duration":"584.913924ms","start":"2026-10-26T20:02:55.619582Z","end":"2026-10-26T20:02:56.204496Z","steps":["trace[1038603709] 'process raft request' (duration: 584.76004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:56.204601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.99481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:56.20463Z","caller":"traceutil/trace.go:171","msg":"trace[386227050] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:21183; }","duration":"438.049151ms","start":"2026-10-26T20:02:55.76657Z","end":"2026-10-26T20:02:56.204619Z","steps":["trace[386227050] 'agreement among raft nodes before linearized reading' (duration: 438.01121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:56.204652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:55.766553Z","time spent":"438.092283ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:02:56.204686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.228158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:56.204684Z","caller":"traceutil/trace.go:171","msg":"trace[29221293] transaction","detail":"{read_only:false; response_revision:21182; number_of_response:1; }","duration":"592.610804ms","start":"2026-10-26T20:02:55.612045Z","end":"2026-10-26T20:02:56.204656Z","steps":["trace[29221293] 'process raft request' (duration: 225.832858ms)","trace[29221293] 'compare' (duration: 366.228642ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:02:56.20479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:55.619569Z","time spent":"585.038217ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:02:56.204837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.645139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:02:56.204725Z","caller":"traceutil/trace.go:171","msg":"trace[1099596029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21183; }","duration":"156.306039ms","start":"2026-10-26T20:02:56.048409Z","end":"2026-10-26T20:02:56.204715Z","steps":["trace[1099596029] 'agreement among raft nodes before linearized reading' (duration: 156.221437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:56.204917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.207165ms","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-10-26T20:02:56.204937Z","caller":"traceutil/trace.go:171","msg":"trace[904112941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21183; }","duration":"174.782723ms","start":"2026-10-26T20:02:56.030129Z","end":"2026-10-26T20:02:56.204912Z","steps":["trace[904112941] 'agreement among raft nodes before linearized reading' (duration: 174.533256ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:56.205048Z","caller":"traceutil/trace.go:171","msg":"trace[1822135111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21183; }","duration":"175.348819ms","start":"2026-10-26T20:02:56.029661Z","end":"2026-10-26T20:02:56.20501Z","steps":["trace[1822135111] 'agreement among raft nodes before linearized reading' (duration: 175.03246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:56.204817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:55.612029Z","time spent":"592.723358ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:02:56.20456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.239697ms","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-10-26T20:02:56.205225Z","caller":"traceutil/trace.go:171","msg":"trace[2140122389] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21183; }","duration":"529.899864ms","start":"2026-10-26T20:02:55.675313Z","end":"2026-10-26T20:02:56.205213Z","steps":["trace[2140122389] 'agreement among raft nodes before linearized reading' (duration: 529.191835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:56.205257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:55.675272Z","time spent":"529.977666ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-26T20:02:56.204854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.316088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-26T20:02:56.205412Z","caller":"traceutil/trace.go:171","msg":"trace[1844593302] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21183; }","duration":"133.841811ms","start":"2026-10-26T20:02:56.071499Z","end":"2026-10-26T20:02:56.205341Z","steps":["trace[1844593302] 'agreement among raft nodes before linearized reading' (duration: 133.258256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.094515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.289901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:02:57.094787Z","caller":"traceutil/trace.go:171","msg":"trace[1606769503] transaction","detail":"{read_only:false; response_revision:21184; number_of_response:1; }","duration":"885.917108ms","start":"2026-10-26T20:02:56.208664Z","end":"2026-10-26T20:02:57.094582Z","steps":["trace[1606769503] 'process raft request' (duration: 383.497493ms)","trace[1606769503] 'compare' (duration: 501.563372ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:02:57.094835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.208643Z","time spent":"886.171435ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:02:57.132982Z","caller":"traceutil/trace.go:171","msg":"trace[1235970458] transaction","detail":"{read_only:false; response_revision:21185; number_of_response:1; }","duration":"924.23661ms","start":"2026-10-26T20:02:56.20871Z","end":"2026-10-26T20:02:57.132947Z","steps":["trace[1235970458] 'process raft request' (duration: 923.914102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.133204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.208688Z","time spent":"924.437526ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:02:57.1338Z","caller":"traceutil/trace.go:171","msg":"trace[713658198] linearizableReadLoop","detail":"{readStateIndex:21906; appliedIndex:21904; }","duration":"924.855568ms","start":"2026-10-26T20:02:56.208919Z","end":"2026-10-26T20:02:57.133774Z","steps":["trace[713658198] 'read index received' (duration: 383.254329ms)","trace[713658198] 'applied index is now lower than readState.Index' (duration: 541.599809ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:02:57.133863Z","caller":"traceutil/trace.go:171","msg":"trace[216833832] transaction","detail":"{read_only:false; response_revision:21186; number_of_response:1; }","duration":"923.78326ms","start":"2026-10-26T20:02:56.210049Z","end":"2026-10-26T20:02:57.133832Z","steps":["trace[216833832] 'process raft request' (duration: 922.705051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.133969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.210032Z","time spent":"923.882122ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:02:57.134002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.067614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-26T20:02:57.134039Z","caller":"traceutil/trace.go:171","msg":"trace[890834758] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:21186; }","duration":"925.142266ms","start":"2026-10-26T20:02:56.208886Z","end":"2026-10-26T20:02:57.134028Z","steps":["trace[890834758] 'agreement among raft nodes before linearized reading' (duration: 925.006302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.13407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.208871Z","time spent":"925.190647ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-10-26T20:02:57.134813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.819464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:57.13484Z","caller":"traceutil/trace.go:171","msg":"trace[133474575] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21188; }","duration":"925.869524ms","start":"2026-10-26T20:02:56.208964Z","end":"2026-10-26T20:02:57.134834Z","steps":["trace[133474575] 'agreement among raft nodes before linearized reading' (duration: 925.810393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.134855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.208955Z","time spent":"925.895726ms","remote":"127.0.0.1:42662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-26T20:02:57.134946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.668421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:57.134982Z","caller":"traceutil/trace.go:171","msg":"trace[1199715169] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:21188; }","duration":"119.737583ms","start":"2026-10-26T20:02:57.015235Z","end":"2026-10-26T20:02:57.134972Z","steps":["trace[1199715169] 'agreement among raft nodes before linearized reading' (duration: 119.672991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.134986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.479117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:57.135004Z","caller":"traceutil/trace.go:171","msg":"trace[416054532] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:21188; }","duration":"322.526399ms","start":"2026-10-26T20:02:56.812471Z","end":"2026-10-26T20:02:57.134997Z","steps":["trace[416054532] 'agreement among raft nodes before linearized reading' (duration: 322.493848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.135022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.812454Z","time spent":"322.563939ms","remote":"127.0.0.1:33968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:02:57.135056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.014747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:57.135091Z","caller":"traceutil/trace.go:171","msg":"trace[1465297433] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:21188; }","duration":"728.081099ms","start":"2026-10-26T20:02:56.407Z","end":"2026-10-26T20:02:57.135081Z","steps":["trace[1465297433] 'agreement among raft nodes before linearized reading' (duration: 728.017857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.135111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.406984Z","time spent":"728.120191ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:02:57.135107Z","caller":"traceutil/trace.go:171","msg":"trace[1624609028] transaction","detail":"{read_only:false; response_revision:21187; number_of_response:1; }","duration":"441.537103ms","start":"2026-10-26T20:02:56.693561Z","end":"2026-10-26T20:02:57.135098Z","steps":["trace[1624609028] 'process raft request' (duration: 441.147633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.135446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.69354Z","time spent":"441.865001ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:02:57.135855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.134011ms","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:6"} {"level":"info","ts":"2026-10-26T20:02:57.135898Z","caller":"traceutil/trace.go:171","msg":"trace[1549777726] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:21188; }","duration":"712.204263ms","start":"2026-10-26T20:02:56.423685Z","end":"2026-10-26T20:02:57.135889Z","steps":["trace[1549777726] 'agreement among raft nodes before linearized reading' (duration: 711.526306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:57.13592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:56.423667Z","time spent":"712.245534ms","remote":"127.0.0.1:52916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:04:42.170997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19835} {"level":"info","ts":"2026-10-26T20:04:42.291808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19835,"took":"117.897325ms","hash":1508742360,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":28925952,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T20:04:42.291869Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1508742360,"revision":19835,"compact-revision":17533} {"level":"warn","ts":"2026-10-26T20:07:13.888716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.059228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-webhook-service\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:07:13.888825Z","caller":"traceutil/trace.go:171","msg":"trace[297204028] range","detail":"{range_begin:/registry/services/specs/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-webhook-service; range_end:; response_count:0; response_revision:23524; }","duration":"103.238512ms","start":"2026-10-26T20:07:13.785567Z","end":"2026-10-26T20:07:13.888805Z","steps":["trace[297204028] 'agreement among raft nodes before linearized reading' (duration: 102.969465ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:23.313111Z","caller":"traceutil/trace.go:171","msg":"trace[253336709] transaction","detail":"{read_only:false; response_revision:23845; number_of_response:1; }","duration":"209.175495ms","start":"2026-10-26T20:07:23.103903Z","end":"2026-10-26T20:07:23.313079Z","steps":["trace[253336709] 'process raft request' (duration: 209.004181ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:23.591756Z","caller":"traceutil/trace.go:171","msg":"trace[628299524] transaction","detail":"{read_only:false; response_revision:23846; number_of_response:1; }","duration":"483.197277ms","start":"2026-10-26T20:07:23.108541Z","end":"2026-10-26T20:07:23.591738Z","steps":["trace[628299524] 'process raft request' (duration: 480.789614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:23.591867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:23.108365Z","time spent":"483.445354ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:07:23.59187Z","caller":"traceutil/trace.go:171","msg":"trace[1884430561] linearizableReadLoop","detail":"{readStateIndex:24648; appliedIndex:24645; }","duration":"393.412957ms","start":"2026-10-26T20:07:23.198443Z","end":"2026-10-26T20:07:23.591856Z","steps":["trace[1884430561] 'read index received' (duration: 114.582789ms)","trace[1884430561] 'applied index is now lower than readState.Index' (duration: 278.828238ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:07:23.591898Z","caller":"traceutil/trace.go:171","msg":"trace[1857278143] transaction","detail":"{read_only:false; response_revision:23847; number_of_response:1; }","duration":"476.019319ms","start":"2026-10-26T20:07:23.115854Z","end":"2026-10-26T20:07:23.591873Z","steps":["trace[1857278143] 'process raft request' (duration: 475.844135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:23.591991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.555931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T20:07:23.592023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:23.115831Z","time spent":"476.129603ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:07:23.59204Z","caller":"traceutil/trace.go:171","msg":"trace[520993476] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:23847; }","duration":"393.670314ms","start":"2026-10-26T20:07:23.198349Z","end":"2026-10-26T20:07:23.592019Z","steps":["trace[520993476] 'agreement among raft nodes before linearized reading' (duration: 393.546591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:23.59207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:23.198318Z","time spent":"393.743066ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":98,"response size":32,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:09:23.305938Z","caller":"traceutil/trace.go:171","msg":"trace[2116890833] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"121.226573ms","start":"2026-10-26T20:09:23.184686Z","end":"2026-10-26T20:09:23.305913Z","steps":["trace[2116890833] 'process raft request' (duration: 121.053438ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:30.658749Z","caller":"traceutil/trace.go:171","msg":"trace[727529780] linearizableReadLoop","detail":"{readStateIndex:25964; appliedIndex:25963; }","duration":"337.886693ms","start":"2026-10-26T20:09:30.320845Z","end":"2026-10-26T20:09:30.658731Z","steps":["trace[727529780] 'read index received' (duration: 337.75679ms)","trace[727529780] 'applied index is now lower than readState.Index' (duration: 129.133µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:09:30.658925Z","caller":"traceutil/trace.go:171","msg":"trace[142392032] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"370.98113ms","start":"2026-10-26T20:09:30.287934Z","end":"2026-10-26T20:09:30.658915Z","steps":["trace[142392032] 'process raft request' (duration: 370.678812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:09:30.659004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:09:30.287915Z","time spent":"371.031691ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:09:30.659212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.365997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:1 size:12669"} {"level":"info","ts":"2026-10-26T20:09:30.659238Z","caller":"traceutil/trace.go:171","msg":"trace[1191908455] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:1; response_revision:25131; }","duration":"338.419157ms","start":"2026-10-26T20:09:30.320812Z","end":"2026-10-26T20:09:30.659231Z","steps":["trace[1191908455] 'agreement among raft nodes before linearized reading' (duration: 338.309124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:09:30.659255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:09:30.320796Z","time spent":"338.455579ms","remote":"127.0.0.1:42702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":12693,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"warn","ts":"2026-10-26T20:09:30.659343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.986192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:09:30.659291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.348456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:09:30.659477Z","caller":"traceutil/trace.go:171","msg":"trace[1830280577] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25131; }","duration":"338.549182ms","start":"2026-10-26T20:09:30.320916Z","end":"2026-10-26T20:09:30.659465Z","steps":["trace[1830280577] 'agreement among raft nodes before linearized reading' (duration: 338.301825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:09:30.65951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:09:30.320894Z","time spent":"338.607383ms","remote":"127.0.0.1:42702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-10-26T20:09:30.65947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.470685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"warn","ts":"2026-10-26T20:09:30.659555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.593915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:1 size:12669"} {"level":"info","ts":"2026-10-26T20:09:30.659564Z","caller":"traceutil/trace.go:171","msg":"trace[331595880] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25131; }","duration":"159.562237ms","start":"2026-10-26T20:09:30.499994Z","end":"2026-10-26T20:09:30.659556Z","steps":["trace[331595880] 'agreement among raft nodes before linearized reading' (duration: 159.390272ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:30.659573Z","caller":"traceutil/trace.go:171","msg":"trace[1854683877] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:1; response_revision:25131; }","duration":"191.624166ms","start":"2026-10-26T20:09:30.467944Z","end":"2026-10-26T20:09:30.659568Z","steps":["trace[1854683877] 'agreement among raft nodes before linearized reading' (duration: 191.568285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:09:30.659608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.021886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-10-26T20:09:30.659653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.459157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:09:30.65967Z","caller":"traceutil/trace.go:171","msg":"trace[1788477305] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25131; }","duration":"192.501788ms","start":"2026-10-26T20:09:30.467163Z","end":"2026-10-26T20:09:30.659665Z","steps":["trace[1788477305] 'agreement among raft nodes before linearized reading' (duration: 192.477137ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:30.659677Z","caller":"traceutil/trace.go:171","msg":"trace[1555248821] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25131; }","duration":"242.131238ms","start":"2026-10-26T20:09:30.41753Z","end":"2026-10-26T20:09:30.659662Z","steps":["trace[1555248821] 'agreement among raft nodes before linearized reading' (duration: 241.933663ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:30.659438Z","caller":"traceutil/trace.go:171","msg":"trace[2107377272] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25131; }","duration":"133.099525ms","start":"2026-10-26T20:09:30.526296Z","end":"2026-10-26T20:09:30.659395Z","steps":["trace[2107377272] 'agreement among raft nodes before linearized reading' (duration: 132.989212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:09:30.659795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.351838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-26T20:09:30.659817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.87474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:09:30.659823Z","caller":"traceutil/trace.go:171","msg":"trace[1822085358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25131; }","duration":"327.435431ms","start":"2026-10-26T20:09:30.33238Z","end":"2026-10-26T20:09:30.659816Z","steps":["trace[1822085358] 'agreement among raft nodes before linearized reading' (duration: 327.324918ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:30.659835Z","caller":"traceutil/trace.go:171","msg":"trace[1127721119] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25131; }","duration":"338.90341ms","start":"2026-10-26T20:09:30.320926Z","end":"2026-10-26T20:09:30.65983Z","steps":["trace[1127721119] 'agreement among raft nodes before linearized reading' (duration: 338.873549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:09:30.659849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:09:30.332362Z","time spent":"327.481182ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T20:09:30.659854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:09:30.320922Z","time spent":"338.924621ms","remote":"127.0.0.1:42702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-10-26T20:09:30.659308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.556914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:09:30.659879Z","caller":"traceutil/trace.go:171","msg":"trace[1090229823] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25131; }","duration":"192.140079ms","start":"2026-10-26T20:09:30.467731Z","end":"2026-10-26T20:09:30.659871Z","steps":["trace[1090229823] 'agreement among raft nodes before linearized reading' (duration: 191.492392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:09:30.908809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.130971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:09:30.908881Z","caller":"traceutil/trace.go:171","msg":"trace[1118758664] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25133; }","duration":"142.223703ms","start":"2026-10-26T20:09:30.766644Z","end":"2026-10-26T20:09:30.908867Z","steps":["trace[1118758664] 'count revisions from in-memory index tree' (duration: 142.064348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:09:30.908912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.545527ms","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-10-26T20:09:30.908815Z","caller":"traceutil/trace.go:171","msg":"trace[1607918946] linearizableReadLoop","detail":"{readStateIndex:25967; appliedIndex:25966; }","duration":"104.427394ms","start":"2026-10-26T20:09:30.804365Z","end":"2026-10-26T20:09:30.908792Z","steps":["trace[1607918946] 'read index received' (duration: 15.702422ms)","trace[1607918946] 'applied index is now lower than readState.Index' (duration: 88.723652ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:09:30.908965Z","caller":"traceutil/trace.go:171","msg":"trace[730235083] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"120.390911ms","start":"2026-10-26T20:09:30.788565Z","end":"2026-10-26T20:09:30.908956Z","steps":["trace[730235083] 'process raft request' (duration: 31.569287ms)","trace[730235083] 'compare' (duration: 88.347382ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:09:30.908937Z","caller":"traceutil/trace.go:171","msg":"trace[1533680448] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25134; }","duration":"104.601649ms","start":"2026-10-26T20:09:30.804328Z","end":"2026-10-26T20:09:30.908929Z","steps":["trace[1533680448] 'agreement among raft nodes before linearized reading' (duration: 104.521827ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:31.340954Z","caller":"traceutil/trace.go:171","msg":"trace[576703512] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"188.840974ms","start":"2026-10-26T20:09:31.15209Z","end":"2026-10-26T20:09:31.340931Z","steps":["trace[576703512] 'process raft request' (duration: 188.641838ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:41.097209Z","caller":"traceutil/trace.go:171","msg":"trace[157804935] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"138.65147ms","start":"2026-10-26T20:09:40.9585Z","end":"2026-10-26T20:09:41.097151Z","steps":["trace[157804935] 'process raft request' (duration: 138.366102ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:42.185096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22039} {"level":"info","ts":"2026-10-26T20:09:42.396908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22039,"took":"208.416675ms","hash":3109742823,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":44523520,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-26T20:09:42.396981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3109742823,"revision":22039,"compact-revision":19835} {"level":"info","ts":"2026-10-26T20:10:50.66574Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000682c.wal"} {"level":"info","ts":"2026-10-26T20:11:08.888366Z","caller":"traceutil/trace.go:171","msg":"trace[198709156] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"395.301177ms","start":"2026-10-26T20:11:08.493042Z","end":"2026-10-26T20:11:08.888343Z","steps":["trace[198709156] 'process raft request' (duration: 395.144853ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:08.888458Z","caller":"traceutil/trace.go:171","msg":"trace[594270238] linearizableReadLoop","detail":"{readStateIndex:26879; appliedIndex:26879; }","duration":"339.145167ms","start":"2026-10-26T20:11:08.549281Z","end":"2026-10-26T20:11:08.888426Z","steps":["trace[594270238] 'read index received' (duration: 339.114617ms)","trace[594270238] 'applied index is now lower than readState.Index' (duration: 28.05µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:11:08.888533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.493001Z","time spent":"395.456621ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:11:08.888647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.339602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:11:08.888687Z","caller":"traceutil/trace.go:171","msg":"trace[1503449854] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26021; }","duration":"339.430225ms","start":"2026-10-26T20:11:08.549245Z","end":"2026-10-26T20:11:08.888675Z","steps":["trace[1503449854] 'agreement among raft nodes before linearized reading' (duration: 339.312282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:08.888724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.549222Z","time spent":"339.487546ms","remote":"127.0.0.1:33922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:11:09.304225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.687312ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:11:09.304326Z","caller":"traceutil/trace.go:171","msg":"trace[2106045588] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26021; }","duration":"305.801585ms","start":"2026-10-26T20:11:08.998509Z","end":"2026-10-26T20:11:09.30431Z","steps":["trace[2106045588] 'range keys from in-memory index tree' (duration: 305.673162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.304504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.108058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:11:09.304673Z","caller":"traceutil/trace.go:171","msg":"trace[314252500] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"354.869148ms","start":"2026-10-26T20:11:08.949796Z","end":"2026-10-26T20:11:09.304665Z","steps":["trace[314252500] 'process raft request' (duration: 354.832837ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:09.304685Z","caller":"traceutil/trace.go:171","msg":"trace[483655745] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"457.814324ms","start":"2026-10-26T20:11:08.846861Z","end":"2026-10-26T20:11:09.304676Z","steps":["trace[483655745] 'process raft request' (duration: 457.737972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.30472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.949776Z","time spent":"354.92027ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:11:09.304732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.846841Z","time spent":"457.864275ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:11:09.304799Z","caller":"traceutil/trace.go:171","msg":"trace[1802751132] transaction","detail":"{read_only:false; response_revision:26023; number_of_response:1; }","duration":"561.297913ms","start":"2026-10-26T20:11:08.743493Z","end":"2026-10-26T20:11:09.304791Z","steps":["trace[1802751132] 'process raft request' (duration: 561.063786ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:09.304792Z","caller":"traceutil/trace.go:171","msg":"trace[1127002523] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"641.616495ms","start":"2026-10-26T20:11:08.663101Z","end":"2026-10-26T20:11:09.304718Z","steps":["trace[1127002523] 'process raft request' (duration: 415.242879ms)","trace[1127002523] 'compare' (duration: 225.4058ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:11:09.304959Z","caller":"traceutil/trace.go:171","msg":"trace[97659476] linearizableReadLoop","detail":"{readStateIndex:26882; appliedIndex:26879; }","duration":"416.41697ms","start":"2026-10-26T20:11:08.888524Z","end":"2026-10-26T20:11:09.304941Z","steps":["trace[97659476] 'read index received' (duration: 189.981273ms)","trace[97659476] 'applied index is now lower than readState.Index' (duration: 226.431326ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:11:09.305082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.255885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:11:09.305111Z","caller":"traceutil/trace.go:171","msg":"trace[657095424] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26025; }","duration":"513.315526ms","start":"2026-10-26T20:11:08.791785Z","end":"2026-10-26T20:11:09.3051Z","steps":["trace[657095424] 'agreement among raft nodes before linearized reading' (duration: 513.239104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.305133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.791763Z","time spent":"513.365138ms","remote":"127.0.0.1:42662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-26T20:11:09.305132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.286352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:11:09.305169Z","caller":"traceutil/trace.go:171","msg":"trace[1267800400] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:26025; }","duration":"276.353354ms","start":"2026-10-26T20:11:09.028804Z","end":"2026-10-26T20:11:09.305157Z","steps":["trace[1267800400] 'agreement among raft nodes before linearized reading' (duration: 276.286953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.305288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.997431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T20:11:09.305386Z","caller":"traceutil/trace.go:171","msg":"trace[1044590015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26025; }","duration":"593.132555ms","start":"2026-10-26T20:11:08.712232Z","end":"2026-10-26T20:11:09.305365Z","steps":["trace[1044590015] 'agreement among raft nodes before linearized reading' (duration: 592.852767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.30546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.712216Z","time spent":"593.235328ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T20:11:09.305482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.743315Z","time spent":"561.504847ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:11:09.305563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.904894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-26T20:11:09.305617Z","caller":"traceutil/trace.go:171","msg":"trace[2090862718] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26025; }","duration":"707.043247ms","start":"2026-10-26T20:11:08.598559Z","end":"2026-10-26T20:11:09.305602Z","steps":["trace[2090862718] 'agreement among raft nodes before linearized reading' (duration: 706.510793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.305642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.598522Z","time spent":"707.111859ms","remote":"127.0.0.1:42632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-10-26T20:11:09.306021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:08.663076Z","time spent":"641.849211ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:11:09.793037Z","caller":"traceutil/trace.go:171","msg":"trace[110042238] linearizableReadLoop","detail":"{readStateIndex:26884; appliedIndex:26883; }","duration":"476.430759ms","start":"2026-10-26T20:11:09.316587Z","end":"2026-10-26T20:11:09.793017Z","steps":["trace[110042238] 'read index received' (duration: 475.869066ms)","trace[110042238] 'applied index is now lower than readState.Index' (duration: 560.943µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:11:09.793113Z","caller":"traceutil/trace.go:171","msg":"trace[1571441924] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"479.11568ms","start":"2026-10-26T20:11:09.313977Z","end":"2026-10-26T20:11:09.793092Z","steps":["trace[1571441924] 'process raft request' (duration: 478.901356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.79323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:09.313962Z","time spent":"479.186852ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:11:09.793507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.912193ms","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:1936"} {"level":"info","ts":"2026-10-26T20:11:09.793655Z","caller":"traceutil/trace.go:171","msg":"trace[1885102392] 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:26026; }","duration":"476.958124ms","start":"2026-10-26T20:11:09.316579Z","end":"2026-10-26T20:11:09.793538Z","steps":["trace[1885102392] 'agreement among raft nodes before linearized reading' (duration: 476.553253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.793778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:09.31657Z","time spent":"477.19781ms","remote":"127.0.0.1:42632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1960,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-10-26T20:11:09.796372Z","caller":"traceutil/trace.go:171","msg":"trace[1772194024] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"479.774188ms","start":"2026-10-26T20:11:09.316576Z","end":"2026-10-26T20:11:09.79635Z","steps":["trace[1772194024] 'process raft request' (duration: 479.526931ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:09.796445Z","caller":"traceutil/trace.go:171","msg":"trace[1439386210] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"343.805029ms","start":"2026-10-26T20:11:09.452591Z","end":"2026-10-26T20:11:09.796396Z","steps":["trace[1439386210] 'process raft request' (duration: 343.701246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.796535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:09.316558Z","time spent":"479.88393ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:11:09.796543Z","caller":"traceutil/trace.go:171","msg":"trace[668266276] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"479.605144ms","start":"2026-10-26T20:11:09.316884Z","end":"2026-10-26T20:11:09.796489Z","steps":["trace[668266276] 'process raft request' (duration: 479.363667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.79662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.833992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-10-26T20:11:09.796556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.857144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:11:09.796664Z","caller":"traceutil/trace.go:171","msg":"trace[1371220807] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26029; }","duration":"107.879723ms","start":"2026-10-26T20:11:09.688773Z","end":"2026-10-26T20:11:09.796652Z","steps":["trace[1371220807] 'agreement among raft nodes before linearized reading' (duration: 107.709368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.796631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:09.45257Z","time spent":"343.971084ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:11:09.796722Z","caller":"traceutil/trace.go:171","msg":"trace[1608309047] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26029; }","duration":"479.157893ms","start":"2026-10-26T20:11:09.317525Z","end":"2026-10-26T20:11:09.796683Z","steps":["trace[1608309047] 'agreement among raft nodes before linearized reading' (duration: 478.819864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.796647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.344055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:11:09.796777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:09.316878Z","time spent":"479.777948ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:11:09.796788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:09.317519Z","time spent":"479.257105ms","remote":"127.0.0.1:42732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-10-26T20:11:09.796819Z","caller":"traceutil/trace.go:171","msg":"trace[1378836700] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26029; }","duration":"312.550791ms","start":"2026-10-26T20:11:09.484256Z","end":"2026-10-26T20:11:09.796807Z","steps":["trace[1378836700] 'agreement among raft nodes before linearized reading' (duration: 312.346346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:09.796901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:11:09.484239Z","time spent":"312.649963ms","remote":"127.0.0.1:34372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:11:28.458877Z","caller":"traceutil/trace.go:171","msg":"trace[1987299132] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"104.391012ms","start":"2026-10-26T20:11:28.354441Z","end":"2026-10-26T20:11:28.458832Z","steps":["trace[1987299132] 'process raft request' (duration: 54.112236ms)","trace[1987299132] 'compare' (duration: 50.03848ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:12:16.386375Z","caller":"traceutil/trace.go:171","msg":"trace[1832166180] linearizableReadLoop","detail":"{readStateIndex:27489; appliedIndex:27488; }","duration":"164.903956ms","start":"2026-10-26T20:12:16.221456Z","end":"2026-10-26T20:12:16.38636Z","steps":["trace[1832166180] 'read index received' (duration: 164.804073ms)","trace[1832166180] 'applied index is now lower than readState.Index' (duration: 99.193µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:12:16.386453Z","caller":"traceutil/trace.go:171","msg":"trace[817739256] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"194.393548ms","start":"2026-10-26T20:12:16.192043Z","end":"2026-10-26T20:12:16.386436Z","steps":["trace[817739256] 'process raft request' (duration: 194.178632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:16.386598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.110501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:12:16.386682Z","caller":"traceutil/trace.go:171","msg":"trace[54210124] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26616; }","duration":"165.215025ms","start":"2026-10-26T20:12:16.221449Z","end":"2026-10-26T20:12:16.386664Z","steps":["trace[54210124] 'agreement among raft nodes before linearized reading' (duration: 165.045561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:16.73839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.45278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:12:16.738646Z","caller":"traceutil/trace.go:171","msg":"trace[914744577] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"408.563295ms","start":"2026-10-26T20:12:16.330067Z","end":"2026-10-26T20:12:16.73863Z","steps":["trace[914744577] 'process raft request' (duration: 408.427702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:16.738709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.330048Z","time spent":"408.629737ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:12:16.738709Z","caller":"traceutil/trace.go:171","msg":"trace[1924763705] linearizableReadLoop","detail":"{readStateIndex:27492; appliedIndex:27489; }","duration":"352.22373ms","start":"2026-10-26T20:12:16.386463Z","end":"2026-10-26T20:12:16.738686Z","steps":["trace[1924763705] 'read index received' (duration: 227.554636ms)","trace[1924763705] 'applied index is now lower than readState.Index' (duration: 124.667994ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:12:16.73881Z","caller":"traceutil/trace.go:171","msg":"trace[659747863] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"408.713278ms","start":"2026-10-26T20:12:16.330068Z","end":"2026-10-26T20:12:16.738781Z","steps":["trace[659747863] 'process raft request' (duration: 281.780667ms)","trace[659747863] 'compare' (duration: 126.228614ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:12:16.738836Z","caller":"traceutil/trace.go:171","msg":"trace[394456367] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"395.44861ms","start":"2026-10-26T20:12:16.343357Z","end":"2026-10-26T20:12:16.738806Z","steps":["trace[394456367] 'process raft request' (duration: 395.234475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:16.738937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.330046Z","time spent":"408.821441ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:12:16.738944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.343324Z","time spent":"395.550323ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:12:16.738942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.038163ms","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-10-26T20:12:16.73899Z","caller":"traceutil/trace.go:171","msg":"trace[1277385161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26619; }","duration":"504.119756ms","start":"2026-10-26T20:12:16.234861Z","end":"2026-10-26T20:12:16.738981Z","steps":["trace[1277385161] 'agreement among raft nodes before linearized reading' (duration: 503.964622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:16.73902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.234843Z","time spent":"504.169795ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T20:12:16.739014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.03621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T20:12:16.739086Z","caller":"traceutil/trace.go:171","msg":"trace[862425731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26619; }","duration":"353.141613ms","start":"2026-10-26T20:12:16.385923Z","end":"2026-10-26T20:12:16.739064Z","steps":["trace[862425731] 'agreement among raft nodes before linearized reading' (duration: 352.859705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:16.739121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.385905Z","time spent":"353.208814ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T20:12:16.739383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.839228ms","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-10-26T20:12:16.739429Z","caller":"traceutil/trace.go:171","msg":"trace[375497798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26619; }","duration":"461.90648ms","start":"2026-10-26T20:12:16.277501Z","end":"2026-10-26T20:12:16.739408Z","steps":["trace[375497798] 'agreement among raft nodes before linearized reading' (duration: 461.296774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:16.739451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.277484Z","time spent":"461.960742ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T20:12:17.211099Z","caller":"traceutil/trace.go:171","msg":"trace[142300227] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"468.135324ms","start":"2026-10-26T20:12:16.742943Z","end":"2026-10-26T20:12:17.211078Z","steps":["trace[142300227] 'process raft request' (duration: 467.968569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:17.21129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.742924Z","time spent":"468.301248ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:12:17.296228Z","caller":"traceutil/trace.go:171","msg":"trace[2136273327] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"552.961853ms","start":"2026-10-26T20:12:16.743244Z","end":"2026-10-26T20:12:17.296206Z","steps":["trace[2136273327] 'process raft request' (duration: 552.779378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:17.296367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.743235Z","time spent":"553.067306ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:12:17.296523Z","caller":"traceutil/trace.go:171","msg":"trace[802072151] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"553.132048ms","start":"2026-10-26T20:12:16.743371Z","end":"2026-10-26T20:12:17.296503Z","steps":["trace[802072151] 'process raft request' (duration: 552.793239ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:12:17.296629Z","caller":"traceutil/trace.go:171","msg":"trace[1748973543] linearizableReadLoop","detail":"{readStateIndex:27496; appliedIndex:27492; }","duration":"544.277566ms","start":"2026-10-26T20:12:16.752331Z","end":"2026-10-26T20:12:17.296608Z","steps":["trace[1748973543] 'read index received' (duration: 458.688596ms)","trace[1748973543] 'applied index is now lower than readState.Index' (duration: 85.58777ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:12:17.296686Z","caller":"traceutil/trace.go:171","msg":"trace[2096071421] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"551.715292ms","start":"2026-10-26T20:12:16.744963Z","end":"2026-10-26T20:12:17.296678Z","steps":["trace[2096071421] 'process raft request' (duration: 551.465365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:17.296646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.743363Z","time spent":"553.20569ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:12:17.296726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.744955Z","time spent":"551.749032ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:12:17.296805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.947832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:12:17.296869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.540853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-26T20:12:17.296886Z","caller":"traceutil/trace.go:171","msg":"trace[650730180] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:26623; }","duration":"475.037904ms","start":"2026-10-26T20:12:16.821805Z","end":"2026-10-26T20:12:17.296843Z","steps":["trace[650730180] 'agreement among raft nodes before linearized reading' (duration: 474.938731ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:12:17.296901Z","caller":"traceutil/trace.go:171","msg":"trace[92779485] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26623; }","duration":"544.601214ms","start":"2026-10-26T20:12:16.752291Z","end":"2026-10-26T20:12:17.296892Z","steps":["trace[92779485] 'agreement among raft nodes before linearized reading' (duration: 544.491692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:12:17.296923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.752274Z","time spent":"544.643416ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-26T20:12:17.296928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:12:16.821788Z","time spent":"475.128156ms","remote":"127.0.0.1:33782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:12:17.49383Z","caller":"traceutil/trace.go:171","msg":"trace[2014517392] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"191.736669ms","start":"2026-10-26T20:12:17.301912Z","end":"2026-10-26T20:12:17.493649Z","steps":["trace[2014517392] 'process raft request' (duration: 188.254577ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:12:17.49385Z","caller":"traceutil/trace.go:171","msg":"trace[453257760] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"191.879944ms","start":"2026-10-26T20:12:17.301947Z","end":"2026-10-26T20:12:17.493827Z","steps":["trace[453257760] 'process raft request' (duration: 191.613356ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:12:17.777555Z","caller":"traceutil/trace.go:171","msg":"trace[1740892339] linearizableReadLoop","detail":"{readStateIndex:27499; appliedIndex:27498; }","duration":"192.964551ms","start":"2026-10-26T20:12:17.584569Z","end":"2026-10-26T20:12:17.777534Z","steps":["trace[1740892339] 'read index received' (duration: 192.9191ms)","trace[1740892339] 'applied index is now lower than readState.Index' (duration: 44.491µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:12:17.777805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.216037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-26T20:12:17.777872Z","caller":"traceutil/trace.go:171","msg":"trace[601057488] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:26626; }","duration":"193.32333ms","start":"2026-10-26T20:12:17.584534Z","end":"2026-10-26T20:12:17.777857Z","steps":["trace[601057488] 'agreement among raft nodes before linearized reading' (duration: 193.155936ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:12:17.77793Z","caller":"traceutil/trace.go:171","msg":"trace[152231664] transaction","detail":"{read_only:false; response_revision:26626; number_of_response:1; }","duration":"237.319702ms","start":"2026-10-26T20:12:17.54058Z","end":"2026-10-26T20:12:17.7779Z","steps":["trace[152231664] 'process raft request' (duration: 236.762978ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:12:17.778717Z","caller":"traceutil/trace.go:171","msg":"trace[820941172] transaction","detail":"{read_only:false; response_revision:26628; number_of_response:1; }","duration":"122.734142ms","start":"2026-10-26T20:12:17.655968Z","end":"2026-10-26T20:12:17.778702Z","steps":["trace[820941172] 'process raft request' (duration: 122.692291ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:12:17.778794Z","caller":"traceutil/trace.go:171","msg":"trace[54729891] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"168.084889ms","start":"2026-10-26T20:12:17.610682Z","end":"2026-10-26T20:12:17.778767Z","steps":["trace[54729891] 'process raft request' (duration: 167.895164ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:42.19388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25226} {"level":"info","ts":"2026-10-26T20:14:42.429217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25226,"took":"231.130747ms","hash":1495414236,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":36098048,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T20:14:42.429348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1495414236,"revision":25226,"compact-revision":22039} {"level":"info","ts":"2026-10-26T20:16:35.836452Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:16:35.844804Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T20:16:35.84494Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T20:17:01.243201Z","caller":"traceutil/trace.go:171","msg":"trace[353397195] transaction","detail":"{read_only:false; response_revision:29354; number_of_response:1; }","duration":"121.696485ms","start":"2026-10-26T20:17:01.121484Z","end":"2026-10-26T20:17:01.24318Z","steps":["trace[353397195] 'process raft request' (duration: 119.490987ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:17:01.247287Z","caller":"traceutil/trace.go:171","msg":"trace[1308262564] linearizableReadLoop","detail":"{readStateIndex:30298; appliedIndex:30296; }","duration":"121.128299ms","start":"2026-10-26T20:17:01.126141Z","end":"2026-10-26T20:17:01.247269Z","steps":["trace[1308262564] 'read index received' (duration: 114.837534ms)","trace[1308262564] 'applied index is now lower than readState.Index' (duration: 6.290125ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:17:01.247423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.269832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:17:01.247405Z","caller":"traceutil/trace.go:171","msg":"trace[168637776] transaction","detail":"{read_only:false; response_revision:29355; number_of_response:1; }","duration":"123.126552ms","start":"2026-10-26T20:17:01.124252Z","end":"2026-10-26T20:17:01.247378Z","steps":["trace[168637776] 'process raft request' (duration: 122.928116ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:17:01.247454Z","caller":"traceutil/trace.go:171","msg":"trace[310871298] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:29355; }","duration":"121.349745ms","start":"2026-10-26T20:17:01.126095Z","end":"2026-10-26T20:17:01.247445Z","steps":["trace[310871298] 'agreement among raft nodes before linearized reading' (duration: 121.249112ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:17:10.452476Z","caller":"traceutil/trace.go:171","msg":"trace[578067967] transaction","detail":"{read_only:false; response_revision:29422; number_of_response:1; }","duration":"395.81624ms","start":"2026-10-26T20:17:10.056638Z","end":"2026-10-26T20:17:10.452455Z","steps":["trace[578067967] 'process raft request' (duration: 395.694476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:17:10.452799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:17:10.056617Z","time spent":"396.122707ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:17:10.455386Z","caller":"traceutil/trace.go:171","msg":"trace[74610553] transaction","detail":"{read_only:false; response_revision:29423; number_of_response:1; }","duration":"366.319626ms","start":"2026-10-26T20:17:10.088991Z","end":"2026-10-26T20:17:10.455311Z","steps":["trace[74610553] 'process raft request' (duration: 366.131711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:17:10.455556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:17:10.088969Z","time spent":"366.49011ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:17:10.455617Z","caller":"traceutil/trace.go:171","msg":"trace[1567458281] transaction","detail":"{read_only:false; response_revision:29424; number_of_response:1; }","duration":"289.603862ms","start":"2026-10-26T20:17:10.16599Z","end":"2026-10-26T20:17:10.455594Z","steps":["trace[1567458281] 'process raft request' (duration: 289.287814ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:17:10.45555Z","caller":"traceutil/trace.go:171","msg":"trace[1415322043] linearizableReadLoop","detail":"{readStateIndex:30368; appliedIndex:30366; }","duration":"331.656705ms","start":"2026-10-26T20:17:10.123859Z","end":"2026-10-26T20:17:10.455516Z","steps":["trace[1415322043] 'read index received' (duration: 328.502532ms)","trace[1415322043] 'applied index is now lower than readState.Index' (duration: 3.151393ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:17:10.455728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.86264ms","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-10-26T20:17:10.455852Z","caller":"traceutil/trace.go:171","msg":"trace[236857341] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:29424; }","duration":"332.023925ms","start":"2026-10-26T20:17:10.123816Z","end":"2026-10-26T20:17:10.45584Z","steps":["trace[236857341] 'agreement among raft nodes before linearized reading' (duration: 331.818889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:17:10.455782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.203045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:17:10.455999Z","caller":"traceutil/trace.go:171","msg":"trace[1565747966] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:29424; }","duration":"240.457491ms","start":"2026-10-26T20:17:10.215521Z","end":"2026-10-26T20:17:10.455978Z","steps":["trace[1565747966] 'agreement among raft nodes before linearized reading' (duration: 240.195164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:17:10.456313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:17:10.123798Z","time spent":"332.501798ms","remote":"127.0.0.1:52848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:19:42.200776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27951} {"level":"info","ts":"2026-10-26T20:19:42.353705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27951,"took":"148.385454ms","hash":666824615,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":34082816,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T20:19:42.353772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":666824615,"revision":27951,"compact-revision":25226} {"level":"info","ts":"2026-10-26T20:24:42.21248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30551} {"level":"info","ts":"2026-10-26T20:24:42.359628Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30551,"took":"143.176625ms","hash":1587945876,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":30720000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:24:42.359734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1587945876,"revision":30551,"compact-revision":27951} {"level":"info","ts":"2026-10-26T20:29:42.226628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32805} {"level":"info","ts":"2026-10-26T20:29:42.409632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32805,"took":"176.375568ms","hash":2821449788,"current-db-size-bytes":57917440,"current-db-size":"58 MB","current-db-size-in-use-bytes":30298112,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:29:42.409744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2821449788,"revision":32805,"compact-revision":30551} {"level":"info","ts":"2026-10-26T20:34:42.234763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35027} {"level":"info","ts":"2026-10-26T20:34:42.369422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35027,"took":"129.365412ms","hash":2133806278,"current-db-size-bytes":57917440,"current-db-size":"58 MB","current-db-size-in-use-bytes":30597120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:34:42.369472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2133806278,"revision":35027,"compact-revision":32805} {"level":"info","ts":"2026-10-26T20:35:10.554811Z","caller":"traceutil/trace.go:171","msg":"trace[416539289] transaction","detail":"{read_only:false; response_revision:37546; number_of_response:1; }","duration":"108.310088ms","start":"2026-10-26T20:35:10.446471Z","end":"2026-10-26T20:35:10.554781Z","steps":["trace[416539289] 'process raft request' (duration: 86.396882ms)","trace[416539289] 'compare' (duration: 21.426563ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:36:56.320586Z","caller":"traceutil/trace.go:171","msg":"trace[1881311077] transaction","detail":"{read_only:false; response_revision:38385; number_of_response:1; }","duration":"144.424028ms","start":"2026-10-26T20:36:56.176145Z","end":"2026-10-26T20:36:56.320569Z","steps":["trace[1881311077] 'process raft request' (duration: 144.307655ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:36:56.320609Z","caller":"traceutil/trace.go:171","msg":"trace[992646762] linearizableReadLoop","detail":"{readStateIndex:39586; appliedIndex:39585; }","duration":"143.205466ms","start":"2026-10-26T20:36:56.177361Z","end":"2026-10-26T20:36:56.320566Z","steps":["trace[992646762] 'read index received' (duration: 143.182415ms)","trace[992646762] 'applied index is now lower than readState.Index' (duration: 18.361µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:36:56.320842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.459892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:36:56.320889Z","caller":"traceutil/trace.go:171","msg":"trace[210319906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:38385; }","duration":"143.546555ms","start":"2026-10-26T20:36:56.177327Z","end":"2026-10-26T20:36:56.320873Z","steps":["trace[210319906] 'agreement among raft nodes before linearized reading' (duration: 143.383251ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:36:56.342755Z","caller":"traceutil/trace.go:171","msg":"trace[1814369183] transaction","detail":"{read_only:false; response_revision:38386; number_of_response:1; }","duration":"164.372172ms","start":"2026-10-26T20:36:56.178349Z","end":"2026-10-26T20:36:56.342722Z","steps":["trace[1814369183] 'process raft request' (duration: 164.159257ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:36:58.507537Z","caller":"traceutil/trace.go:171","msg":"trace[1126778600] transaction","detail":"{read_only:false; response_revision:38402; number_of_response:1; }","duration":"120.741005ms","start":"2026-10-26T20:36:58.386759Z","end":"2026-10-26T20:36:58.5075Z","steps":["trace[1126778600] 'process raft request' (duration: 43.913554ms)","trace[1126778600] 'compare' (duration: 76.629706ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:37:51.886317Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:37:51.89831Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T20:37:51.898427Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T20:39:42.252635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37290} {"level":"info","ts":"2026-10-26T20:39:42.390294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37290,"took":"134.837583ms","hash":541959278,"current-db-size-bytes":57917440,"current-db-size":"58 MB","current-db-size-in-use-bytes":32571392,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T20:39:42.390392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":541959278,"revision":37290,"compact-revision":35027} {"level":"info","ts":"2026-10-26T20:44:42.261652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39596} {"level":"info","ts":"2026-10-26T20:44:42.440646Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39596,"took":"176.041905ms","hash":722380606,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:44:42.44072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":722380606,"revision":39596,"compact-revision":37290} {"level":"info","ts":"2026-10-26T20:49:42.26898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41831} {"level":"info","ts":"2026-10-26T20:49:42.395626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41831,"took":"123.248648ms","hash":1219287135,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":29900800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:49:42.395719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1219287135,"revision":41831,"compact-revision":39596}