{"level":"warn","ts":"2026-09-11T15:22:17.469788Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-11T15:22:17.46992Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.118: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.118:2380","--initial-cluster=instance=https://162.253.55.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.118:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-09-11T15:22:17.470019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-11T15:22:17.470042Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.118:2380"]}
{"level":"info","ts":"2026-09-11T15:22:17.470081Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-09-11T15:22:17.471205Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"]}
{"level":"info","ts":"2026-09-11T15:22:17.471365Z","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.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.118:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-09-11T15:22:17.47598Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.243579ms"}
{"level":"info","ts":"2026-09-11T15:22:17.481234Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39c975536cfc1086","cluster-id":"ac0a95643fc422f0"}
{"level":"info","ts":"2026-09-11T15:22:17.481327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=()"}
{"level":"info","ts":"2026-09-11T15:22:17.481361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 0"}
{"level":"info","ts":"2026-09-11T15:22:17.481385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39c975536cfc1086 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-11T15:22:17.481407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 1"}
{"level":"info","ts":"2026-09-11T15:22:17.481458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"}
{"level":"warn","ts":"2026-09-11T15:22:17.493102Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-11T15:22:17.495048Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-11T15:22:17.496534Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-09-11T15:22:17.499471Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39c975536cfc1086","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-11T15:22:17.499826Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39c975536cfc1086","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-11T15:22:17.499847Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-11T15:22:17.500123Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-11T15:22:17.500192Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-11T15:22:17.500837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"}
{"level":"info","ts":"2026-09-11T15:22:17.50143Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","added-peer-id":"39c975536cfc1086","added-peer-peer-urls":["https://162.253.55.118:2380"]}
{"level":"info","ts":"2026-09-11T15:22:17.505277Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-09-11T15:22:17.505495Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.118:2380"}
{"level":"info","ts":"2026-09-11T15:22:17.505622Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.118:2380"}
{"level":"info","ts":"2026-09-11T15:22:17.505728Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39c975536cfc1086","initial-advertise-peer-urls":["https://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-11T15:22:17.505814Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-11T15:22:17.781764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 is starting a new election at term 1"}
{"level":"info","ts":"2026-09-11T15:22:17.781837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-11T15:22:17.781872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgPreVoteResp from 39c975536cfc1086 at term 1"}
{"level":"info","ts":"2026-09-11T15:22:17.781894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became candidate at term 2"}
{"level":"info","ts":"2026-09-11T15:22:17.781906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgVoteResp from 39c975536cfc1086 at term 2"}
{"level":"info","ts":"2026-09-11T15:22:17.781921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became leader at term 2"}
{"level":"info","ts":"2026-09-11T15:22:17.781935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39c975536cfc1086 elected leader 39c975536cfc1086 at term 2"}
{"level":"info","ts":"2026-09-11T15:22:17.78308Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39c975536cfc1086","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.118:2379]}","request-path":"/0/members/39c975536cfc1086/attributes","cluster-id":"ac0a95643fc422f0","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-11T15:22:17.783282Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-11T15:22:17.783389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-11T15:22:17.783497Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-11T15:22:17.7846Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-11T15:22:17.784727Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-11T15:22:17.784775Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-11T15:22:17.786609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-11T15:22:17.786761Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-11T15:22:17.786802Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-11T15:22:17.787039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.118:2379"}
{"level":"info","ts":"2026-09-11T15:22:19.749295Z","caller":"traceutil/trace.go:171","msg":"trace[2113194671] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"101.801153ms","start":"2026-09-11T15:22:19.647464Z","end":"2026-09-11T15:22:19.749265Z","steps":["trace[2113194671] 'process raft request'  (duration: 101.718061ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:22:19.749542Z","caller":"traceutil/trace.go:171","msg":"trace[580307006] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"102.13116ms","start":"2026-09-11T15:22:19.647272Z","end":"2026-09-11T15:22:19.749403Z","steps":["trace[580307006] 'process raft request'  (duration: 94.840023ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:22:36.140032Z","caller":"traceutil/trace.go:171","msg":"trace[1655378270] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"104.487754ms","start":"2026-09-11T15:22:36.035516Z","end":"2026-09-11T15:22:36.140004Z","steps":["trace[1655378270] 'process raft request'  (duration: 104.317569ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:23:05.068641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.2417ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-11T15:23:05.068881Z","caller":"traceutil/trace.go:171","msg":"trace[1046482024] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:599; }","duration":"197.524226ms","start":"2026-09-11T15:23:04.871324Z","end":"2026-09-11T15:23:05.068848Z","steps":["trace[1046482024] 'range keys from in-memory index tree'  (duration: 197.186469ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:23:05.069737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.854304ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384560665 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:585 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-11T15:23:05.069908Z","caller":"traceutil/trace.go:171","msg":"trace[530026122] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"316.929669ms","start":"2026-09-11T15:23:04.752949Z","end":"2026-09-11T15:23:05.069879Z","steps":["trace[530026122] 'process raft request'  (duration: 141.894567ms)","trace[530026122] 'compare'  (duration: 173.604587ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:23:05.069988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:23:04.752934Z","time spent":"317.019601ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:585 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-09-11T15:23:05.147166Z","caller":"traceutil/trace.go:171","msg":"trace[1725319171] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"323.287195ms","start":"2026-09-11T15:23:04.823845Z","end":"2026-09-11T15:23:05.147133Z","steps":["trace[1725319171] 'process raft request'  (duration: 322.556427ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:23:05.147374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:23:04.823794Z","time spent":"323.48257ms","remote":"127.0.0.1:39046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036253,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:595 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036169 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"warn","ts":"2026-09-11T15:23:05.767764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1190815696384560664,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-09-11T15:23:05.792113Z","caller":"traceutil/trace.go:171","msg":"trace[2138431698] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"544.403974ms","start":"2026-09-11T15:23:05.247676Z","end":"2026-09-11T15:23:05.79208Z","steps":["trace[2138431698] 'process raft request'  (duration: 544.25099ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:23:05.792245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:23:05.24764Z","time spent":"544.515326ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" value_size:994 >> failure:<>"}
{"level":"info","ts":"2026-09-11T15:23:05.792907Z","caller":"traceutil/trace.go:171","msg":"trace[415832120] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:626; }","duration":"525.476667ms","start":"2026-09-11T15:23:05.267352Z","end":"2026-09-11T15:23:05.792828Z","steps":["trace[415832120] 'read index received'  (duration: 525.461577ms)","trace[415832120] 'applied index is now lower than readState.Index'  (duration: 11.97µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:23:05.793103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.713144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n2s9z\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-09-11T15:23:05.793213Z","caller":"traceutil/trace.go:171","msg":"trace[1490898256] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n2s9z; range_end:; response_count:1; response_revision:602; }","duration":"525.860806ms","start":"2026-09-11T15:23:05.267308Z","end":"2026-09-11T15:23:05.793169Z","steps":["trace[1490898256] 'agreement among raft nodes before linearized reading'  (duration: 525.61351ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:23:05.793298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:23:05.267294Z","time spent":"525.98962ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-n2s9z\" "}
{"level":"info","ts":"2026-09-11T15:23:22.993001Z","caller":"traceutil/trace.go:171","msg":"trace[1107882612] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"158.68921ms","start":"2026-09-11T15:23:22.834288Z","end":"2026-09-11T15:23:22.992977Z","steps":["trace[1107882612] 'read index received'  (duration: 158.562557ms)","trace[1107882612] 'applied index is now lower than readState.Index'  (duration: 125.263µs)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:23:22.993069Z","caller":"traceutil/trace.go:171","msg":"trace[1305332782] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"161.006177ms","start":"2026-09-11T15:23:22.832029Z","end":"2026-09-11T15:23:22.993035Z","steps":["trace[1305332782] 'process raft request'  (duration: 160.743371ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:23:22.993262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.942406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-11T15:23:22.993369Z","caller":"traceutil/trace.go:171","msg":"trace[414623228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"159.07843ms","start":"2026-09-11T15:23:22.834262Z","end":"2026-09-11T15:23:22.99334Z","steps":["trace[414623228] 'agreement among raft nodes before linearized reading'  (duration: 158.884346ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:24:44.62722Z","caller":"traceutil/trace.go:171","msg":"trace[1006243474] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"133.485626ms","start":"2026-09-11T15:24:44.493702Z","end":"2026-09-11T15:24:44.627188Z","steps":["trace[1006243474] 'process raft request'  (duration: 133.29218ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:24:45.073089Z","caller":"traceutil/trace.go:171","msg":"trace[296152869] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1946; }","duration":"212.113349ms","start":"2026-09-11T15:24:44.860951Z","end":"2026-09-11T15:24:45.073064Z","steps":["trace[296152869] 'read index received'  (duration: 26.683592ms)","trace[296152869] 'applied index is now lower than readState.Index'  (duration: 185.428637ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:24:45.073174Z","caller":"traceutil/trace.go:171","msg":"trace[130070880] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"225.542522ms","start":"2026-09-11T15:24:44.847584Z","end":"2026-09-11T15:24:45.073127Z","steps":["trace[130070880] 'process raft request'  (duration: 225.335737ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:24:45.073352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.987709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-09-11T15:24:45.073397Z","caller":"traceutil/trace.go:171","msg":"trace[1483476312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1872; }","duration":"177.04985ms","start":"2026-09-11T15:24:44.896335Z","end":"2026-09-11T15:24:45.073385Z","steps":["trace[1483476312] 'agreement among raft nodes before linearized reading'  (duration: 176.885496ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:24:45.07341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.414046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-09-11T15:24:45.073661Z","caller":"traceutil/trace.go:171","msg":"trace[1972702488] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1872; }","duration":"212.708375ms","start":"2026-09-11T15:24:44.860927Z","end":"2026-09-11T15:24:45.073635Z","steps":["trace[1972702488] 'agreement among raft nodes before linearized reading'  (duration: 212.267393ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:24:51.621594Z","caller":"traceutil/trace.go:171","msg":"trace[1030493837] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"109.195385ms","start":"2026-09-11T15:24:51.512368Z","end":"2026-09-11T15:24:51.621563Z","steps":["trace[1030493837] 'process raft request'  (duration: 106.672852ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:24:53.26867Z","caller":"traceutil/trace.go:171","msg":"trace[734255410] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"114.012538ms","start":"2026-09-11T15:24:53.154636Z","end":"2026-09-11T15:24:53.268648Z","steps":["trace[734255410] 'process raft request'  (duration: 113.873034ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:24:57.914243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.02536ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384563401 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:1965 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-11T15:24:57.914358Z","caller":"traceutil/trace.go:171","msg":"trace[143437114] linearizableReadLoop","detail":"{readStateIndex:2055; appliedIndex:2054; }","duration":"269.916028ms","start":"2026-09-11T15:24:57.644421Z","end":"2026-09-11T15:24:57.914337Z","steps":["trace[143437114] 'read index received'  (duration: 40.971µs)","trace[143437114] 'applied index is now lower than readState.Index'  (duration: 269.872887ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:24:57.914446Z","caller":"traceutil/trace.go:171","msg":"trace[201746359] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"389.822348ms","start":"2026-09-11T15:24:57.524596Z","end":"2026-09-11T15:24:57.914418Z","steps":["trace[201746359] 'process raft request'  (duration: 96.46557ms)","trace[201746359] 'compare'  (duration: 292.866375ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:24:57.914567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:24:57.52457Z","time spent":"389.92975ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:1965 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-09-11T15:24:57.914577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.156134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-09-11T15:24:57.914647Z","caller":"traceutil/trace.go:171","msg":"trace[1210013858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1976; }","duration":"270.255716ms","start":"2026-09-11T15:24:57.644376Z","end":"2026-09-11T15:24:57.914632Z","steps":["trace[1210013858] 'agreement among raft nodes before linearized reading'  (duration: 270.068321ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:24:57.91504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.01034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-09-11T15:24:57.915098Z","caller":"traceutil/trace.go:171","msg":"trace[2063742990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1976; }","duration":"109.100732ms","start":"2026-09-11T15:24:57.805983Z","end":"2026-09-11T15:24:57.915084Z","steps":["trace[2063742990] 'agreement among raft nodes before linearized reading'  (duration: 108.948017ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:24:57.915105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.113263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-11T15:24:57.915231Z","caller":"traceutil/trace.go:171","msg":"trace[1359184780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1976; }","duration":"259.298967ms","start":"2026-09-11T15:24:57.655908Z","end":"2026-09-11T15:24:57.915206Z","steps":["trace[1359184780] 'agreement among raft nodes before linearized reading'  (duration: 258.962848ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:24:57.915277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.23507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-snjg6\" ","response":"range_response_count:1 size:5855"}
{"level":"info","ts":"2026-09-11T15:24:57.915372Z","caller":"traceutil/trace.go:171","msg":"trace[414908598] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-snjg6; range_end:; response_count:1; response_revision:1976; }","duration":"235.393545ms","start":"2026-09-11T15:24:57.679953Z","end":"2026-09-11T15:24:57.915347Z","steps":["trace[414908598] 'agreement among raft nodes before linearized reading'  (duration: 234.979893ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:25:35.259863Z","caller":"traceutil/trace.go:171","msg":"trace[212713413] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"178.094199ms","start":"2026-09-11T15:25:35.08173Z","end":"2026-09-11T15:25:35.259824Z","steps":["trace[212713413] 'process raft request'  (duration: 177.944685ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:25:35.916894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.161106ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384563923 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:2187 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-11T15:25:35.917019Z","caller":"traceutil/trace.go:171","msg":"trace[1157217460] linearizableReadLoop","detail":"{readStateIndex:2283; appliedIndex:2282; }","duration":"357.754836ms","start":"2026-09-11T15:25:35.559244Z","end":"2026-09-11T15:25:35.916999Z","steps":["trace[1157217460] 'read index received'  (duration: 153.322903ms)","trace[1157217460] 'applied index is now lower than readState.Index'  (duration: 204.430343ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:25:35.917169Z","caller":"traceutil/trace.go:171","msg":"trace[1451073112] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"360.017645ms","start":"2026-09-11T15:25:35.557105Z","end":"2026-09-11T15:25:35.917122Z","steps":["trace[1451073112] 'process raft request'  (duration: 155.529181ms)","trace[1451073112] 'compare'  (duration: 204.040182ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:25:35.917234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.554793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-11T15:25:35.917259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:25:35.557086Z","time spent":"360.129747ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:2187 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-11T15:25:35.917284Z","caller":"traceutil/trace.go:171","msg":"trace[1236623210] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2196; }","duration":"350.635184ms","start":"2026-09-11T15:25:35.566636Z","end":"2026-09-11T15:25:35.917271Z","steps":["trace[1236623210] 'agreement among raft nodes before linearized reading'  (duration: 350.549982ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:25:35.917314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:25:35.566623Z","time spent":"350.681186ms","remote":"127.0.0.1:34720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "}
{"level":"warn","ts":"2026-09-11T15:25:35.917329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.083494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-09-11T15:25:35.917391Z","caller":"traceutil/trace.go:171","msg":"trace[683249734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2196; }","duration":"358.175408ms","start":"2026-09-11T15:25:35.5592Z","end":"2026-09-11T15:25:35.917375Z","steps":["trace[683249734] 'agreement among raft nodes before linearized reading'  (duration: 358.000594ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:25:35.917431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:25:35.559184Z","time spent":"358.236429ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-09-11T15:25:41.605811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.617331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-09-11T15:25:41.605904Z","caller":"traceutil/trace.go:171","msg":"trace[814804794] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2225; }","duration":"119.787225ms","start":"2026-09-11T15:25:41.486094Z","end":"2026-09-11T15:25:41.605881Z","steps":["trace[814804794] 'range keys from in-memory index tree'  (duration: 119.319354ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:26:31.957931Z","caller":"traceutil/trace.go:171","msg":"trace[1599916607] linearizableReadLoop","detail":"{readStateIndex:2776; appliedIndex:2775; }","duration":"123.498613ms","start":"2026-09-11T15:26:31.834407Z","end":"2026-09-11T15:26:31.957905Z","steps":["trace[1599916607] 'read index received'  (duration: 123.305978ms)","trace[1599916607] 'applied index is now lower than readState.Index'  (duration: 191.755µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:26:31.95818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.742658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-11T15:26:31.958269Z","caller":"traceutil/trace.go:171","msg":"trace[1089849630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2669; }","duration":"123.916352ms","start":"2026-09-11T15:26:31.83433Z","end":"2026-09-11T15:26:31.958247Z","steps":["trace[1089849630] 'agreement among raft nodes before linearized reading'  (duration: 123.751358ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:26:31.958253Z","caller":"traceutil/trace.go:171","msg":"trace[1427285910] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"154.262527ms","start":"2026-09-11T15:26:31.803953Z","end":"2026-09-11T15:26:31.958215Z","steps":["trace[1427285910] 'process raft request'  (duration: 153.819826ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:27:04.882228Z","caller":"traceutil/trace.go:171","msg":"trace[914884027] linearizableReadLoop","detail":"{readStateIndex:2982; appliedIndex:2981; }","duration":"194.464459ms","start":"2026-09-11T15:27:04.68774Z","end":"2026-09-11T15:27:04.882204Z","steps":["trace[914884027] 'read index received'  (duration: 194.231663ms)","trace[914884027] 'applied index is now lower than readState.Index'  (duration: 232.086µs)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:27:04.882291Z","caller":"traceutil/trace.go:171","msg":"trace[1007699773] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"194.699115ms","start":"2026-09-11T15:27:04.687574Z","end":"2026-09-11T15:27:04.882273Z","steps":["trace[1007699773] 'process raft request'  (duration: 194.483159ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:27:04.882519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.49985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"warn","ts":"2026-09-11T15:27:04.882446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.681045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-09-11T15:27:04.882549Z","caller":"traceutil/trace.go:171","msg":"trace[405988283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2869; }","duration":"189.54106ms","start":"2026-09-11T15:27:04.693Z","end":"2026-09-11T15:27:04.882541Z","steps":["trace[405988283] 'agreement among raft nodes before linearized reading'  (duration: 189.476429ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:27:04.882571Z","caller":"traceutil/trace.go:171","msg":"trace[1062685213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2869; }","duration":"194.815639ms","start":"2026-09-11T15:27:04.687738Z","end":"2026-09-11T15:27:04.882553Z","steps":["trace[1062685213] 'agreement among raft nodes before linearized reading'  (duration: 194.570242ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:27:05.406355Z","caller":"traceutil/trace.go:171","msg":"trace[1851399382] transaction","detail":"{read_only:false; response_revision:2872; number_of_response:1; }","duration":"113.961695ms","start":"2026-09-11T15:27:05.292366Z","end":"2026-09-11T15:27:05.406327Z","steps":["trace[1851399382] 'process raft request'  (duration: 113.796361ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:27:10.58466Z","caller":"traceutil/trace.go:171","msg":"trace[1704826221] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"107.028743ms","start":"2026-09-11T15:27:10.477582Z","end":"2026-09-11T15:27:10.584611Z","steps":["trace[1704826221] 'process raft request'  (duration: 106.858559ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:29:48.616821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.789043ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384568128 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/replicasets/secretgen-controller/secretgen-controller-5cf976ccc7\" mod_revision:3880 > success:<request_put:<key:\"/registry/replicasets/secretgen-controller/secretgen-controller-5cf976ccc7\" value_size:2775 >> failure:<request_range:<key:\"/registry/replicasets/secretgen-controller/secretgen-controller-5cf976ccc7\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-11T15:29:48.617066Z","caller":"traceutil/trace.go:171","msg":"trace[1681753342] transaction","detail":"{read_only:false; response_revision:3887; number_of_response:1; }","duration":"124.78687ms","start":"2026-09-11T15:29:48.492257Z","end":"2026-09-11T15:29:48.617044Z","steps":["trace[1681753342] 'process raft request'  (duration: 124.695328ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:29:48.617085Z","caller":"traceutil/trace.go:171","msg":"trace[1966771747] transaction","detail":"{read_only:false; response_revision:3886; number_of_response:1; }","duration":"125.913209ms","start":"2026-09-11T15:29:48.491134Z","end":"2026-09-11T15:29:48.617047Z","steps":["trace[1966771747] 'compare'  (duration: 121.601149ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:30:23.650997Z","caller":"traceutil/trace.go:171","msg":"trace[1835392223] transaction","detail":"{read_only:false; response_revision:4116; number_of_response:1; }","duration":"242.75946ms","start":"2026-09-11T15:30:23.408208Z","end":"2026-09-11T15:30:23.650967Z","steps":["trace[1835392223] 'process raft request'  (duration: 242.466232ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:31:58.075976Z","caller":"traceutil/trace.go:171","msg":"trace[1519535861] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"113.303999ms","start":"2026-09-11T15:31:57.962637Z","end":"2026-09-11T15:31:58.075941Z","steps":["trace[1519535861] 'process raft request'  (duration: 113.077224ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:31:58.2983Z","caller":"traceutil/trace.go:171","msg":"trace[1009317243] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5531; }","duration":"239.647651ms","start":"2026-09-11T15:31:58.058616Z","end":"2026-09-11T15:31:58.298263Z","steps":["trace[1009317243] 'read index received'  (duration: 17.156448ms)","trace[1009317243] 'applied index is now lower than readState.Index'  (duration: 222.489813ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:31:58.298459Z","caller":"traceutil/trace.go:171","msg":"trace[1832307637] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"254.021757ms","start":"2026-09-11T15:31:58.044375Z","end":"2026-09-11T15:31:58.298397Z","steps":["trace[1832307637] 'process raft request'  (duration: 219.229ms)","trace[1832307637] 'compare'  (duration: 34.049228ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:31:58.298705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.227746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b04afbab-d8c8-40d3-bd0e-08b5d92c9eee\" ","response":"range_response_count:1 size:3601"}
{"level":"info","ts":"2026-09-11T15:31:58.298778Z","caller":"traceutil/trace.go:171","msg":"trace[357079440] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b04afbab-d8c8-40d3-bd0e-08b5d92c9eee; range_end:; response_count:1; response_revision:5326; }","duration":"131.37757ms","start":"2026-09-11T15:31:58.167379Z","end":"2026-09-11T15:31:58.298757Z","steps":["trace[357079440] 'agreement among raft nodes before linearized reading'  (duration: 131.103912ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:31:58.299086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.431841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728719"}
{"level":"info","ts":"2026-09-11T15:31:58.299207Z","caller":"traceutil/trace.go:171","msg":"trace[1778296470] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5326; }","duration":"240.619105ms","start":"2026-09-11T15:31:58.058562Z","end":"2026-09-11T15:31:58.299181Z","steps":["trace[1778296470] 'agreement among raft nodes before linearized reading'  (duration: 239.891917ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:32:00.473067Z","caller":"traceutil/trace.go:171","msg":"trace[2001246028] linearizableReadLoop","detail":"{readStateIndex:5573; appliedIndex:5572; }","duration":"138.464991ms","start":"2026-09-11T15:32:00.33457Z","end":"2026-09-11T15:32:00.473035Z","steps":["trace[2001246028] 'read index received'  (duration: 76.432719ms)","trace[2001246028] 'applied index is now lower than readState.Index'  (duration: 62.031172ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:32:00.473434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.82746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-11T15:32:00.473452Z","caller":"traceutil/trace.go:171","msg":"trace[117536878] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"169.546494ms","start":"2026-09-11T15:32:00.303858Z","end":"2026-09-11T15:32:00.473404Z","steps":["trace[117536878] 'process raft request'  (duration: 107.107881ms)","trace[117536878] 'compare'  (duration: 61.934329ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:32:00.473497Z","caller":"traceutil/trace.go:171","msg":"trace[1038264962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5365; }","duration":"138.975494ms","start":"2026-09-11T15:32:00.334504Z","end":"2026-09-11T15:32:00.47348Z","steps":["trace[1038264962] 'agreement among raft nodes before linearized reading'  (duration: 138.688426ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:32:17.860666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2942}
{"level":"info","ts":"2026-09-11T15:32:17.944349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2942,"took":"81.957388ms","hash":1609485229,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-11T15:32:17.944429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1609485229,"revision":2942,"compact-revision":-1}
{"level":"info","ts":"2026-09-11T15:37:17.86835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5683}
{"level":"info","ts":"2026-09-11T15:37:17.964963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5683,"took":"94.239181ms","hash":3375966041,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":26873856,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-09-11T15:37:17.965055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3375966041,"revision":5683,"compact-revision":2942}
{"level":"info","ts":"2026-09-11T15:38:41.166568Z","caller":"traceutil/trace.go:171","msg":"trace[1145293941] linearizableReadLoop","detail":"{readStateIndex:8979; appliedIndex:8978; }","duration":"313.88187ms","start":"2026-09-11T15:38:40.852652Z","end":"2026-09-11T15:38:41.166534Z","steps":["trace[1145293941] 'read index received'  (duration: 313.760486ms)","trace[1145293941] 'applied index is now lower than readState.Index'  (duration: 120.214µs)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:38:41.166633Z","caller":"traceutil/trace.go:171","msg":"trace[333971020] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"340.780617ms","start":"2026-09-11T15:38:40.825802Z","end":"2026-09-11T15:38:41.166583Z","steps":["trace[333971020] 'process raft request'  (duration: 340.557531ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:38:41.166832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.144806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-11T15:38:41.166869Z","caller":"traceutil/trace.go:171","msg":"trace[1066034264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8669; }","duration":"314.266579ms","start":"2026-09-11T15:38:40.852588Z","end":"2026-09-11T15:38:41.166855Z","steps":["trace[1066034264] 'agreement among raft nodes before linearized reading'  (duration: 314.096465ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:38:41.166908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:38:40.852565Z","time spent":"314.336371ms","remote":"127.0.0.1:39238","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-09-11T15:38:41.166898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:38:40.825783Z","time spent":"340.948052ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:8658 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-09-11T15:38:41.362315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.911102ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384579876 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:8663 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:476 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-11T15:38:41.3627Z","caller":"traceutil/trace.go:171","msg":"trace[1218990232] linearizableReadLoop","detail":"{readStateIndex:8981; appliedIndex:8979; }","duration":"196.016704ms","start":"2026-09-11T15:38:41.166652Z","end":"2026-09-11T15:38:41.362668Z","steps":["trace[1218990232] 'read index received'  (duration: 92.531927ms)","trace[1218990232] 'applied index is now lower than readState.Index'  (duration: 103.482817ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:38:41.362744Z","caller":"traceutil/trace.go:171","msg":"trace[1391464309] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"244.856005ms","start":"2026-09-11T15:38:41.117824Z","end":"2026-09-11T15:38:41.36268Z","steps":["trace[1391464309] 'process raft request'  (duration: 244.613618ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:38:41.362862Z","caller":"traceutil/trace.go:171","msg":"trace[1555889568] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"245.701875ms","start":"2026-09-11T15:38:41.117145Z","end":"2026-09-11T15:38:41.362847Z","steps":["trace[1555889568] 'process raft request'  (duration: 142.139545ms)","trace[1555889568] 'compare'  (duration: 102.759629ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:38:41.363129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.601947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-09-11T15:38:41.36325Z","caller":"traceutil/trace.go:171","msg":"trace[1762407909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8671; }","duration":"486.801052ms","start":"2026-09-11T15:38:40.87642Z","end":"2026-09-11T15:38:41.363221Z","steps":["trace[1762407909] 'agreement among raft nodes before linearized reading'  (duration: 486.351731ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:38:41.363303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:38:40.876395Z","time spent":"486.899015ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-09-11T15:38:41.383386Z","caller":"traceutil/trace.go:171","msg":"trace[2102115413] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"211.226383ms","start":"2026-09-11T15:38:41.172131Z","end":"2026-09-11T15:38:41.383357Z","steps":["trace[2102115413] 'process raft request'  (duration: 210.994488ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:38:51.751218Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002352.wal"}
{"level":"warn","ts":"2026-09-11T15:38:53.773558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.178873ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384580047 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:8724 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-11T15:38:53.773706Z","caller":"traceutil/trace.go:171","msg":"trace[2140847064] linearizableReadLoop","detail":"{readStateIndex:9048; appliedIndex:9046; }","duration":"335.077916ms","start":"2026-09-11T15:38:53.438602Z","end":"2026-09-11T15:38:53.77368Z","steps":["trace[2140847064] 'read index received'  (duration: 48.493209ms)","trace[2140847064] 'applied index is now lower than readState.Index'  (duration: 286.583237ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:38:53.77381Z","caller":"traceutil/trace.go:171","msg":"trace[1189567587] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"357.547121ms","start":"2026-09-11T15:38:53.416219Z","end":"2026-09-11T15:38:53.773766Z","steps":["trace[1189567587] 'process raft request'  (duration: 111.031939ms)","trace[1189567587] 'compare'  (duration: 245.963418ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:38:53.77401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:38:53.416185Z","time spent":"357.712485ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:8724 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-11T15:38:53.774008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.376074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-11T15:38:53.774134Z","caller":"traceutil/trace.go:171","msg":"trace[112506309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8735; }","duration":"335.562829ms","start":"2026-09-11T15:38:53.438545Z","end":"2026-09-11T15:38:53.774108Z","steps":["trace[112506309] 'agreement among raft nodes before linearized reading'  (duration: 335.239201ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:38:53.774186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:38:53.438527Z","time spent":"335.6491ms","remote":"127.0.0.1:39238","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-09-11T15:38:53.774191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.918312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-09-11T15:38:53.774236Z","caller":"traceutil/trace.go:171","msg":"trace[52288695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8735; }","duration":"335.012474ms","start":"2026-09-11T15:38:53.43921Z","end":"2026-09-11T15:38:53.774223Z","steps":["trace[52288695] 'agreement among raft nodes before linearized reading'  (duration: 334.599344ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:38:53.774282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:38:53.439183Z","time spent":"335.089237ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-09-11T15:38:53.77457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.281297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jc4h9\" ","response":"range_response_count:1 size:6451"}
{"level":"info","ts":"2026-09-11T15:38:53.774636Z","caller":"traceutil/trace.go:171","msg":"trace[1644240204] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jc4h9; range_end:; response_count:1; response_revision:8735; }","duration":"282.38161ms","start":"2026-09-11T15:38:53.492239Z","end":"2026-09-11T15:38:53.774621Z","steps":["trace[1644240204] 'agreement among raft nodes before linearized reading'  (duration: 281.706113ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:38:53.774578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.127668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-09-11T15:38:53.774857Z","caller":"traceutil/trace.go:171","msg":"trace[1051881474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8735; }","duration":"326.460196ms","start":"2026-09-11T15:38:53.448376Z","end":"2026-09-11T15:38:53.774837Z","steps":["trace[1051881474] 'agreement among raft nodes before linearized reading'  (duration: 325.536743ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:38:53.774985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:38:53.44834Z","time spent":"326.629111ms","remote":"127.0.0.1:39238","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-09-11T15:38:54.539611Z","caller":"traceutil/trace.go:171","msg":"trace[484305426] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"131.105483ms","start":"2026-09-11T15:38:54.408467Z","end":"2026-09-11T15:38:54.539572Z","steps":["trace[484305426] 'process raft request'  (duration: 130.886278ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:39:24.165711Z","caller":"traceutil/trace.go:171","msg":"trace[1044755037] linearizableReadLoop","detail":"{readStateIndex:9273; appliedIndex:9272; }","duration":"217.310995ms","start":"2026-09-11T15:39:23.948271Z","end":"2026-09-11T15:39:24.165582Z","steps":["trace[1044755037] 'read index received'  (duration: 217.063779ms)","trace[1044755037] 'applied index is now lower than readState.Index'  (duration: 245.416µs)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:39:24.166187Z","caller":"traceutil/trace.go:171","msg":"trace[2030789025] transaction","detail":"{read_only:false; response_revision:8952; number_of_response:1; }","duration":"274.145579ms","start":"2026-09-11T15:39:23.892025Z","end":"2026-09-11T15:39:24.166171Z","steps":["trace[2030789025] 'process raft request'  (duration: 273.382569ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:39:24.166711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.415443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-09-11T15:39:24.166955Z","caller":"traceutil/trace.go:171","msg":"trace[43888076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8952; }","duration":"218.741363ms","start":"2026-09-11T15:39:23.948199Z","end":"2026-09-11T15:39:24.166941Z","steps":["trace[43888076] 'agreement among raft nodes before linearized reading'  (duration: 218.191648ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:39:24.166985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.86685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-11T15:39:24.167072Z","caller":"traceutil/trace.go:171","msg":"trace[543570609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8952; }","duration":"217.931672ms","start":"2026-09-11T15:39:23.949095Z","end":"2026-09-11T15:39:24.167027Z","steps":["trace[543570609] 'agreement among raft nodes before linearized reading'  (duration: 217.770518ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:39:24.167185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.901188ms","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-09-11T15:39:24.167164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.232372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jc4h9\" ","response":"range_response_count:1 size:6451"}
{"level":"info","ts":"2026-09-11T15:39:24.167218Z","caller":"traceutil/trace.go:171","msg":"trace[552390988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8952; }","duration":"204.961299ms","start":"2026-09-11T15:39:23.962247Z","end":"2026-09-11T15:39:24.167208Z","steps":["trace[552390988] 'agreement among raft nodes before linearized reading'  (duration: 204.863177ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:39:24.167266Z","caller":"traceutil/trace.go:171","msg":"trace[597935192] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jc4h9; range_end:; response_count:1; response_revision:8952; }","duration":"188.401156ms","start":"2026-09-11T15:39:23.978842Z","end":"2026-09-11T15:39:24.167243Z","steps":["trace[597935192] 'agreement among raft nodes before linearized reading'  (duration: 188.036427ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:39:24.404514Z","caller":"traceutil/trace.go:171","msg":"trace[1685928434] transaction","detail":"{read_only:false; response_revision:8953; number_of_response:1; }","duration":"233.345465ms","start":"2026-09-11T15:39:24.17113Z","end":"2026-09-11T15:39:24.404475Z","steps":["trace[1685928434] 'process raft request'  (duration: 226.426369ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:39:24.420866Z","caller":"traceutil/trace.go:171","msg":"trace[93744285] linearizableReadLoop","detail":"{readStateIndex:9275; appliedIndex:9273; }","duration":"248.604086ms","start":"2026-09-11T15:39:24.172234Z","end":"2026-09-11T15:39:24.420838Z","steps":["trace[93744285] 'read index received'  (duration: 225.358432ms)","trace[93744285] 'applied index is now lower than readState.Index'  (duration: 23.244284ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:39:24.420973Z","caller":"traceutil/trace.go:171","msg":"trace[1008187660] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"247.799846ms","start":"2026-09-11T15:39:24.173121Z","end":"2026-09-11T15:39:24.420921Z","steps":["trace[1008187660] 'process raft request'  (duration: 247.648872ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:39:24.420921Z","caller":"traceutil/trace.go:171","msg":"trace[1434443467] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"248.860143ms","start":"2026-09-11T15:39:24.172017Z","end":"2026-09-11T15:39:24.420877Z","steps":["trace[1434443467] 'process raft request'  (duration: 248.528054ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:39:24.421411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.14708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:227513"}
{"level":"info","ts":"2026-09-11T15:39:24.421461Z","caller":"traceutil/trace.go:171","msg":"trace[468258911] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8955; }","duration":"249.247062ms","start":"2026-09-11T15:39:24.172203Z","end":"2026-09-11T15:39:24.42145Z","steps":["trace[468258911] 'agreement among raft nodes before linearized reading'  (duration: 248.74541ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:39:26.537788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.667324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-11T15:39:26.537904Z","caller":"traceutil/trace.go:171","msg":"trace[1227018604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8962; }","duration":"111.88316ms","start":"2026-09-11T15:39:26.425996Z","end":"2026-09-11T15:39:26.537879Z","steps":["trace[1227018604] 'range keys from in-memory index tree'  (duration: 111.445959ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:39:26.5379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.359871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"warn","ts":"2026-09-11T15:39:26.53793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.794193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-09-11T15:39:26.537997Z","caller":"traceutil/trace.go:171","msg":"trace[287721127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8962; }","duration":"128.518285ms","start":"2026-09-11T15:39:26.409452Z","end":"2026-09-11T15:39:26.53797Z","steps":["trace[287721127] 'range keys from in-memory index tree'  (duration: 128.148216ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:39:26.53807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.816426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jc4h9\" ","response":"range_response_count:1 size:6451"}
{"level":"info","ts":"2026-09-11T15:39:26.538135Z","caller":"traceutil/trace.go:171","msg":"trace[2133640737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8962; }","duration":"112.923936ms","start":"2026-09-11T15:39:26.425076Z","end":"2026-09-11T15:39:26.538Z","steps":["trace[2133640737] 'range keys from in-memory index tree'  (duration: 112.647089ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:39:26.53817Z","caller":"traceutil/trace.go:171","msg":"trace[2071542784] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jc4h9; range_end:; response_count:1; response_revision:8962; }","duration":"107.97038ms","start":"2026-09-11T15:39:26.430169Z","end":"2026-09-11T15:39:26.53814Z","steps":["trace[2071542784] 'range keys from in-memory index tree'  (duration: 107.541829ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:39:26.538156Z","caller":"traceutil/trace.go:171","msg":"trace[1572259563] transaction","detail":"{read_only:false; response_revision:8963; number_of_response:1; }","duration":"104.419239ms","start":"2026-09-11T15:39:26.433697Z","end":"2026-09-11T15:39:26.538116Z","steps":["trace[1572259563] 'process raft request'  (duration: 85.330621ms)","trace[1572259563] 'compare'  (duration: 18.409681ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:39:37.460705Z","caller":"traceutil/trace.go:171","msg":"trace[754420561] linearizableReadLoop","detail":"{readStateIndex:9358; appliedIndex:9357; }","duration":"172.645914ms","start":"2026-09-11T15:39:37.288028Z","end":"2026-09-11T15:39:37.460674Z","steps":["trace[754420561] 'read index received'  (duration: 109.815048ms)","trace[754420561] 'applied index is now lower than readState.Index'  (duration: 62.830066ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:39:37.460931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.87439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qr8m6\" ","response":"range_response_count:1 size:4648"}
{"level":"info","ts":"2026-09-11T15:39:37.460915Z","caller":"traceutil/trace.go:171","msg":"trace[28841040] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9034; }","duration":"237.681716ms","start":"2026-09-11T15:39:37.223199Z","end":"2026-09-11T15:39:37.46088Z","steps":["trace[28841040] 'process raft request'  (duration: 174.645935ms)","trace[28841040] 'compare'  (duration: 62.709133ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:39:37.460968Z","caller":"traceutil/trace.go:171","msg":"trace[993425674] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qr8m6; range_end:; response_count:1; response_revision:9034; }","duration":"172.970372ms","start":"2026-09-11T15:39:37.287984Z","end":"2026-09-11T15:39:37.460955Z","steps":["trace[993425674] 'agreement among raft nodes before linearized reading'  (duration: 172.795388ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:40:38.557691Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39c975536cfc1086","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-11T15:40:38.570785Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-09-11T15:40:38.571169Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"warn","ts":"2026-09-11T15:41:06.274058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.562444ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384583313 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:9871 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:463 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-11T15:41:06.274219Z","caller":"traceutil/trace.go:171","msg":"trace[2138157531] transaction","detail":"{read_only:false; response_revision:9880; number_of_response:1; }","duration":"193.604134ms","start":"2026-09-11T15:41:06.080586Z","end":"2026-09-11T15:41:06.274191Z","steps":["trace[2138157531] 'process raft request'  (duration: 89.747213ms)","trace[2138157531] 'compare'  (duration: 103.195184ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:42:17.884113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8000}
{"level":"info","ts":"2026-09-11T15:42:17.955785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8000,"took":"69.356774ms","hash":191817319,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":28721152,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-09-11T15:42:17.955873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":191817319,"revision":8000,"compact-revision":5683}
{"level":"info","ts":"2026-09-11T15:46:50.644892Z","caller":"traceutil/trace.go:171","msg":"trace[666481876] transaction","detail":"{read_only:false; response_revision:12342; number_of_response:1; }","duration":"102.231327ms","start":"2026-09-11T15:46:50.542618Z","end":"2026-09-11T15:46:50.64485Z","steps":["trace[666481876] 'process raft request'  (duration: 79.615808ms)","trace[666481876] 'compare'  (duration: 21.8998ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:46:51.234388Z","caller":"traceutil/trace.go:171","msg":"trace[976534077] transaction","detail":"{read_only:false; response_revision:12343; number_of_response:1; }","duration":"100.660336ms","start":"2026-09-11T15:46:51.133687Z","end":"2026-09-11T15:46:51.234347Z","steps":["trace[976534077] 'process raft request'  (duration: 100.44925ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:46:51.272298Z","caller":"traceutil/trace.go:171","msg":"trace[1280117248] linearizableReadLoop","detail":"{readStateIndex:12773; appliedIndex:12771; }","duration":"128.557071ms","start":"2026-09-11T15:46:51.143712Z","end":"2026-09-11T15:46:51.272269Z","steps":["trace[1280117248] 'read index received'  (duration: 90.480156ms)","trace[1280117248] 'applied index is now lower than readState.Index'  (duration: 38.075065ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:46:51.272601Z","caller":"traceutil/trace.go:171","msg":"trace[76968395] transaction","detail":"{read_only:false; response_revision:12344; number_of_response:1; }","duration":"134.534774ms","start":"2026-09-11T15:46:51.138036Z","end":"2026-09-11T15:46:51.27257Z","steps":["trace[76968395] 'process raft request'  (duration: 133.979949ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:46:51.27283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.948721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-09-11T15:46:51.272924Z","caller":"traceutil/trace.go:171","msg":"trace[832561254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12345; }","duration":"129.217828ms","start":"2026-09-11T15:46:51.143686Z","end":"2026-09-11T15:46:51.272903Z","steps":["trace[832561254] 'agreement among raft nodes before linearized reading'  (duration: 128.858788ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:46:51.8381Z","caller":"traceutil/trace.go:171","msg":"trace[1784154689] transaction","detail":"{read_only:false; response_revision:12348; number_of_response:1; }","duration":"257.747157ms","start":"2026-09-11T15:46:51.580316Z","end":"2026-09-11T15:46:51.838063Z","steps":["trace[1784154689] 'process raft request'  (duration: 218.936534ms)","trace[1784154689] 'compare'  (duration: 38.330731ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:46:51.838728Z","caller":"traceutil/trace.go:171","msg":"trace[600013565] linearizableReadLoop","detail":"{readStateIndex:12778; appliedIndex:12777; }","duration":"208.765954ms","start":"2026-09-11T15:46:51.629934Z","end":"2026-09-11T15:46:51.8387Z","steps":["trace[600013565] 'read index received'  (duration: 169.336955ms)","trace[600013565] 'applied index is now lower than readState.Index'  (duration: 39.427219ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:46:51.83894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.989959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-09-11T15:46:51.838986Z","caller":"traceutil/trace.go:171","msg":"trace[1611427552] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12348; }","duration":"209.085092ms","start":"2026-09-11T15:46:51.629893Z","end":"2026-09-11T15:46:51.838978Z","steps":["trace[1611427552] 'agreement among raft nodes before linearized reading'  (duration: 208.880396ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:47:17.892023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10370}
{"level":"info","ts":"2026-09-11T15:47:17.989542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10370,"took":"94.390525ms","hash":2513130299,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":26255360,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-11T15:47:17.989635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2513130299,"revision":10370,"compact-revision":8000}
{"level":"info","ts":"2026-09-11T15:52:17.901041Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12534}
{"level":"info","ts":"2026-09-11T15:52:17.985001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12534,"took":"81.192978ms","hash":913599655,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":27631616,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-11T15:52:17.985177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":913599655,"revision":12534,"compact-revision":10370}
{"level":"info","ts":"2026-09-11T15:52:53.982186Z","caller":"traceutil/trace.go:171","msg":"trace[1366726278] transaction","detail":"{read_only:false; response_revision:14802; number_of_response:1; }","duration":"193.917502ms","start":"2026-09-11T15:52:53.788239Z","end":"2026-09-11T15:52:53.982157Z","steps":["trace[1366726278] 'process raft request'  (duration: 193.730927ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:53:18.30657Z","caller":"traceutil/trace.go:171","msg":"trace[670335240] transaction","detail":"{read_only:false; response_revision:14931; number_of_response:1; }","duration":"131.217467ms","start":"2026-09-11T15:53:18.175317Z","end":"2026-09-11T15:53:18.306534Z","steps":["trace[670335240] 'process raft request'  (duration: 128.658272ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:53:18.306662Z","caller":"traceutil/trace.go:171","msg":"trace[1916688751] linearizableReadLoop","detail":"{readStateIndex:15452; appliedIndex:15451; }","duration":"113.247618ms","start":"2026-09-11T15:53:18.19337Z","end":"2026-09-11T15:53:18.306618Z","steps":["trace[1916688751] 'read index received'  (duration: 110.62037ms)","trace[1916688751] 'applied index is now lower than readState.Index'  (duration: 2.619598ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:53:18.306924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.516234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-09-11T15:53:18.307018Z","caller":"traceutil/trace.go:171","msg":"trace[1747181260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14932; }","duration":"113.662058ms","start":"2026-09-11T15:53:18.193336Z","end":"2026-09-11T15:53:18.306998Z","steps":["trace[1747181260] 'agreement among raft nodes before linearized reading'  (duration: 113.399012ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:53:27.609962Z","caller":"traceutil/trace.go:171","msg":"trace[158833404] transaction","detail":"{read_only:false; response_revision:14977; number_of_response:1; }","duration":"151.056404ms","start":"2026-09-11T15:53:27.458878Z","end":"2026-09-11T15:53:27.609934Z","steps":["trace[158833404] 'process raft request'  (duration: 150.818638ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:53:36.378975Z","caller":"traceutil/trace.go:171","msg":"trace[1706833138] transaction","detail":"{read_only:false; response_revision:15040; number_of_response:1; }","duration":"131.72922ms","start":"2026-09-11T15:53:36.247204Z","end":"2026-09-11T15:53:36.378933Z","steps":["trace[1706833138] 'process raft request'  (duration: 33.376244ms)","trace[1706833138] 'compare'  (duration: 97.966817ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:53:36.497336Z","caller":"traceutil/trace.go:171","msg":"trace[138030151] linearizableReadLoop","detail":"{readStateIndex:15567; appliedIndex:15566; }","duration":"104.068552ms","start":"2026-09-11T15:53:36.393224Z","end":"2026-09-11T15:53:36.497293Z","steps":["trace[138030151] 'read index received'  (duration: 94.538628ms)","trace[138030151] 'applied index is now lower than readState.Index'  (duration: 9.527884ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:53:36.497381Z","caller":"traceutil/trace.go:171","msg":"trace[643049154] transaction","detail":"{read_only:false; response_revision:15041; number_of_response:1; }","duration":"129.207645ms","start":"2026-09-11T15:53:36.368136Z","end":"2026-09-11T15:53:36.497344Z","steps":["trace[643049154] 'process raft request'  (duration: 119.664631ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:53:36.497606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.35934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-09-11T15:53:36.497704Z","caller":"traceutil/trace.go:171","msg":"trace[113969414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15041; }","duration":"104.503544ms","start":"2026-09-11T15:53:36.393176Z","end":"2026-09-11T15:53:36.497679Z","steps":["trace[113969414] 'agreement among raft nodes before linearized reading'  (duration: 104.208136ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:53:59.848721Z","caller":"traceutil/trace.go:171","msg":"trace[1553511337] linearizableReadLoop","detail":"{readStateIndex:15891; appliedIndex:15890; }","duration":"102.409063ms","start":"2026-09-11T15:53:59.746276Z","end":"2026-09-11T15:53:59.848686Z","steps":["trace[1553511337] 'read index received'  (duration: 93.955686ms)","trace[1553511337] 'applied index is now lower than readState.Index'  (duration: 8.450767ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:53:59.849024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.712881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1993bcb0-0062-4387-b023-afcb8ae9e5f8\" ","response":"range_response_count:1 size:3637"}
{"level":"info","ts":"2026-09-11T15:53:59.849107Z","caller":"traceutil/trace.go:171","msg":"trace[887731481] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1993bcb0-0062-4387-b023-afcb8ae9e5f8; range_end:; response_count:1; response_revision:15354; }","duration":"102.865444ms","start":"2026-09-11T15:53:59.746221Z","end":"2026-09-11T15:53:59.849087Z","steps":["trace[887731481] 'agreement among raft nodes before linearized reading'  (duration: 102.586827ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:53:59.84877Z","caller":"traceutil/trace.go:171","msg":"trace[174264536] transaction","detail":"{read_only:false; response_revision:15354; number_of_response:1; }","duration":"103.688176ms","start":"2026-09-11T15:53:59.745058Z","end":"2026-09-11T15:53:59.848746Z","steps":["trace[174264536] 'process raft request'  (duration: 95.244979ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:54:00.198476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.328926ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384598326 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:15287 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-11T15:54:00.198723Z","caller":"traceutil/trace.go:171","msg":"trace[189504929] transaction","detail":"{read_only:false; response_revision:15357; number_of_response:1; }","duration":"345.124635ms","start":"2026-09-11T15:53:59.853574Z","end":"2026-09-11T15:54:00.198699Z","steps":["trace[189504929] 'process raft request'  (duration: 345.021691ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:54:00.198851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:53:59.853546Z","time spent":"345.228727ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:15288 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-11T15:54:00.198771Z","caller":"traceutil/trace.go:171","msg":"trace[1039195073] transaction","detail":"{read_only:false; response_revision:15356; number_of_response:1; }","duration":"345.166465ms","start":"2026-09-11T15:53:59.85353Z","end":"2026-09-11T15:54:00.198696Z","steps":["trace[1039195073] 'process raft request'  (duration: 213.50608ms)","trace[1039195073] 'compare'  (duration: 131.171762ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:54:00.198926Z","caller":"traceutil/trace.go:171","msg":"trace[347354858] linearizableReadLoop","detail":"{readStateIndex:15895; appliedIndex:15892; }","duration":"236.387789ms","start":"2026-09-11T15:53:59.962482Z","end":"2026-09-11T15:54:00.19887Z","steps":["trace[347354858] 'read index received'  (duration: 104.509447ms)","trace[347354858] 'applied index is now lower than readState.Index'  (duration: 131.876462ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:54:00.199033Z","caller":"traceutil/trace.go:171","msg":"trace[301950045] transaction","detail":"{read_only:false; response_revision:15358; number_of_response:1; }","duration":"305.642578ms","start":"2026-09-11T15:53:59.893378Z","end":"2026-09-11T15:54:00.199021Z","steps":["trace[301950045] 'process raft request'  (duration: 305.273558ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:54:00.199065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:53:59.853509Z","time spent":"345.433153ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:15287 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-09-11T15:54:00.199098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T15:53:59.893356Z","time spent":"305.709212ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:15289 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-11T15:54:00.19919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.693066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jc4h9\" ","response":"range_response_count:1 size:6451"}
{"level":"info","ts":"2026-09-11T15:54:00.199234Z","caller":"traceutil/trace.go:171","msg":"trace[856244490] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jc4h9; range_end:; response_count:1; response_revision:15358; }","duration":"236.800139ms","start":"2026-09-11T15:53:59.962422Z","end":"2026-09-11T15:54:00.199222Z","steps":["trace[856244490] 'agreement among raft nodes before linearized reading'  (duration: 236.625455ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:54:53.806143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.61639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-11T15:54:53.806283Z","caller":"traceutil/trace.go:171","msg":"trace[1617242738] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15997; }","duration":"104.795624ms","start":"2026-09-11T15:54:53.701457Z","end":"2026-09-11T15:54:53.806253Z","steps":["trace[1617242738] 'range keys from in-memory index tree'  (duration: 104.532087ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:54:53.806382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.58372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-11T15:54:53.80647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.61229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-11T15:54:53.806473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.624273ms","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-09-11T15:54:53.806483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.660782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"}
{"level":"warn","ts":"2026-09-11T15:54:53.806556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.732435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-11T15:54:53.806596Z","caller":"traceutil/trace.go:171","msg":"trace[1959093351] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15997; }","duration":"106.804107ms","start":"2026-09-11T15:54:53.699763Z","end":"2026-09-11T15:54:53.806567Z","steps":["trace[1959093351] 'range keys from in-memory index tree'  (duration: 106.505839ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:54:53.806576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.55748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"}
{"level":"info","ts":"2026-09-11T15:54:53.806616Z","caller":"traceutil/trace.go:171","msg":"trace[251107200] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15997; }","duration":"113.878408ms","start":"2026-09-11T15:54:53.692712Z","end":"2026-09-11T15:54:53.806591Z","steps":["trace[251107200] 'range keys from in-memory index tree'  (duration: 113.403416ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:54:53.806686Z","caller":"traceutil/trace.go:171","msg":"trace[368629507] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15997; }","duration":"106.725375ms","start":"2026-09-11T15:54:53.699935Z","end":"2026-09-11T15:54:53.806661Z","steps":["trace[368629507] 'range keys from in-memory index tree'  (duration: 106.326294ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:54:53.806605Z","caller":"traceutil/trace.go:171","msg":"trace[1958392866] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15997; }","duration":"101.833458ms","start":"2026-09-11T15:54:53.704761Z","end":"2026-09-11T15:54:53.806595Z","steps":["trace[1958392866] 'range keys from in-memory index tree'  (duration: 101.599672ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:54:53.80649Z","caller":"traceutil/trace.go:171","msg":"trace[1752569931] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15997; }","duration":"113.739274ms","start":"2026-09-11T15:54:53.692729Z","end":"2026-09-11T15:54:53.806468Z","steps":["trace[1752569931] 'range keys from in-memory index tree'  (duration: 113.461967ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:54:53.806564Z","caller":"traceutil/trace.go:171","msg":"trace[2080776755] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15997; }","duration":"104.721922ms","start":"2026-09-11T15:54:53.70182Z","end":"2026-09-11T15:54:53.806542Z","steps":["trace[2080776755] 'range keys from in-memory index tree'  (duration: 104.550379ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:55:33.290932Z","caller":"traceutil/trace.go:171","msg":"trace[608713192] transaction","detail":"{read_only:false; response_revision:16205; number_of_response:1; }","duration":"122.008917ms","start":"2026-09-11T15:55:33.168868Z","end":"2026-09-11T15:55:33.290876Z","steps":["trace[608713192] 'process raft request'  (duration: 121.827852ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:55:33.407003Z","caller":"traceutil/trace.go:171","msg":"trace[310889816] linearizableReadLoop","detail":"{readStateIndex:16767; appliedIndex:16765; }","duration":"192.016847ms","start":"2026-09-11T15:55:33.21496Z","end":"2026-09-11T15:55:33.406977Z","steps":["trace[310889816] 'read index received'  (duration: 75.769059ms)","trace[310889816] 'applied index is now lower than readState.Index'  (duration: 116.246818ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T15:55:33.407079Z","caller":"traceutil/trace.go:171","msg":"trace[1292083077] transaction","detail":"{read_only:false; response_revision:16206; number_of_response:1; }","duration":"235.140486ms","start":"2026-09-11T15:55:33.171904Z","end":"2026-09-11T15:55:33.407044Z","steps":["trace[1292083077] 'process raft request'  (duration: 200.229159ms)","trace[1292083077] 'compare'  (duration: 34.706372ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T15:55:33.407234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.273454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"}
{"level":"info","ts":"2026-09-11T15:55:33.407266Z","caller":"traceutil/trace.go:171","msg":"trace[564564853] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:16206; }","duration":"192.394567ms","start":"2026-09-11T15:55:33.21486Z","end":"2026-09-11T15:55:33.407254Z","steps":["trace[564564853] 'agreement among raft nodes before linearized reading'  (duration: 192.235243ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:55:33.42065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.95051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"}
{"level":"info","ts":"2026-09-11T15:55:33.420704Z","caller":"traceutil/trace.go:171","msg":"trace[1719549294] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16207; }","duration":"114.051862ms","start":"2026-09-11T15:55:33.306638Z","end":"2026-09-11T15:55:33.42069Z","steps":["trace[1719549294] 'agreement among raft nodes before linearized reading'  (duration: 113.93773ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:55:33.420876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.404409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jc4h9\" ","response":"range_response_count:1 size:6451"}
{"level":"info","ts":"2026-09-11T15:55:33.420903Z","caller":"traceutil/trace.go:171","msg":"trace[600213478] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jc4h9; range_end:; response_count:1; response_revision:16207; }","duration":"180.46164ms","start":"2026-09-11T15:55:33.240433Z","end":"2026-09-11T15:55:33.420895Z","steps":["trace[600213478] 'agreement among raft nodes before linearized reading'  (duration: 180.381708ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T15:55:33.421112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.749032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-czt8h\" ","response":"range_response_count:1 size:22021"}
{"level":"info","ts":"2026-09-11T15:55:33.421143Z","caller":"traceutil/trace.go:171","msg":"trace[1246598092] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-czt8h; range_end:; response_count:1; response_revision:16207; }","duration":"186.801683ms","start":"2026-09-11T15:55:33.234333Z","end":"2026-09-11T15:55:33.421135Z","steps":["trace[1246598092] 'agreement among raft nodes before linearized reading'  (duration: 186.712181ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T15:57:17.91288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14541}
{"level":"info","ts":"2026-09-11T15:57:17.999672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14541,"took":"83.23241ms","hash":2668058249,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":30953472,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-11T15:57:17.999754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2668058249,"revision":14541,"compact-revision":12534}
{"level":"info","ts":"2026-09-11T15:58:44.276278Z","caller":"traceutil/trace.go:171","msg":"trace[2031163476] transaction","detail":"{read_only:false; response_revision:17713; number_of_response:1; }","duration":"117.040569ms","start":"2026-09-11T15:58:44.159195Z","end":"2026-09-11T15:58:44.276236Z","steps":["trace[2031163476] 'process raft request'  (duration: 46.521382ms)","trace[2031163476] 'compare'  (duration: 70.313332ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:01:57.176871Z","caller":"traceutil/trace.go:171","msg":"trace[782407407] transaction","detail":"{read_only:false; response_revision:19003; number_of_response:1; }","duration":"114.780821ms","start":"2026-09-11T16:01:57.06206Z","end":"2026-09-11T16:01:57.176841Z","steps":["trace[782407407] 'process raft request'  (duration: 105.679218ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:01:57.176875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.629801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-czt8h\" ","response":"range_response_count:1 size:22022"}
{"level":"info","ts":"2026-09-11T16:01:57.177013Z","caller":"traceutil/trace.go:171","msg":"trace[845399288] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-czt8h; range_end:; response_count:1; response_revision:19002; }","duration":"159.841606ms","start":"2026-09-11T16:01:57.017147Z","end":"2026-09-11T16:01:57.176989Z","steps":["trace[845399288] 'range keys from in-memory index tree'  (duration: 159.327032ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:02:03.683336Z","caller":"traceutil/trace.go:171","msg":"trace[148917079] transaction","detail":"{read_only:false; response_revision:19052; number_of_response:1; }","duration":"101.427479ms","start":"2026-09-11T16:02:03.581871Z","end":"2026-09-11T16:02:03.683298Z","steps":["trace[148917079] 'process raft request'  (duration: 101.222054ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:02:03.69015Z","caller":"traceutil/trace.go:171","msg":"trace[1864181360] transaction","detail":"{read_only:false; response_revision:19053; number_of_response:1; }","duration":"105.562825ms","start":"2026-09-11T16:02:03.584568Z","end":"2026-09-11T16:02:03.69013Z","steps":["trace[1864181360] 'process raft request'  (duration: 105.407961ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:02:04.032189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.921259ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384612712 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:19039 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-11T16:02:04.032316Z","caller":"traceutil/trace.go:171","msg":"trace[1337965644] linearizableReadLoop","detail":"{readStateIndex:19710; appliedIndex:19709; }","duration":"335.654082ms","start":"2026-09-11T16:02:03.69664Z","end":"2026-09-11T16:02:04.032294Z","steps":["trace[1337965644] 'read index received'  (duration: 45.542868ms)","trace[1337965644] 'applied index is now lower than readState.Index'  (duration: 290.109674ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:02:04.032439Z","caller":"traceutil/trace.go:171","msg":"trace[1574179048] transaction","detail":"{read_only:false; response_revision:19055; number_of_response:1; }","duration":"336.940294ms","start":"2026-09-11T16:02:03.69545Z","end":"2026-09-11T16:02:04.03239Z","steps":["trace[1574179048] 'process raft request'  (duration: 46.723237ms)","trace[1574179048] 'compare'  (duration: 289.755015ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T16:02:04.03267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:02:03.695424Z","time spent":"337.120478ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:19039 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-09-11T16:02:04.033432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.796191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:955381"}
{"level":"info","ts":"2026-09-11T16:02:04.033477Z","caller":"traceutil/trace.go:171","msg":"trace[1017989139] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:19055; }","duration":"336.889833ms","start":"2026-09-11T16:02:03.696574Z","end":"2026-09-11T16:02:04.033463Z","steps":["trace[1017989139] 'agreement among raft nodes before linearized reading'  (duration: 335.805845ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:02:04.033512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:02:03.696546Z","time spent":"336.954825ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":96,"response size":955404,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "}
{"level":"info","ts":"2026-09-11T16:02:04.335825Z","caller":"traceutil/trace.go:171","msg":"trace[1241176662] linearizableReadLoop","detail":"{readStateIndex:19711; appliedIndex:19710; }","duration":"303.447546ms","start":"2026-09-11T16:02:04.032341Z","end":"2026-09-11T16:02:04.335789Z","steps":["trace[1241176662] 'read index received'  (duration: 252.792878ms)","trace[1241176662] 'applied index is now lower than readState.Index'  (duration: 50.652958ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T16:02:04.336149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.247684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-11T16:02:04.336311Z","caller":"traceutil/trace.go:171","msg":"trace[107916734] transaction","detail":"{read_only:false; response_revision:19056; number_of_response:1; }","duration":"350.694965ms","start":"2026-09-11T16:02:03.985565Z","end":"2026-09-11T16:02:04.33626Z","steps":["trace[107916734] 'process raft request'  (duration: 299.606707ms)","trace[107916734] 'compare'  (duration: 50.461113ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:02:04.336397Z","caller":"traceutil/trace.go:171","msg":"trace[860088093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19056; }","duration":"501.453489ms","start":"2026-09-11T16:02:03.834733Z","end":"2026-09-11T16:02:04.336186Z","steps":["trace[860088093] 'agreement among raft nodes before linearized reading'  (duration: 501.202423ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:02:04.336538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:02:03.834649Z","time spent":"501.873661ms","remote":"127.0.0.1:38968","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-09-11T16:02:04.336578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:02:03.985528Z","time spent":"350.895761ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:19041 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-11T16:02:04.336781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.397627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4wvxd\" ","response":"range_response_count:1 size:20668"}
{"level":"info","ts":"2026-09-11T16:02:04.337127Z","caller":"traceutil/trace.go:171","msg":"trace[1077796107] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4wvxd; range_end:; response_count:1; response_revision:19056; }","duration":"499.826957ms","start":"2026-09-11T16:02:03.837277Z","end":"2026-09-11T16:02:04.337104Z","steps":["trace[1077796107] 'agreement among raft nodes before linearized reading'  (duration: 499.058617ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:02:04.337213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:02:03.837243Z","time spent":"499.95777ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20691,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4wvxd\" "}
{"level":"info","ts":"2026-09-11T16:02:04.598767Z","caller":"traceutil/trace.go:171","msg":"trace[1371988033] transaction","detail":"{read_only:false; response_revision:19057; number_of_response:1; }","duration":"262.988218ms","start":"2026-09-11T16:02:04.335743Z","end":"2026-09-11T16:02:04.598731Z","steps":["trace[1371988033] 'process raft request'  (duration: 262.766082ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:02:04.599142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.015269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-11T16:02:04.598989Z","caller":"traceutil/trace.go:171","msg":"trace[511382264] linearizableReadLoop","detail":"{readStateIndex:19712; appliedIndex:19712; }","duration":"260.842064ms","start":"2026-09-11T16:02:04.338113Z","end":"2026-09-11T16:02:04.598955Z","steps":["trace[511382264] 'read index received'  (duration: 260.827304ms)","trace[511382264] 'applied index is now lower than readState.Index'  (duration: 12.52µs)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:02:04.599188Z","caller":"traceutil/trace.go:171","msg":"trace[671565072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19057; }","duration":"261.107271ms","start":"2026-09-11T16:02:04.33807Z","end":"2026-09-11T16:02:04.599178Z","steps":["trace[671565072] 'agreement among raft nodes before linearized reading'  (duration: 260.982218ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:02:04.646606Z","caller":"traceutil/trace.go:171","msg":"trace[363301171] transaction","detail":"{read_only:false; response_revision:19058; number_of_response:1; }","duration":"283.357751ms","start":"2026-09-11T16:02:04.363212Z","end":"2026-09-11T16:02:04.64657Z","steps":["trace[363301171] 'process raft request'  (duration: 282.965011ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:02:17.919472Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16869}
{"level":"info","ts":"2026-09-11T16:02:18.022593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16869,"took":"100.000252ms","hash":624336620,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":32342016,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-11T16:02:18.022759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":624336620,"revision":16869,"compact-revision":14541}
{"level":"info","ts":"2026-09-11T16:02:27.15243Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39c975536cfc1086","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-11T16:02:27.162027Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-09-11T16:02:27.162158Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-09-11T16:07:17.92746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19264}
{"level":"info","ts":"2026-09-11T16:07:18.036014Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19264,"took":"103.961492ms","hash":1660754710,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":34410496,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-09-11T16:07:18.0361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1660754710,"revision":19264,"compact-revision":16869}
{"level":"info","ts":"2026-09-11T16:08:01.860023Z","caller":"traceutil/trace.go:171","msg":"trace[1495191061] linearizableReadLoop","detail":"{readStateIndex:22597; appliedIndex:22596; }","duration":"144.94241ms","start":"2026-09-11T16:08:01.715049Z","end":"2026-09-11T16:08:01.859991Z","steps":["trace[1495191061] 'read index received'  (duration: 144.653423ms)","trace[1495191061] 'applied index is now lower than readState.Index'  (duration: 287.897µs)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:08:01.860099Z","caller":"traceutil/trace.go:171","msg":"trace[207679857] transaction","detail":"{read_only:false; response_revision:21849; number_of_response:1; }","duration":"156.877376ms","start":"2026-09-11T16:08:01.703173Z","end":"2026-09-11T16:08:01.86005Z","steps":["trace[207679857] 'process raft request'  (duration: 156.602319ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:08:01.860385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.308039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-11T16:08:01.860452Z","caller":"traceutil/trace.go:171","msg":"trace[699959457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21849; }","duration":"145.446352ms","start":"2026-09-11T16:08:01.714991Z","end":"2026-09-11T16:08:01.860437Z","steps":["trace[699959457] 'agreement among raft nodes before linearized reading'  (duration: 145.155475ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:08:02.107049Z","caller":"traceutil/trace.go:171","msg":"trace[688040149] transaction","detail":"{read_only:false; response_revision:21850; number_of_response:1; }","duration":"241.850961ms","start":"2026-09-11T16:08:01.865141Z","end":"2026-09-11T16:08:02.106991Z","steps":["trace[688040149] 'process raft request'  (duration: 142.468787ms)","trace[688040149] 'compare'  (duration: 98.846691ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T16:08:02.107224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.928486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-11T16:08:02.107289Z","caller":"traceutil/trace.go:171","msg":"trace[1909446863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21852; }","duration":"130.083371ms","start":"2026-09-11T16:08:01.977195Z","end":"2026-09-11T16:08:02.107279Z","steps":["trace[1909446863] 'agreement among raft nodes before linearized reading'  (duration: 129.923297ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:08:02.107055Z","caller":"traceutil/trace.go:171","msg":"trace[1391269761] linearizableReadLoop","detail":"{readStateIndex:22599; appliedIndex:22597; }","duration":"129.764861ms","start":"2026-09-11T16:08:01.977261Z","end":"2026-09-11T16:08:02.107026Z","steps":["trace[1391269761] 'read index received'  (duration: 30.442669ms)","trace[1391269761] 'applied index is now lower than readState.Index'  (duration: 99.320952ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:08:02.10751Z","caller":"traceutil/trace.go:171","msg":"trace[1501846925] transaction","detail":"{read_only:false; response_revision:21851; number_of_response:1; }","duration":"189.812939ms","start":"2026-09-11T16:08:01.917687Z","end":"2026-09-11T16:08:02.1075Z","steps":["trace[1501846925] 'process raft request'  (duration: 189.156982ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:08:02.107533Z","caller":"traceutil/trace.go:171","msg":"trace[204728896] transaction","detail":"{read_only:false; response_revision:21852; number_of_response:1; }","duration":"114.84775ms","start":"2026-09-11T16:08:01.992663Z","end":"2026-09-11T16:08:02.107511Z","steps":["trace[204728896] 'process raft request'  (duration: 114.312386ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:08:18.557483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.467201ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384620866 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:21945 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-11T16:08:18.557644Z","caller":"traceutil/trace.go:171","msg":"trace[214175223] transaction","detail":"{read_only:false; response_revision:21956; number_of_response:1; }","duration":"342.095177ms","start":"2026-09-11T16:08:18.215516Z","end":"2026-09-11T16:08:18.557611Z","steps":["trace[214175223] 'process raft request'  (duration: 150.38141ms)","trace[214175223] 'compare'  (duration: 191.283996ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T16:08:18.557725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:08:18.215489Z","time spent":"342.196189ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:21945 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-11T16:08:18.847623Z","caller":"traceutil/trace.go:171","msg":"trace[2029340727] transaction","detail":"{read_only:false; response_revision:21957; number_of_response:1; }","duration":"578.090928ms","start":"2026-09-11T16:08:18.2695Z","end":"2026-09-11T16:08:18.847591Z","steps":["trace[2029340727] 'process raft request'  (duration: 509.056351ms)","trace[2029340727] 'compare'  (duration: 68.786351ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T16:08:18.847832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:08:18.26946Z","time spent":"578.249132ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:21946 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-09-11T16:08:18.847873Z","caller":"traceutil/trace.go:171","msg":"trace[476447403] linearizableReadLoop","detail":"{readStateIndex:22709; appliedIndex:22706; }","duration":"558.740482ms","start":"2026-09-11T16:08:18.289111Z","end":"2026-09-11T16:08:18.847851Z","steps":["trace[476447403] 'read index received'  (duration: 76.761185ms)","trace[476447403] 'applied index is now lower than readState.Index'  (duration: 481.977887ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:08:18.847906Z","caller":"traceutil/trace.go:171","msg":"trace[575963154] transaction","detail":"{read_only:false; response_revision:21958; number_of_response:1; }","duration":"563.618518ms","start":"2026-09-11T16:08:18.284226Z","end":"2026-09-11T16:08:18.847845Z","steps":["trace[575963154] 'process raft request'  (duration: 563.28797ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:08:18.848094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:08:18.284197Z","time spent":"563.786702ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:21947 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-11T16:08:18.848063Z","caller":"traceutil/trace.go:171","msg":"trace[273936829] transaction","detail":"{read_only:false; response_revision:21959; number_of_response:1; }","duration":"485.780594ms","start":"2026-09-11T16:08:18.362232Z","end":"2026-09-11T16:08:18.848012Z","steps":["trace[273936829] 'process raft request'  (duration: 485.523128ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:08:18.848217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.110052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"warn","ts":"2026-09-11T16:08:18.848264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:08:18.362211Z","time spent":"485.950199ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:21949 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-11T16:08:18.848277Z","caller":"traceutil/trace.go:171","msg":"trace[1468385995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21959; }","duration":"559.214994ms","start":"2026-09-11T16:08:18.289045Z","end":"2026-09-11T16:08:18.84826Z","steps":["trace[1468385995] 'agreement among raft nodes before linearized reading'  (duration: 559.041609ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:08:18.84832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:08:18.289021Z","time spent":"559.290377ms","remote":"127.0.0.1:39238","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-09-11T16:08:19.196716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.381252ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384620875 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:21950 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-11T16:08:19.197074Z","caller":"traceutil/trace.go:171","msg":"trace[1218708229] transaction","detail":"{read_only:false; response_revision:21962; number_of_response:1; }","duration":"263.088505ms","start":"2026-09-11T16:08:18.933949Z","end":"2026-09-11T16:08:19.197038Z","steps":["trace[1218708229] 'process raft request'  (duration: 262.89248ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:08:19.19717Z","caller":"traceutil/trace.go:171","msg":"trace[2009694181] transaction","detail":"{read_only:false; response_revision:21961; number_of_response:1; }","duration":"297.623699ms","start":"2026-09-11T16:08:18.89949Z","end":"2026-09-11T16:08:19.197114Z","steps":["trace[2009694181] 'process raft request'  (duration: 142.746684ms)","trace[2009694181] 'compare'  (duration: 154.013073ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:08:19.473375Z","caller":"traceutil/trace.go:171","msg":"trace[297486571] transaction","detail":"{read_only:false; response_revision:21963; number_of_response:1; }","duration":"195.141596ms","start":"2026-09-11T16:08:19.2782Z","end":"2026-09-11T16:08:19.473341Z","steps":["trace[297486571] 'process raft request'  (duration: 194.937461ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:08:19.474311Z","caller":"traceutil/trace.go:171","msg":"trace[739115094] linearizableReadLoop","detail":"{readStateIndex:22715; appliedIndex:22714; }","duration":"117.56196ms","start":"2026-09-11T16:08:19.356718Z","end":"2026-09-11T16:08:19.47428Z","steps":["trace[739115094] 'read index received'  (duration: 117.297143ms)","trace[739115094] 'applied index is now lower than readState.Index'  (duration: 263.877µs)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:08:19.474649Z","caller":"traceutil/trace.go:171","msg":"trace[831297047] transaction","detail":"{read_only:false; response_revision:21964; number_of_response:1; }","duration":"195.022352ms","start":"2026-09-11T16:08:19.279612Z","end":"2026-09-11T16:08:19.474634Z","steps":["trace[831297047] 'process raft request'  (duration: 194.510919ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:08:19.475034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.303268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-11T16:08:19.475081Z","caller":"traceutil/trace.go:171","msg":"trace[317907991] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:21964; }","duration":"118.417751ms","start":"2026-09-11T16:08:19.356651Z","end":"2026-09-11T16:08:19.475069Z","steps":["trace[317907991] 'agreement among raft nodes before linearized reading'  (duration: 118.320069ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:12:17.935359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21625}
{"level":"info","ts":"2026-09-11T16:12:18.075998Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21625,"took":"136.33565ms","hash":1001507744,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":39919616,"current-db-size-in-use":"40 MB"}
{"level":"info","ts":"2026-09-11T16:12:18.076093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1001507744,"revision":21625,"compact-revision":19264}
{"level":"warn","ts":"2026-09-11T16:12:24.39425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.978ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815696384628176 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24422 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-11T16:12:24.394529Z","caller":"traceutil/trace.go:171","msg":"trace[868121808] transaction","detail":"{read_only:false; response_revision:24473; number_of_response:1; }","duration":"358.844636ms","start":"2026-09-11T16:12:24.035657Z","end":"2026-09-11T16:12:24.394502Z","steps":["trace[868121808] 'process raft request'  (duration: 56.510996ms)","trace[868121808] 'compare'  (duration: 301.334884ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T16:12:24.394609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:12:24.035619Z","time spent":"358.954908ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24422 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-11T16:12:24.395394Z","caller":"traceutil/trace.go:171","msg":"trace[963466015] transaction","detail":"{read_only:false; response_revision:24474; number_of_response:1; }","duration":"331.26555ms","start":"2026-09-11T16:12:24.064117Z","end":"2026-09-11T16:12:24.395382Z","steps":["trace[963466015] 'process raft request'  (duration: 330.272195ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:12:24.395469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T16:12:24.064084Z","time spent":"331.350782ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24425 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:450 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"info","ts":"2026-09-11T16:13:45.728796Z","caller":"traceutil/trace.go:171","msg":"trace[910174462] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26199; }","duration":"149.447276ms","start":"2026-09-11T16:13:45.579305Z","end":"2026-09-11T16:13:45.728752Z","steps":["trace[910174462] 'read index received'  (duration: 122.27156ms)","trace[910174462] 'applied index is now lower than readState.Index'  (duration: 27.173696ms)"],"step_count":2}
{"level":"info","ts":"2026-09-11T16:13:45.728861Z","caller":"traceutil/trace.go:171","msg":"trace[755444762] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"149.713282ms","start":"2026-09-11T16:13:45.579101Z","end":"2026-09-11T16:13:45.728814Z","steps":["trace[755444762] 'process raft request'  (duration: 122.530657ms)","trace[755444762] 'compare'  (duration: 26.933689ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-11T16:13:45.729054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.707752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-11T16:13:45.729109Z","caller":"traceutil/trace.go:171","msg":"trace[3726787] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25350; }","duration":"149.796525ms","start":"2026-09-11T16:13:45.579295Z","end":"2026-09-11T16:13:45.729092Z","steps":["trace[3726787] 'agreement among raft nodes before linearized reading'  (duration: 149.664121ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-11T16:13:45.729204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.366794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-db-sync-bvtvm\" ","response":"range_response_count:1 size:6778"}
{"level":"info","ts":"2026-09-11T16:13:45.729315Z","caller":"traceutil/trace.go:171","msg":"trace[354545058] range","detail":"{range_begin:/registry/pods/openstack/magnum-db-sync-bvtvm; range_end:; response_count:1; response_revision:25350; }","duration":"108.536159ms","start":"2026-09-11T16:13:45.620752Z","end":"2026-09-11T16:13:45.729288Z","steps":["trace[354545058] 'agreement among raft nodes before linearized reading'  (duration: 108.252861ms)"],"step_count":1}
{"level":"info","ts":"2026-09-11T16:15:10.739326Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006910.wal"}
{"level":"info","ts":"2026-09-11T16:17:17.942028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24333}
{"level":"info","ts":"2026-09-11T16:17:18.091081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24333,"took":"144.790758ms","hash":876611429,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":34783232,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-09-11T16:17:18.091161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":876611429,"revision":24333,"compact-revision":21625}
{"level":"info","ts":"2026-09-11T16:21:58.391358Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39c975536cfc1086","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-11T16:21:58.3989Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003}
{"level":"info","ts":"2026-09-11T16:21:58.399017Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}
{"level":"info","ts":"2026-09-11T16:22:17.951277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26961}
{"level":"info","ts":"2026-09-11T16:22:18.089025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26961,"took":"132.230427ms","hash":1680166503,"current-db-size-bytes":56569856,"current-db-size":"57 MB","current-db-size-in-use-bytes":29634560,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-11T16:22:18.089114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1680166503,"revision":26961,"compact-revision":24333}