{"level":"warn","ts":"2026-09-23T10:26:25.047776Z","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-23T10:26:25.047893Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.102: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.102:2380","--initial-cluster=instance=https://162.253.55.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.102: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-23T10:26:25.047967Z","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-23T10:26:25.047992Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.102:2380"]}
{"level":"info","ts":"2026-09-23T10:26:25.048019Z","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-23T10:26:25.048594Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.102:2379"]}
{"level":"info","ts":"2026-09-23T10:26:25.048706Z","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":8,"max-cpu-available":8,"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.102:2380"],"listen-peer-urls":["https://162.253.55.102:2380"],"advertise-client-urls":["https://162.253.55.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.102: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-23T10:26:25.05352Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.209629ms"}
{"level":"info","ts":"2026-09-23T10:26:25.059917Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2af340d70e332c3","cluster-id":"da2a6dcf93591a68"}
{"level":"info","ts":"2026-09-23T10:26:25.059988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 switched to configuration voters=()"}
{"level":"info","ts":"2026-09-23T10:26:25.060014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became follower at term 0"}
{"level":"info","ts":"2026-09-23T10:26:25.060034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2af340d70e332c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-23T10:26:25.060052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became follower at term 1"}
{"level":"info","ts":"2026-09-23T10:26:25.060106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 switched to configuration voters=(16334331605829235395)"}
{"level":"warn","ts":"2026-09-23T10:26:25.067111Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-23T10:26:25.068577Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-23T10:26:25.070549Z","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-23T10:26:25.073164Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2af340d70e332c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-23T10:26:25.077513Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2af340d70e332c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-23T10:26:25.077695Z","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-23T10:26:25.077721Z","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-23T10:26:25.07782Z","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-23T10:26:25.077849Z","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-23T10:26:25.077962Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2af340d70e332c3","initial-advertise-peer-urls":["https://162.253.55.102:2380"],"listen-peer-urls":["https://162.253.55.102:2380"],"advertise-client-urls":["https://162.253.55.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-23T10:26:25.078003Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-23T10:26:25.078036Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.102:2380"}
{"level":"info","ts":"2026-09-23T10:26:25.078053Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.102:2380"}
{"level":"info","ts":"2026-09-23T10:26:25.078396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 switched to configuration voters=(16334331605829235395)"}
{"level":"info","ts":"2026-09-23T10:26:25.078499Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"da2a6dcf93591a68","local-member-id":"e2af340d70e332c3","added-peer-id":"e2af340d70e332c3","added-peer-peer-urls":["https://162.253.55.102:2380"]}
{"level":"info","ts":"2026-09-23T10:26:25.460447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 is starting a new election at term 1"}
{"level":"info","ts":"2026-09-23T10:26:25.460505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-23T10:26:25.460528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 received MsgPreVoteResp from e2af340d70e332c3 at term 1"}
{"level":"info","ts":"2026-09-23T10:26:25.460546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became candidate at term 2"}
{"level":"info","ts":"2026-09-23T10:26:25.460556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 received MsgVoteResp from e2af340d70e332c3 at term 2"}
{"level":"info","ts":"2026-09-23T10:26:25.46057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became leader at term 2"}
{"level":"info","ts":"2026-09-23T10:26:25.460588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2af340d70e332c3 elected leader e2af340d70e332c3 at term 2"}
{"level":"info","ts":"2026-09-23T10:26:25.462046Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-23T10:26:25.46273Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2af340d70e332c3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.102:2379]}","request-path":"/0/members/e2af340d70e332c3/attributes","cluster-id":"da2a6dcf93591a68","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-23T10:26:25.462952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-23T10:26:25.463158Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"da2a6dcf93591a68","local-member-id":"e2af340d70e332c3","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-23T10:26:25.463182Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-23T10:26:25.463324Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-23T10:26:25.463397Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-23T10:26:25.46347Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-23T10:26:25.462971Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-23T10:26:25.466338Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-23T10:26:25.467118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.102:2379"}
{"level":"warn","ts":"2026-09-23T10:26:28.474779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.690965ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129639770 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" value_size:599 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:26:28.475286Z","caller":"traceutil/trace.go:171","msg":"trace[1024757689] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"426.679168ms","start":"2026-09-23T10:26:28.048518Z","end":"2026-09-23T10:26:28.475197Z","steps":["trace[1024757689] 'process raft request'  (duration: 193.650605ms)","trace[1024757689] 'compare'  (duration: 231.513951ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:26:28.475545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:28.048508Z","time spent":"426.955565ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" value_size:599 >> failure:<>"}
{"level":"warn","ts":"2026-09-23T10:26:28.868059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.866705ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129639772 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" value_size:615 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:26:28.868168Z","caller":"traceutil/trace.go:171","msg":"trace[751222581] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"387.688519ms","start":"2026-09-23T10:26:28.480462Z","end":"2026-09-23T10:26:28.868151Z","steps":["trace[751222581] 'process raft request'  (duration: 181.65988ms)","trace[751222581] 'compare'  (duration: 205.757301ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:26:28.868213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:28.480442Z","time spent":"387.752951ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" value_size:615 >> failure:<>"}
{"level":"info","ts":"2026-09-23T10:27:03.313754Z","caller":"traceutil/trace.go:171","msg":"trace[1295113421] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"206.48394ms","start":"2026-09-23T10:27:03.10724Z","end":"2026-09-23T10:27:03.313724Z","steps":["trace[1295113421] 'process raft request'  (duration: 206.231674ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.319858Z","caller":"traceutil/trace.go:171","msg":"trace[413171868] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"206.527271ms","start":"2026-09-23T10:27:03.113293Z","end":"2026-09-23T10:27:03.31982Z","steps":["trace[413171868] 'process raft request'  (duration: 206.394257ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320027Z","caller":"traceutil/trace.go:171","msg":"trace[1916962714] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"190.397089ms","start":"2026-09-23T10:27:03.129617Z","end":"2026-09-23T10:27:03.320014Z","steps":["trace[1916962714] 'process raft request'  (duration: 190.094481ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.319966Z","caller":"traceutil/trace.go:171","msg":"trace[667447549] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"184.86578ms","start":"2026-09-23T10:27:03.135073Z","end":"2026-09-23T10:27:03.319939Z","steps":["trace[667447549] 'process raft request'  (duration: 184.671964ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320113Z","caller":"traceutil/trace.go:171","msg":"trace[2037718318] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:547; }","duration":"144.746984ms","start":"2026-09-23T10:27:03.175349Z","end":"2026-09-23T10:27:03.320096Z","steps":["trace[2037718318] 'read index received'  (duration: 138.109681ms)","trace[2037718318] 'applied index is now lower than readState.Index'  (duration: 6.636713ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:27:03.32022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.866207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-23T10:27:03.320219Z","caller":"traceutil/trace.go:171","msg":"trace[1448583126] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"164.728066ms","start":"2026-09-23T10:27:03.155473Z","end":"2026-09-23T10:27:03.320201Z","steps":["trace[1448583126] 'process raft request'  (duration: 164.450989ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320246Z","caller":"traceutil/trace.go:171","msg":"trace[1153006533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"144.936929ms","start":"2026-09-23T10:27:03.175303Z","end":"2026-09-23T10:27:03.32024Z","steps":["trace[1153006533] 'agreement among raft nodes before linearized reading'  (duration: 144.853977ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320254Z","caller":"traceutil/trace.go:171","msg":"trace[50446514] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"181.201626ms","start":"2026-09-23T10:27:03.139047Z","end":"2026-09-23T10:27:03.320249Z","steps":["trace[50446514] 'process raft request'  (duration: 180.725974ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320304Z","caller":"traceutil/trace.go:171","msg":"trace[1238473013] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"164.894169ms","start":"2026-09-23T10:27:03.155402Z","end":"2026-09-23T10:27:03.320296Z","steps":["trace[1238473013] 'process raft request'  (duration: 164.4911ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320227Z","caller":"traceutil/trace.go:171","msg":"trace[1004842944] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"176.662091ms","start":"2026-09-23T10:27:03.143553Z","end":"2026-09-23T10:27:03.320215Z","steps":["trace[1004842944] 'process raft request'  (duration: 176.290743ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320361Z","caller":"traceutil/trace.go:171","msg":"trace[1806409626] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"150.112917ms","start":"2026-09-23T10:27:03.170241Z","end":"2026-09-23T10:27:03.320354Z","steps":["trace[1806409626] 'process raft request'  (duration: 149.710168ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320443Z","caller":"traceutil/trace.go:171","msg":"trace[259681072] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"147.963658ms","start":"2026-09-23T10:27:03.172474Z","end":"2026-09-23T10:27:03.320438Z","steps":["trace[259681072] 'process raft request'  (duration: 147.529758ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.320626Z","caller":"traceutil/trace.go:171","msg":"trace[1353275400] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"206.963421ms","start":"2026-09-23T10:27:03.113234Z","end":"2026-09-23T10:27:03.320198Z","steps":["trace[1353275400] 'process raft request'  (duration: 206.381217ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:03.83827Z","caller":"traceutil/trace.go:171","msg":"trace[1171930599] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"132.564732ms","start":"2026-09-23T10:27:03.705683Z","end":"2026-09-23T10:27:03.838248Z","steps":["trace[1171930599] 'process raft request'  (duration: 98.782462ms)","trace[1171930599] 'compare'  (duration: 33.475293ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:27:04.053385Z","caller":"traceutil/trace.go:171","msg":"trace[1663550416] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"124.644865ms","start":"2026-09-23T10:27:03.928717Z","end":"2026-09-23T10:27:04.053362Z","steps":["trace[1663550416] 'process raft request'  (duration: 39.919623ms)","trace[1663550416] 'compare'  (duration: 84.60581ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:27:04.135464Z","caller":"traceutil/trace.go:171","msg":"trace[1844505114] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"125.765143ms","start":"2026-09-23T10:27:04.009674Z","end":"2026-09-23T10:27:04.135439Z","steps":["trace[1844505114] 'process raft request'  (duration: 125.252161ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:27:04.321441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.606661ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129640659 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" mod_revision:557 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" value_size:17935 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:27:04.321577Z","caller":"traceutil/trace.go:171","msg":"trace[1545314282] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"252.732723ms","start":"2026-09-23T10:27:04.068834Z","end":"2026-09-23T10:27:04.321566Z","steps":["trace[1545314282] 'process raft request'  (duration: 252.686102ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:04.321687Z","caller":"traceutil/trace.go:171","msg":"trace[1253391153] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"261.287249ms","start":"2026-09-23T10:27:04.060352Z","end":"2026-09-23T10:27:04.32164Z","steps":["trace[1253391153] 'process raft request'  (duration: 154.32002ms)","trace[1253391153] 'compare'  (duration: 106.492039ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:27:04.378171Z","caller":"traceutil/trace.go:171","msg":"trace[1039044305] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"164.311609ms","start":"2026-09-23T10:27:04.213828Z","end":"2026-09-23T10:27:04.37814Z","steps":["trace[1039044305] 'process raft request'  (duration: 164.205536ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:04.573577Z","caller":"traceutil/trace.go:171","msg":"trace[770986976] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"205.959546ms","start":"2026-09-23T10:27:04.367598Z","end":"2026-09-23T10:27:04.573558Z","steps":["trace[770986976] 'process raft request'  (duration: 205.876075ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:04.573692Z","caller":"traceutil/trace.go:171","msg":"trace[12920202] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"245.067117ms","start":"2026-09-23T10:27:04.328603Z","end":"2026-09-23T10:27:04.573671Z","steps":["trace[12920202] 'process raft request'  (duration: 181.055044ms)","trace[12920202] 'compare'  (duration: 63.695696ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:27:04.76943Z","caller":"traceutil/trace.go:171","msg":"trace[620929811] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"184.7782ms","start":"2026-09-23T10:27:04.584629Z","end":"2026-09-23T10:27:04.769407Z","steps":["trace[620929811] 'process raft request'  (duration: 149.444697ms)","trace[620929811] 'compare'  (duration: 35.246241ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:27:04.832738Z","caller":"traceutil/trace.go:171","msg":"trace[1228628724] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"236.866588ms","start":"2026-09-23T10:27:04.595831Z","end":"2026-09-23T10:27:04.832697Z","steps":["trace[1228628724] 'process raft request'  (duration: 236.687104ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:04.877455Z","caller":"traceutil/trace.go:171","msg":"trace[2076202608] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"255.429554ms","start":"2026-09-23T10:27:04.622001Z","end":"2026-09-23T10:27:04.87743Z","steps":["trace[2076202608] 'read index received'  (duration: 112.087847ms)","trace[2076202608] 'applied index is now lower than readState.Index'  (duration: 143.340657ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:27:04.881179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.72853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"}
{"level":"info","ts":"2026-09-23T10:27:04.881269Z","caller":"traceutil/trace.go:171","msg":"trace[28430713] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:593; }","duration":"259.274733ms","start":"2026-09-23T10:27:04.621973Z","end":"2026-09-23T10:27:04.881247Z","steps":["trace[28430713] 'agreement among raft nodes before linearized reading'  (duration: 255.561737ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:27:05.199368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.854097ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129640671 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" mod_revision:593 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" value_size:65311 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:27:05.199497Z","caller":"traceutil/trace.go:171","msg":"trace[699939031] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"259.681163ms","start":"2026-09-23T10:27:04.939797Z","end":"2026-09-23T10:27:05.199478Z","steps":["trace[699939031] 'read index received'  (duration: 128.625091ms)","trace[699939031] 'applied index is now lower than readState.Index'  (duration: 131.054092ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:27:05.199579Z","caller":"traceutil/trace.go:171","msg":"trace[1011119381] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"284.33568ms","start":"2026-09-23T10:27:04.915193Z","end":"2026-09-23T10:27:05.199528Z","steps":["trace[1011119381] 'process raft request'  (duration: 153.216607ms)","trace[1011119381] 'compare'  (duration: 130.693963ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:27:05.199714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.904999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"}
{"level":"info","ts":"2026-09-23T10:27:05.199769Z","caller":"traceutil/trace.go:171","msg":"trace[186744596] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:596; }","duration":"259.99728ms","start":"2026-09-23T10:27:04.939755Z","end":"2026-09-23T10:27:05.199752Z","steps":["trace[186744596] 'agreement among raft nodes before linearized reading'  (duration: 259.811506ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:05.205969Z","caller":"traceutil/trace.go:171","msg":"trace[259798099] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"259.633412ms","start":"2026-09-23T10:27:04.946323Z","end":"2026-09-23T10:27:05.205957Z","steps":["trace[259798099] 'process raft request'  (duration: 259.54434ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:27:05.214813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.788267ms","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-23T10:27:05.214934Z","caller":"traceutil/trace.go:171","msg":"trace[94283866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"233.96753ms","start":"2026-09-23T10:27:04.980947Z","end":"2026-09-23T10:27:05.214915Z","steps":["trace[94283866] 'agreement among raft nodes before linearized reading'  (duration: 233.757625ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:06.632487Z","caller":"traceutil/trace.go:171","msg":"trace[1280895453] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"335.500548ms","start":"2026-09-23T10:27:06.29695Z","end":"2026-09-23T10:27:06.632451Z","steps":["trace[1280895453] 'process raft request'  (duration: 335.237083ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:27:06.632668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:27:06.296932Z","time spent":"335.643813ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" mod_revision:603 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" value_size:15570 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-09-23T10:27:10.332444Z","caller":"traceutil/trace.go:171","msg":"trace[248346113] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"129.854291ms","start":"2026-09-23T10:27:10.202572Z","end":"2026-09-23T10:27:10.332426Z","steps":["trace[248346113] 'read index received'  (duration: 128.838328ms)","trace[248346113] 'applied index is now lower than readState.Index'  (duration: 1.015313ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:27:10.332511Z","caller":"traceutil/trace.go:171","msg":"trace[546916574] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"206.710035ms","start":"2026-09-23T10:27:10.125759Z","end":"2026-09-23T10:27:10.332469Z","steps":["trace[546916574] 'process raft request'  (duration: 205.854925ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:27:10.332681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.087367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7klcp\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-09-23T10:27:10.332704Z","caller":"traceutil/trace.go:171","msg":"trace[1923031139] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7klcp; range_end:; response_count:1; response_revision:627; }","duration":"130.161728ms","start":"2026-09-23T10:27:10.202535Z","end":"2026-09-23T10:27:10.332696Z","steps":["trace[1923031139] 'agreement among raft nodes before linearized reading'  (duration: 129.977474ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:27:10.954744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.566178ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129640836 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/cilium-7klcp\" mod_revision:521 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-7klcp\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-7klcp\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:27:10.95496Z","caller":"traceutil/trace.go:171","msg":"trace[1607258935] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"520.294304ms","start":"2026-09-23T10:27:10.434648Z","end":"2026-09-23T10:27:10.954943Z","steps":["trace[1607258935] 'process raft request'  (duration: 520.241513ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:27:10.955033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:27:10.434625Z","time spent":"520.383336ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" value_size:729 >> failure:<>"}
{"level":"info","ts":"2026-09-23T10:27:10.955135Z","caller":"traceutil/trace.go:171","msg":"trace[1870442832] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"610.975307ms","start":"2026-09-23T10:27:10.344125Z","end":"2026-09-23T10:27:10.9551Z","steps":["trace[1870442832] 'process raft request'  (duration: 222.983009ms)","trace[1870442832] 'compare'  (duration: 387.413655ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:27:10.955257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:27:10.344099Z","time spent":"611.107181ms","remote":"127.0.0.1:59302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-7klcp\" mod_revision:521 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-7klcp\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-7klcp\" > >"}
{"level":"info","ts":"2026-09-23T10:27:12.492203Z","caller":"traceutil/trace.go:171","msg":"trace[669985868] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"123.041136ms","start":"2026-09-23T10:27:12.369141Z","end":"2026-09-23T10:27:12.492182Z","steps":["trace[669985868] 'process raft request'  (duration: 122.925922ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:12.50534Z","caller":"traceutil/trace.go:171","msg":"trace[1945064967] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"129.594642ms","start":"2026-09-23T10:27:12.375729Z","end":"2026-09-23T10:27:12.505324Z","steps":["trace[1945064967] 'process raft request'  (duration: 129.310026ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:27:55.662787Z","caller":"traceutil/trace.go:171","msg":"trace[1746694117] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"107.483381ms","start":"2026-09-23T10:27:55.555281Z","end":"2026-09-23T10:27:55.662764Z","steps":["trace[1746694117] 'process raft request'  (duration: 107.326938ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:28:10.388738Z","caller":"traceutil/trace.go:171","msg":"trace[788297391] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"120.644512ms","start":"2026-09-23T10:28:10.268064Z","end":"2026-09-23T10:28:10.388708Z","steps":["trace[788297391] 'process raft request'  (duration: 119.424149ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:28:45.191459Z","caller":"traceutil/trace.go:171","msg":"trace[1966192412] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"192.571532ms","start":"2026-09-23T10:28:44.998864Z","end":"2026-09-23T10:28:45.191435Z","steps":["trace[1966192412] 'read index received'  (duration: 192.371199ms)","trace[1966192412] 'applied index is now lower than readState.Index'  (duration: 199.283µs)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:28:45.191508Z","caller":"traceutil/trace.go:171","msg":"trace[1229636779] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"200.646126ms","start":"2026-09-23T10:28:44.990841Z","end":"2026-09-23T10:28:45.191487Z","steps":["trace[1229636779] 'process raft request'  (duration: 200.455073ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:28:45.191675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.964798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-09-23T10:28:45.191779Z","caller":"traceutil/trace.go:171","msg":"trace[327767344] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"104.017079ms","start":"2026-09-23T10:28:45.087685Z","end":"2026-09-23T10:28:45.191702Z","steps":["trace[327767344] 'agreement among raft nodes before linearized reading'  (duration: 103.876237ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:28:45.191898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.99119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b0ce9d2-4504-4be0-830a-377d34b52983\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-09-23T10:28:45.192014Z","caller":"traceutil/trace.go:171","msg":"trace[934834716] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b0ce9d2-4504-4be0-830a-377d34b52983; range_end:; response_count:1; response_revision:1881; }","duration":"193.179873ms","start":"2026-09-23T10:28:44.998814Z","end":"2026-09-23T10:28:45.191994Z","steps":["trace[934834716] 'agreement among raft nodes before linearized reading'  (duration: 192.746436ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:28:45.694973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.030554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-09-23T10:28:45.695054Z","caller":"traceutil/trace.go:171","msg":"trace[749006716] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1881; }","duration":"319.156095ms","start":"2026-09-23T10:28:45.375881Z","end":"2026-09-23T10:28:45.695037Z","steps":["trace[749006716] 'range keys from in-memory index tree'  (duration: 318.753789ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:28:45.695081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:28:45.375862Z","time spent":"319.211847ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"info","ts":"2026-09-23T10:28:45.695354Z","caller":"traceutil/trace.go:171","msg":"trace[467340703] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"114.717292ms","start":"2026-09-23T10:28:45.58063Z","end":"2026-09-23T10:28:45.695347Z","steps":["trace[467340703] 'read index received'  (duration: 45.545254ms)","trace[467340703] 'applied index is now lower than readState.Index'  (duration: 69.171378ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:28:45.695445Z","caller":"traceutil/trace.go:171","msg":"trace[1875135924] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"319.508512ms","start":"2026-09-23T10:28:45.375915Z","end":"2026-09-23T10:28:45.695424Z","steps":["trace[1875135924] 'process raft request'  (duration: 250.324325ms)","trace[1875135924] 'compare'  (duration: 68.452543ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:28:45.695477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.854444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-23T10:28:45.695559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:28:45.37589Z","time spent":"319.607443ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:1863 > 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-23T10:28:45.695578Z","caller":"traceutil/trace.go:171","msg":"trace[1356981894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"114.992115ms","start":"2026-09-23T10:28:45.580577Z","end":"2026-09-23T10:28:45.69557Z","steps":["trace[1356981894] 'agreement among raft nodes before linearized reading'  (duration: 114.870153ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:28:45.823496Z","caller":"traceutil/trace.go:171","msg":"trace[1561914324] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"120.199437ms","start":"2026-09-23T10:28:45.70327Z","end":"2026-09-23T10:28:45.823469Z","steps":["trace[1561914324] 'process raft request'  (duration: 112.418659ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:28:46.229886Z","caller":"traceutil/trace.go:171","msg":"trace[1017582747] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"167.303118ms","start":"2026-09-23T10:28:46.062565Z","end":"2026-09-23T10:28:46.229869Z","steps":["trace[1017582747] 'process raft request'  (duration: 164.158531ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:28:46.230111Z","caller":"traceutil/trace.go:171","msg":"trace[734584323] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"165.853042ms","start":"2026-09-23T10:28:46.064218Z","end":"2026-09-23T10:28:46.230071Z","steps":["trace[734584323] 'process raft request'  (duration: 165.622198ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:21.983956Z","caller":"traceutil/trace.go:171","msg":"trace[530816234] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2208; }","duration":"106.932711ms","start":"2026-09-23T10:29:21.877006Z","end":"2026-09-23T10:29:21.983939Z","steps":["trace[530816234] 'read index received'  (duration: 106.83057ms)","trace[530816234] 'applied index is now lower than readState.Index'  (duration: 101.241µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:21.984092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.068543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-09-23T10:29:21.984112Z","caller":"traceutil/trace.go:171","msg":"trace[252408454] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2129; }","duration":"107.120695ms","start":"2026-09-23T10:29:21.876984Z","end":"2026-09-23T10:29:21.984105Z","steps":["trace[252408454] 'agreement among raft nodes before linearized reading'  (duration: 107.023673ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:21.984186Z","caller":"traceutil/trace.go:171","msg":"trace[484089470] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"123.801342ms","start":"2026-09-23T10:29:21.860378Z","end":"2026-09-23T10:29:21.984179Z","steps":["trace[484089470] 'process raft request'  (duration: 123.431566ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:22.147075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.048337ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129643875 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2119 > 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-23T10:29:22.147185Z","caller":"traceutil/trace.go:171","msg":"trace[1523174623] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2209; }","duration":"126.231333ms","start":"2026-09-23T10:29:22.020934Z","end":"2026-09-23T10:29:22.147165Z","steps":["trace[1523174623] 'read index received'  (duration: 20.498015ms)","trace[1523174623] 'applied index is now lower than readState.Index'  (duration: 105.731728ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:22.147313Z","caller":"traceutil/trace.go:171","msg":"trace[945123628] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"158.94734ms","start":"2026-09-23T10:29:21.988324Z","end":"2026-09-23T10:29:22.147271Z","steps":["trace[945123628] 'process raft request'  (duration: 53.17169ms)","trace[945123628] 'compare'  (duration: 104.833783ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:22.14747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.525939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-23T10:29:22.147539Z","caller":"traceutil/trace.go:171","msg":"trace[1780744881] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2130; }","duration":"126.62343ms","start":"2026-09-23T10:29:22.020906Z","end":"2026-09-23T10:29:22.147529Z","steps":["trace[1780744881] 'agreement among raft nodes before linearized reading'  (duration: 126.449707ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:22.52329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.060673ms","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-23T10:29:22.523371Z","caller":"traceutil/trace.go:171","msg":"trace[1825124855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2133; }","duration":"103.187255ms","start":"2026-09-23T10:29:22.420167Z","end":"2026-09-23T10:29:22.523354Z","steps":["trace[1825124855] 'range keys from in-memory index tree'  (duration: 102.869649ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:24.595721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.131133Z","time spent":"464.582428ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-09-23T10:29:24.595762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014743181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-23T10:29:24.595837Z","caller":"traceutil/trace.go:171","msg":"trace[2098402337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2138; }","duration":"1.014849635s","start":"2026-09-23T10:29:23.580968Z","end":"2026-09-23T10:29:24.595817Z","steps":["trace[2098402337] 'range keys from in-memory index tree'  (duration: 1.014652019s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:24.595875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:23.580954Z","time spent":"1.014911448s","remote":"127.0.0.1:59110","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-23T10:29:24.596464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.369563ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129643901 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2129 > 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-23T10:29:24.596568Z","caller":"traceutil/trace.go:171","msg":"trace[1205773384] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"607.769618ms","start":"2026-09-23T10:29:23.988777Z","end":"2026-09-23T10:29:24.596547Z","steps":["trace[1205773384] 'process raft request'  (duration: 235.214827ms)","trace[1205773384] 'compare'  (duration: 371.980087ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:24.596621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:23.988746Z","time spent":"607.84957ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2129 > 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-23T10:29:25.455944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.896574ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129643902 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:2088 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:471 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:29:25.456127Z","caller":"traceutil/trace.go:171","msg":"trace[254601617] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"1.374760613s","start":"2026-09-23T10:29:24.081338Z","end":"2026-09-23T10:29:25.456099Z","steps":["trace[254601617] 'process raft request'  (duration: 683.64378ms)","trace[254601617] 'compare'  (duration: 690.455735ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:25.456311Z","caller":"traceutil/trace.go:171","msg":"trace[111622238] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"1.305877932s","start":"2026-09-23T10:29:24.150416Z","end":"2026-09-23T10:29:25.456294Z","steps":["trace[111622238] 'read index received'  (duration: 73.567488ms)","trace[111622238] 'applied index is now lower than readState.Index'  (duration: 1.232309124s)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:25.456318Z","caller":"traceutil/trace.go:171","msg":"trace[810282078] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"1.219414377s","start":"2026-09-23T10:29:24.236879Z","end":"2026-09-23T10:29:25.456293Z","steps":["trace[810282078] 'process raft request'  (duration: 1.219184347s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:25.456447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.23685Z","time spent":"1.219543362s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2131 > 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":"warn","ts":"2026-09-23T10:29:25.457098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.081319Z","time spent":"1.374954641s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:2088 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:471 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-09-23T10:29:26.597969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000143621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-09-23T10:29:26.598106Z","caller":"traceutil/trace.go:171","msg":"trace[67496997] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000311288s","start":"2026-09-23T10:29:24.597762Z","end":"2026-09-23T10:29:26.598073Z","steps":["trace[67496997] 'agreement among raft nodes before linearized reading'  (duration: 2.000166132s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.598152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.597749Z","time spent":"2.000394241s","remote":"127.0.0.1:59108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-23T10:29:26.689435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.538990404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-09-23T10:29:26.689525Z","caller":"traceutil/trace.go:171","msg":"trace[391361733] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2141; }","duration":"2.539102198s","start":"2026-09-23T10:29:24.1504Z","end":"2026-09-23T10:29:26.689502Z","steps":["trace[391361733] 'agreement among raft nodes before linearized reading'  (duration: 1.305949644s)","trace[391361733] 'range keys from in-memory index tree'  (duration: 1.232935704s)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:26.689572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.15039Z","time spent":"2.539170201s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-09-23T10:29:26.690108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233985018s","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129643904 > lease_revoke:<id:32c3a0cdcd64b4ae>","response":"size:29"}
{"level":"warn","ts":"2026-09-23T10:29:26.690809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.039117805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-09-23T10:29:26.690846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.885178347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-09-23T10:29:26.690888Z","caller":"traceutil/trace.go:171","msg":"trace[1186481571] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2141; }","duration":"2.039257921s","start":"2026-09-23T10:29:24.651611Z","end":"2026-09-23T10:29:26.690869Z","steps":["trace[1186481571] 'agreement among raft nodes before linearized reading'  (duration: 2.039117504s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.690873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.110753261s","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-23T10:29:26.690923Z","caller":"traceutil/trace.go:171","msg":"trace[1718777889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2141; }","duration":"1.88526985s","start":"2026-09-23T10:29:24.805621Z","end":"2026-09-23T10:29:26.690891Z","steps":["trace[1718777889] 'agreement among raft nodes before linearized reading'  (duration: 1.885116324s)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.690813Z","caller":"traceutil/trace.go:171","msg":"trace[932192360] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2221; }","duration":"1.233820831s","start":"2026-09-23T10:29:25.456355Z","end":"2026-09-23T10:29:26.690176Z","steps":["trace[932192360] 'read index received'  (duration: 44.362µs)","trace[932192360] 'applied index is now lower than readState.Index'  (duration: 1.233775159s)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:26.690964Z","caller":"traceutil/trace.go:171","msg":"trace[616987189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2141; }","duration":"2.110866215s","start":"2026-09-23T10:29:24.580084Z","end":"2026-09-23T10:29:26.69095Z","steps":["trace[616987189] 'agreement among raft nodes before linearized reading'  (duration: 2.110659326s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.690962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.146771562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"warn","ts":"2026-09-23T10:29:26.691012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.983043176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-23T10:29:26.691042Z","caller":"traceutil/trace.go:171","msg":"trace[1240155191] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2141; }","duration":"1.983098719s","start":"2026-09-23T10:29:24.707934Z","end":"2026-09-23T10:29:26.691033Z","steps":["trace[1240155191] 'agreement among raft nodes before linearized reading'  (duration: 1.983058438s)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.691036Z","caller":"traceutil/trace.go:171","msg":"trace[1322552680] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2141; }","duration":"2.146901258s","start":"2026-09-23T10:29:24.544118Z","end":"2026-09-23T10:29:26.691019Z","steps":["trace[1322552680] 'agreement among raft nodes before linearized reading'  (duration: 2.146693399s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.691043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.580071Z","time spent":"2.110963319s","remote":"127.0.0.1:59390","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-23T10:29:26.691059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.707892Z","time spent":"1.983162861s","remote":"127.0.0.1:51828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "}
{"level":"warn","ts":"2026-09-23T10:29:26.691074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.544097Z","time spent":"2.146969321s","remote":"127.0.0.1:54808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"warn","ts":"2026-09-23T10:29:26.691092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.909836764s","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-23T10:29:26.691118Z","caller":"traceutil/trace.go:171","msg":"trace[119012414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2141; }","duration":"1.909887736s","start":"2026-09-23T10:29:24.781221Z","end":"2026-09-23T10:29:26.691109Z","steps":["trace[119012414] 'agreement among raft nodes before linearized reading'  (duration: 1.909768501s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.691163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.781191Z","time spent":"1.909952049s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-23T10:29:26.690931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.44248221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-09-23T10:29:26.691258Z","caller":"traceutil/trace.go:171","msg":"trace[76197627] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2141; }","duration":"2.442864995s","start":"2026-09-23T10:29:24.248386Z","end":"2026-09-23T10:29:26.691251Z","steps":["trace[76197627] 'agreement among raft nodes before linearized reading'  (duration: 2.442468479s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.691285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.248366Z","time spent":"2.442913247s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-09-23T10:29:26.69097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.216956348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-23T10:29:26.691353Z","caller":"traceutil/trace.go:171","msg":"trace[654179826] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2141; }","duration":"2.217407737s","start":"2026-09-23T10:29:24.473932Z","end":"2026-09-23T10:29:26.69134Z","steps":["trace[654179826] 'agreement among raft nodes before linearized reading'  (duration: 2.217014451s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.691397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.473907Z","time spent":"2.217476009s","remote":"127.0.0.1:59266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "}
{"level":"warn","ts":"2026-09-23T10:29:26.690975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.805605Z","time spent":"1.885359824s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-09-23T10:29:26.690931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.651585Z","time spent":"2.039335394s","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "}
{"level":"warn","ts":"2026-09-23T10:29:26.691671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.111149636s","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-23T10:29:26.691837Z","caller":"traceutil/trace.go:171","msg":"trace[845952729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2141; }","duration":"2.111365256s","start":"2026-09-23T10:29:24.580444Z","end":"2026-09-23T10:29:26.691809Z","steps":["trace[845952729] 'agreement among raft nodes before linearized reading'  (duration: 2.110395086s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.691936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:24.580438Z","time spent":"2.11148367s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-09-23T10:29:26.727452Z","caller":"traceutil/trace.go:171","msg":"trace[961259791] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"122.070456ms","start":"2026-09-23T10:29:26.605361Z","end":"2026-09-23T10:29:26.727431Z","steps":["trace[961259791] 'process raft request'  (duration: 121.92875ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.897083Z","caller":"traceutil/trace.go:171","msg":"trace[1099188324] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"288.540569ms","start":"2026-09-23T10:29:26.608516Z","end":"2026-09-23T10:29:26.897057Z","steps":["trace[1099188324] 'process raft request'  (duration: 288.33433ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.897201Z","caller":"traceutil/trace.go:171","msg":"trace[1438122465] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"202.145256ms","start":"2026-09-23T10:29:26.695031Z","end":"2026-09-23T10:29:26.897177Z","steps":["trace[1438122465] 'process raft request'  (duration: 201.99178ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.897206Z","caller":"traceutil/trace.go:171","msg":"trace[2102067296] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"202.131726ms","start":"2026-09-23T10:29:26.695031Z","end":"2026-09-23T10:29:26.897163Z","steps":["trace[2102067296] 'process raft request'  (duration: 201.937578ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.897466Z","caller":"traceutil/trace.go:171","msg":"trace[376799474] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"202.316134ms","start":"2026-09-23T10:29:26.695111Z","end":"2026-09-23T10:29:26.897427Z","steps":["trace[376799474] 'process raft request'  (duration: 201.944888ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.897757Z","caller":"traceutil/trace.go:171","msg":"trace[1309139767] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"202.588675ms","start":"2026-09-23T10:29:26.695148Z","end":"2026-09-23T10:29:26.897736Z","steps":["trace[1309139767] 'process raft request'  (duration: 201.96118ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.897916Z","caller":"traceutil/trace.go:171","msg":"trace[1242178046] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"200.807831ms","start":"2026-09-23T10:29:26.697094Z","end":"2026-09-23T10:29:26.897902Z","steps":["trace[1242178046] 'process raft request'  (duration: 200.739918ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.89801Z","caller":"traceutil/trace.go:171","msg":"trace[1529736666] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"202.548124ms","start":"2026-09-23T10:29:26.695454Z","end":"2026-09-23T10:29:26.898002Z","steps":["trace[1529736666] 'process raft request'  (duration: 202.188588ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.898141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208173291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-23T10:29:26.898154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.043186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-23T10:29:26.898187Z","caller":"traceutil/trace.go:171","msg":"trace[75400224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2149; }","duration":"1.208274235s","start":"2026-09-23T10:29:25.689901Z","end":"2026-09-23T10:29:26.898175Z","steps":["trace[75400224] 'agreement among raft nodes before linearized reading'  (duration: 1.208125329s)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:26.898271Z","caller":"traceutil/trace.go:171","msg":"trace[1220094920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2149; }","duration":"286.151911ms","start":"2026-09-23T10:29:26.612039Z","end":"2026-09-23T10:29:26.898191Z","steps":["trace[1220094920] 'agreement among raft nodes before linearized reading'  (duration: 285.864768ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.898218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:25.689838Z","time spent":"1.208370959s","remote":"127.0.0.1:59110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-09-23T10:29:26.898292Z","caller":"traceutil/trace.go:171","msg":"trace[1937838268] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2222; }","duration":"207.28677ms","start":"2026-09-23T10:29:26.690962Z","end":"2026-09-23T10:29:26.898248Z","steps":["trace[1937838268] 'read index received'  (duration: 36.422946ms)","trace[1937838268] 'applied index is now lower than readState.Index'  (duration: 170.488648ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:26.898461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356246419s","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-23T10:29:26.898719Z","caller":"traceutil/trace.go:171","msg":"trace[1958677245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2149; }","duration":"1.356565141s","start":"2026-09-23T10:29:25.542134Z","end":"2026-09-23T10:29:26.898699Z","steps":["trace[1958677245] 'agreement among raft nodes before linearized reading'  (duration: 1.356161915s)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:26.898745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:25.5421Z","time spent":"1.356635604s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-09-23T10:29:26.900256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.617567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-23T10:29:26.900382Z","caller":"traceutil/trace.go:171","msg":"trace[652601481] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2149; }","duration":"158.785594ms","start":"2026-09-23T10:29:26.741572Z","end":"2026-09-23T10:29:26.900358Z","steps":["trace[652601481] 'agreement among raft nodes before linearized reading'  (duration: 156.816543ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.003928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.741971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"}
{"level":"warn","ts":"2026-09-23T10:29:29.003931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.016772ms","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-23T10:29:29.003991Z","caller":"traceutil/trace.go:171","msg":"trace[1164069506] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2154; }","duration":"101.843195ms","start":"2026-09-23T10:29:28.902124Z","end":"2026-09-23T10:29:29.003967Z","steps":["trace[1164069506] 'range keys from in-memory index tree'  (duration: 101.547874ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:29.003995Z","caller":"traceutil/trace.go:171","msg":"trace[1874771331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2154; }","duration":"101.090045ms","start":"2026-09-23T10:29:28.902888Z","end":"2026-09-23T10:29:29.003978Z","steps":["trace[1874771331] 'range keys from in-memory index tree'  (duration: 100.884066ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.004141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.46266ms","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-23T10:29:29.004158Z","caller":"traceutil/trace.go:171","msg":"trace[626916098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2154; }","duration":"101.504542ms","start":"2026-09-23T10:29:28.902649Z","end":"2026-09-23T10:29:29.004154Z","steps":["trace[626916098] 'range keys from in-memory index tree'  (duration: 101.192459ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.004127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.904417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"warn","ts":"2026-09-23T10:29:29.004236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.730351ms","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-23T10:29:29.004271Z","caller":"traceutil/trace.go:171","msg":"trace[1471000913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2154; }","duration":"101.775382ms","start":"2026-09-23T10:29:28.90249Z","end":"2026-09-23T10:29:29.004266Z","steps":["trace[1471000913] 'range keys from in-memory index tree'  (duration: 101.284163ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:29.004252Z","caller":"traceutil/trace.go:171","msg":"trace[1447017008] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2154; }","duration":"101.064213ms","start":"2026-09-23T10:29:28.90316Z","end":"2026-09-23T10:29:29.004224Z","steps":["trace[1447017008] 'range keys from in-memory index tree'  (duration: 100.626715ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.004211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.637527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-09-23T10:29:29.004681Z","caller":"traceutil/trace.go:171","msg":"trace[1380763782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2154; }","duration":"102.101226ms","start":"2026-09-23T10:29:28.902571Z","end":"2026-09-23T10:29:29.004672Z","steps":["trace[1380763782] 'range keys from in-memory index tree'  (duration: 101.242551ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.321524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.978486ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129643943 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:2150 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:29:29.321775Z","caller":"traceutil/trace.go:171","msg":"trace[638705238] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"314.439689ms","start":"2026-09-23T10:29:29.007302Z","end":"2026-09-23T10:29:29.321742Z","steps":["trace[638705238] 'process raft request'  (duration: 148.15454ms)","trace[638705238] 'compare'  (duration: 165.862821ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:29.321935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:29.007288Z","time spent":"314.552783ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:2150 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-23T10:29:29.322174Z","caller":"traceutil/trace.go:171","msg":"trace[1086797133] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"314.820414ms","start":"2026-09-23T10:29:29.007338Z","end":"2026-09-23T10:29:29.322159Z","steps":["trace[1086797133] 'process raft request'  (duration: 314.304403ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.322236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:29.00732Z","time spent":"314.887607ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2145 > 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-23T10:29:29.322389Z","caller":"traceutil/trace.go:171","msg":"trace[351415924] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"314.630897ms","start":"2026-09-23T10:29:29.007741Z","end":"2026-09-23T10:29:29.322372Z","steps":["trace[351415924] 'process raft request'  (duration: 314.244661ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:29.322472Z","caller":"traceutil/trace.go:171","msg":"trace[2038958507] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"314.106214ms","start":"2026-09-23T10:29:29.008358Z","end":"2026-09-23T10:29:29.322465Z","steps":["trace[2038958507] 'process raft request'  (duration: 313.834444ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.322506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:29.008354Z","time spent":"314.134145ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:2148 > 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-23T10:29:29.322503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:29.007736Z","time spent":"314.71163ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:2146 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-23T10:29:29.322653Z","caller":"traceutil/trace.go:171","msg":"trace[795734481] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"314.00223ms","start":"2026-09-23T10:29:29.008635Z","end":"2026-09-23T10:29:29.322637Z","steps":["trace[795734481] 'process raft request'  (duration: 313.724189ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:29.322672Z","caller":"traceutil/trace.go:171","msg":"trace[1116923126] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"313.464889ms","start":"2026-09-23T10:29:29.009188Z","end":"2026-09-23T10:29:29.322653Z","steps":["trace[1116923126] 'process raft request'  (duration: 313.25216ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.322774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:29.008628Z","time spent":"314.095604ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:2144 > 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-23T10:29:29.323041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:29.009174Z","time spent":"313.562812ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2147 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-09-23T10:29:29.729283Z","caller":"traceutil/trace.go:171","msg":"trace[829070023] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"183.346006ms","start":"2026-09-23T10:29:29.545902Z","end":"2026-09-23T10:29:29.729248Z","steps":["trace[829070023] 'process raft request'  (duration: 183.138646ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:29.729273Z","caller":"traceutil/trace.go:171","msg":"trace[1263716371] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2242; }","duration":"150.750155ms","start":"2026-09-23T10:29:29.578471Z","end":"2026-09-23T10:29:29.729221Z","steps":["trace[1263716371] 'read index received'  (duration: 12.848855ms)","trace[1263716371] 'applied index is now lower than readState.Index'  (duration: 137.90048ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:29.729648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.148492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-23T10:29:29.729779Z","caller":"traceutil/trace.go:171","msg":"trace[1205612660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2162; }","duration":"151.262086ms","start":"2026-09-23T10:29:29.578437Z","end":"2026-09-23T10:29:29.729699Z","steps":["trace[1205612660] 'agreement among raft nodes before linearized reading'  (duration: 150.87657ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:29.912012Z","caller":"traceutil/trace.go:171","msg":"trace[86266986] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2244; }","duration":"180.99608ms","start":"2026-09-23T10:29:29.730997Z","end":"2026-09-23T10:29:29.911993Z","steps":["trace[86266986] 'read index received'  (duration: 82.146564ms)","trace[86266986] 'applied index is now lower than readState.Index'  (duration: 98.848655ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:29.912043Z","caller":"traceutil/trace.go:171","msg":"trace[879085767] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"319.776026ms","start":"2026-09-23T10:29:29.592238Z","end":"2026-09-23T10:29:29.912014Z","steps":["trace[879085767] 'process raft request'  (duration: 220.91601ms)","trace[879085767] 'compare'  (duration: 98.718831ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:29.912168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:29.592217Z","time spent":"319.880311ms","remote":"127.0.0.1:59156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/162.253.55.102\" mod_revision:2116 > success:<request_put:<key:\"/registry/masterleases/162.253.55.102\" value_size:67 lease:3657944128129643950 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.102\" > >"}
{"level":"warn","ts":"2026-09-23T10:29:29.912374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.950234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-09-23T10:29:29.912422Z","caller":"traceutil/trace.go:171","msg":"trace[1284075190] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2163; }","duration":"117.028427ms","start":"2026-09-23T10:29:29.795382Z","end":"2026-09-23T10:29:29.91241Z","steps":["trace[1284075190] 'agreement among raft nodes before linearized reading'  (duration: 116.809028ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:29.912541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.417636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-23T10:29:29.912568Z","caller":"traceutil/trace.go:171","msg":"trace[655925288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2163; }","duration":"181.588204ms","start":"2026-09-23T10:29:29.730971Z","end":"2026-09-23T10:29:29.912559Z","steps":["trace[655925288] 'agreement among raft nodes before linearized reading'  (duration: 181.114574ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:31.380153Z","caller":"traceutil/trace.go:171","msg":"trace[1419861970] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2246; }","duration":"131.164672ms","start":"2026-09-23T10:29:31.248976Z","end":"2026-09-23T10:29:31.38014Z","steps":["trace[1419861970] 'read index received'  (duration: 131.13662ms)","trace[1419861970] 'applied index is now lower than readState.Index'  (duration: 27.452µs)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:31.380214Z","caller":"traceutil/trace.go:171","msg":"trace[1999611883] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"322.813063ms","start":"2026-09-23T10:29:31.057385Z","end":"2026-09-23T10:29:31.380198Z","steps":["trace[1999611883] 'process raft request'  (duration: 322.649456ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:31.380359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.057367Z","time spent":"322.887265ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:2155 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-09-23T10:29:31.38038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.38553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-23T10:29:31.380409Z","caller":"traceutil/trace.go:171","msg":"trace[557000481] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2165; }","duration":"131.484494ms","start":"2026-09-23T10:29:31.248918Z","end":"2026-09-23T10:29:31.380402Z","steps":["trace[557000481] 'agreement among raft nodes before linearized reading'  (duration: 131.290236ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:31.964989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.531744ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129643968 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:2126 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:29:31.965109Z","caller":"traceutil/trace.go:171","msg":"trace[1028326343] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2247; }","duration":"584.889275ms","start":"2026-09-23T10:29:31.380198Z","end":"2026-09-23T10:29:31.965087Z","steps":["trace[1028326343] 'read index received'  (duration: 336.142432ms)","trace[1028326343] 'applied index is now lower than readState.Index'  (duration: 248.744743ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:31.965259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.929004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-09-23T10:29:31.965362Z","caller":"traceutil/trace.go:171","msg":"trace[1552153701] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2166; }","duration":"639.040719ms","start":"2026-09-23T10:29:31.326281Z","end":"2026-09-23T10:29:31.965322Z","steps":["trace[1552153701] 'agreement among raft nodes before linearized reading'  (duration: 638.84288ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:31.965396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.326261Z","time spent":"639.127753ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-09-23T10:29:31.96553Z","caller":"traceutil/trace.go:171","msg":"trace[1803079668] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"598.285319ms","start":"2026-09-23T10:29:31.367235Z","end":"2026-09-23T10:29:31.96552Z","steps":["trace[1803079668] 'process raft request'  (duration: 349.14974ms)","trace[1803079668] 'compare'  (duration: 248.372308ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:31.965597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.367225Z","time spent":"598.32629ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:2126 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-09-23T10:29:31.965614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.811619ms","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-23T10:29:31.965655Z","caller":"traceutil/trace.go:171","msg":"trace[2031456370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2166; }","duration":"639.865292ms","start":"2026-09-23T10:29:31.325778Z","end":"2026-09-23T10:29:31.965643Z","steps":["trace[2031456370] 'agreement among raft nodes before linearized reading'  (duration: 639.731987ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:31.965668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.896953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"warn","ts":"2026-09-23T10:29:31.965672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.856192ms","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-23T10:29:31.965694Z","caller":"traceutil/trace.go:171","msg":"trace[1777182415] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2166; }","duration":"639.951286ms","start":"2026-09-23T10:29:31.325735Z","end":"2026-09-23T10:29:31.965686Z","steps":["trace[1777182415] 'agreement among raft nodes before linearized reading'  (duration: 639.876593ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:31.965696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.325774Z","time spent":"639.901823ms","remote":"127.0.0.1:59390","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-23T10:29:31.965713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.325701Z","time spent":"640.007488ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"info","ts":"2026-09-23T10:29:31.965711Z","caller":"traceutil/trace.go:171","msg":"trace[536649153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2166; }","duration":"639.911663ms","start":"2026-09-23T10:29:31.325788Z","end":"2026-09-23T10:29:31.9657Z","steps":["trace[536649153] 'agreement among raft nodes before linearized reading'  (duration: 639.806269ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:31.965746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.325779Z","time spent":"639.958886ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-23T10:29:31.965761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.145254ms","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-23T10:29:31.96578Z","caller":"traceutil/trace.go:171","msg":"trace[158795134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2166; }","duration":"639.349151ms","start":"2026-09-23T10:29:31.326424Z","end":"2026-09-23T10:29:31.965774Z","steps":["trace[158795134] 'agreement among raft nodes before linearized reading'  (duration: 639.103541ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:31.965797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.326417Z","time spent":"639.373162ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-09-23T10:29:31.96649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.5637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-09-23T10:29:31.966546Z","caller":"traceutil/trace.go:171","msg":"trace[1899755183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2166; }","duration":"640.545229ms","start":"2026-09-23T10:29:31.325985Z","end":"2026-09-23T10:29:31.966531Z","steps":["trace[1899755183] 'agreement among raft nodes before linearized reading'  (duration: 639.198166ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:31.966617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.32598Z","time spent":"640.593452ms","remote":"127.0.0.1:59390","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-23T10:29:32.178728Z","caller":"traceutil/trace.go:171","msg":"trace[149978619] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"444.046953ms","start":"2026-09-23T10:29:31.734615Z","end":"2026-09-23T10:29:32.178662Z","steps":["trace[149978619] 'process raft request'  (duration: 419.608538ms)","trace[149978619] 'compare'  (duration: 24.045039ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:32.178847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.629563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-09-23T10:29:32.178883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.734564Z","time spent":"444.23451ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2162 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-09-23T10:29:32.178902Z","caller":"traceutil/trace.go:171","msg":"trace[39427753] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2167; }","duration":"317.714177ms","start":"2026-09-23T10:29:31.861175Z","end":"2026-09-23T10:29:32.178889Z","steps":["trace[39427753] 'agreement among raft nodes before linearized reading'  (duration: 317.624873ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:32.178932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.861167Z","time spent":"317.760288ms","remote":"127.0.0.1:59638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":3,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "}
{"level":"info","ts":"2026-09-23T10:29:32.178829Z","caller":"traceutil/trace.go:171","msg":"trace[1669516248] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"213.649723ms","start":"2026-09-23T10:29:31.96514Z","end":"2026-09-23T10:29:32.17879Z","steps":["trace[1669516248] 'read index received'  (duration: 189.438907ms)","trace[1669516248] 'applied index is now lower than readState.Index'  (duration: 24.206866ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:32.178968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.993038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-23T10:29:32.17908Z","caller":"traceutil/trace.go:171","msg":"trace[877336909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2167; }","duration":"599.135723ms","start":"2026-09-23T10:29:31.579916Z","end":"2026-09-23T10:29:32.179052Z","steps":["trace[877336909] 'agreement among raft nodes before linearized reading'  (duration: 598.971667ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:32.179172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.579902Z","time spent":"599.235418ms","remote":"127.0.0.1:59108","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-23T10:29:32.672786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.677779ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129643972 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:2158 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-23T10:29:32.673245Z","caller":"traceutil/trace.go:171","msg":"trace[918309420] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2249; }","duration":"494.211602ms","start":"2026-09-23T10:29:32.179012Z","end":"2026-09-23T10:29:32.673224Z","steps":["trace[918309420] 'read index received'  (duration: 278.855706ms)","trace[918309420] 'applied index is now lower than readState.Index'  (duration: 215.354636ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:32.673381Z","caller":"traceutil/trace.go:171","msg":"trace[1762196553] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"702.405874ms","start":"2026-09-23T10:29:31.970942Z","end":"2026-09-23T10:29:32.673348Z","steps":["trace[1762196553] 'process raft request'  (duration: 702.086681ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:32.673452Z","caller":"traceutil/trace.go:171","msg":"trace[2035041793] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"700.717605ms","start":"2026-09-23T10:29:31.972712Z","end":"2026-09-23T10:29:32.673429Z","steps":["trace[2035041793] 'process raft request'  (duration: 700.450004ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:32.67349Z","caller":"traceutil/trace.go:171","msg":"trace[451877347] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"702.844611ms","start":"2026-09-23T10:29:31.970625Z","end":"2026-09-23T10:29:32.67347Z","steps":["trace[451877347] 'process raft request'  (duration: 702.343602ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:32.673481Z","caller":"traceutil/trace.go:171","msg":"trace[1096589252] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"702.841462ms","start":"2026-09-23T10:29:31.970615Z","end":"2026-09-23T10:29:32.673456Z","steps":["trace[1096589252] 'process raft request'  (duration: 702.27857ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:32.673541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.970931Z","time spent":"702.528198ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:2156 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-09-23T10:29:32.673552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.972696Z","time spent":"700.805509ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:2159 > 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-23T10:29:32.673554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.399344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d7ec723d54bbe8\" ","response":"range_response_count:1 size:846"}
{"level":"warn","ts":"2026-09-23T10:29:32.673564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.970572Z","time spent":"702.953286ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:2160 > 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-23T10:29:32.673584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.970562Z","time spent":"702.978167ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2161 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-09-23T10:29:32.673604Z","caller":"traceutil/trace.go:171","msg":"trace[1150680106] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d7ec723d54bbe8; range_end:; response_count:1; response_revision:2173; }","duration":"390.510668ms","start":"2026-09-23T10:29:32.283082Z","end":"2026-09-23T10:29:32.673593Z","steps":["trace[1150680106] 'agreement among raft nodes before linearized reading'  (duration: 390.385423ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:32.673709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:32.28305Z","time spent":"390.637505ms","remote":"127.0.0.1:59198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d7ec723d54bbe8\" "}
{"level":"info","ts":"2026-09-23T10:29:32.6734Z","caller":"traceutil/trace.go:171","msg":"trace[1941595576] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"702.32569ms","start":"2026-09-23T10:29:31.971058Z","end":"2026-09-23T10:29:32.673383Z","steps":["trace[1941595576] 'process raft request'  (duration: 702.014428ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:32.673781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.97104Z","time spent":"702.705346ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2157 > 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-23T10:29:32.673944Z","caller":"traceutil/trace.go:171","msg":"trace[541204064] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"702.602262ms","start":"2026-09-23T10:29:31.970559Z","end":"2026-09-23T10:29:32.673162Z","steps":["trace[541204064] 'process raft request'  (duration: 487.436104ms)","trace[541204064] 'compare'  (duration: 214.583085ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:32.674022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.970537Z","time spent":"703.447017ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:2158 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-23T10:29:32.675228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.035836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-09-23T10:29:32.675346Z","caller":"traceutil/trace.go:171","msg":"trace[434002146] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"677.161941ms","start":"2026-09-23T10:29:31.998154Z","end":"2026-09-23T10:29:32.675316Z","steps":["trace[434002146] 'agreement among raft nodes before linearized reading'  (duration: 675.319006ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:32.675405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:31.998147Z","time spent":"677.244834ms","remote":"127.0.0.1:54808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"warn","ts":"2026-09-23T10:29:32.989566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.566638ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129643985 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2164 > 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-23T10:29:32.989893Z","caller":"traceutil/trace.go:171","msg":"trace[2112512349] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"156.430378ms","start":"2026-09-23T10:29:32.833394Z","end":"2026-09-23T10:29:32.989825Z","steps":["trace[2112512349] 'process raft request'  (duration: 18.520857ms)","trace[2112512349] 'compare'  (duration: 137.467264ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:34.834774Z","caller":"traceutil/trace.go:171","msg":"trace[2064802043] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"153.301593ms","start":"2026-09-23T10:29:34.68145Z","end":"2026-09-23T10:29:34.834752Z","steps":["trace[2064802043] 'process raft request'  (duration: 153.160818ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:34.885511Z","caller":"traceutil/trace.go:171","msg":"trace[628495358] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"203.660747ms","start":"2026-09-23T10:29:34.681822Z","end":"2026-09-23T10:29:34.885483Z","steps":["trace[628495358] 'process raft request'  (duration: 203.401547ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:34.885525Z","caller":"traceutil/trace.go:171","msg":"trace[1444871891] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"202.639926ms","start":"2026-09-23T10:29:34.682866Z","end":"2026-09-23T10:29:34.885505Z","steps":["trace[1444871891] 'process raft request'  (duration: 202.550103ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:34.88575Z","caller":"traceutil/trace.go:171","msg":"trace[639711714] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"202.524971ms","start":"2026-09-23T10:29:34.683214Z","end":"2026-09-23T10:29:34.885739Z","steps":["trace[639711714] 'process raft request'  (duration: 202.252821ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:34.886144Z","caller":"traceutil/trace.go:171","msg":"trace[2008534845] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"202.178748ms","start":"2026-09-23T10:29:34.683939Z","end":"2026-09-23T10:29:34.886117Z","steps":["trace[2008534845] 'process raft request'  (duration: 201.940018ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:34.886239Z","caller":"traceutil/trace.go:171","msg":"trace[870403878] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"202.560424ms","start":"2026-09-23T10:29:34.683373Z","end":"2026-09-23T10:29:34.885933Z","steps":["trace[870403878] 'process raft request'  (duration: 202.331074ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:35.08634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.224156ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3657944128129644013 > lease_revoke:<id:32c3a0cdcd64b53d>","response":"size:29"}
{"level":"info","ts":"2026-09-23T10:29:35.086688Z","caller":"traceutil/trace.go:171","msg":"trace[730479642] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2262; }","duration":"400.378623ms","start":"2026-09-23T10:29:34.686287Z","end":"2026-09-23T10:29:35.086666Z","steps":["trace[730479642] 'read index received'  (duration: 148.362855ms)","trace[730479642] 'applied index is now lower than readState.Index'  (duration: 252.014238ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:29:35.087001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.706817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-09-23T10:29:35.087035Z","caller":"traceutil/trace.go:171","msg":"trace[102173275] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"400.770418ms","start":"2026-09-23T10:29:34.686254Z","end":"2026-09-23T10:29:35.087024Z","steps":["trace[102173275] 'agreement among raft nodes before linearized reading'  (duration: 400.538459ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:35.087028Z","caller":"traceutil/trace.go:171","msg":"trace[332675786] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"233.716897ms","start":"2026-09-23T10:29:34.853287Z","end":"2026-09-23T10:29:35.087004Z","steps":["trace[332675786] 'process raft request'  (duration: 233.176026ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:35.087059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:34.686244Z","time spent":"400.809501ms","remote":"127.0.0.1:54808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-09-23T10:29:36.229153Z","caller":"traceutil/trace.go:171","msg":"trace[1458369777] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2273; }","duration":"136.351561ms","start":"2026-09-23T10:29:36.092769Z","end":"2026-09-23T10:29:36.229121Z","steps":["trace[1458369777] 'read index received'  (duration: 136.088561ms)","trace[1458369777] 'applied index is now lower than readState.Index'  (duration: 261.8µs)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:36.229245Z","caller":"traceutil/trace.go:171","msg":"trace[1385656677] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"138.876781ms","start":"2026-09-23T10:29:36.090336Z","end":"2026-09-23T10:29:36.229213Z","steps":["trace[1385656677] 'process raft request'  (duration: 138.504856ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:29:36.229466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.661494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-09-23T10:29:36.229562Z","caller":"traceutil/trace.go:171","msg":"trace[2044243904] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2190; }","duration":"136.80251ms","start":"2026-09-23T10:29:36.092712Z","end":"2026-09-23T10:29:36.229514Z","steps":["trace[2044243904] 'agreement among raft nodes before linearized reading'  (duration: 136.533269ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:37.127344Z","caller":"traceutil/trace.go:171","msg":"trace[1908490999] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"233.273387ms","start":"2026-09-23T10:29:36.894016Z","end":"2026-09-23T10:29:37.127289Z","steps":["trace[1908490999] 'process raft request'  (duration: 208.618322ms)","trace[1908490999] 'compare'  (duration: 24.139405ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:29:37.127464Z","caller":"traceutil/trace.go:171","msg":"trace[474797444] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"230.964236ms","start":"2026-09-23T10:29:36.896454Z","end":"2026-09-23T10:29:37.127418Z","steps":["trace[474797444] 'process raft request'  (duration: 230.520178ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:37.127659Z","caller":"traceutil/trace.go:171","msg":"trace[1933478415] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"231.147544ms","start":"2026-09-23T10:29:36.896503Z","end":"2026-09-23T10:29:37.127651Z","steps":["trace[1933478415] 'process raft request'  (duration: 230.959365ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:37.127564Z","caller":"traceutil/trace.go:171","msg":"trace[411036761] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"230.880283ms","start":"2026-09-23T10:29:36.896667Z","end":"2026-09-23T10:29:37.127547Z","steps":["trace[411036761] 'process raft request'  (duration: 230.84383ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:29:38.428846Z","caller":"traceutil/trace.go:171","msg":"trace[258827956] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"134.255396ms","start":"2026-09-23T10:29:38.294573Z","end":"2026-09-23T10:29:38.428828Z","steps":["trace[258827956] 'process raft request'  (duration: 134.140242ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:30:49.864231Z","caller":"traceutil/trace.go:171","msg":"trace[1719353522] linearizableReadLoop","detail":"{readStateIndex:2779; appliedIndex:2778; }","duration":"266.844724ms","start":"2026-09-23T10:30:49.597364Z","end":"2026-09-23T10:30:49.864209Z","steps":["trace[1719353522] 'read index received'  (duration: 266.662119ms)","trace[1719353522] 'applied index is now lower than readState.Index'  (duration: 181.655µs)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:30:49.864619Z","caller":"traceutil/trace.go:171","msg":"trace[520324413] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"274.368037ms","start":"2026-09-23T10:30:49.590212Z","end":"2026-09-23T10:30:49.86458Z","steps":["trace[520324413] 'process raft request'  (duration: 273.869711ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:30:49.864858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.476984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-09-23T10:30:49.864893Z","caller":"traceutil/trace.go:171","msg":"trace[25661869] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2678; }","duration":"267.518456ms","start":"2026-09-23T10:30:49.597362Z","end":"2026-09-23T10:30:49.864881Z","steps":["trace[25661869] 'agreement among raft nodes before linearized reading'  (duration: 267.410652ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:30:49.865064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.005577ms","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-23T10:30:49.865095Z","caller":"traceutil/trace.go:171","msg":"trace[1108240407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2678; }","duration":"264.048078ms","start":"2026-09-23T10:30:49.601039Z","end":"2026-09-23T10:30:49.865087Z","steps":["trace[1108240407] 'agreement among raft nodes before linearized reading'  (duration: 263.972585ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:30:49.865427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.475557ms","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-23T10:30:49.86546Z","caller":"traceutil/trace.go:171","msg":"trace[1590034241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2678; }","duration":"259.518419ms","start":"2026-09-23T10:30:49.605934Z","end":"2026-09-23T10:30:49.865452Z","steps":["trace[1590034241] 'agreement among raft nodes before linearized reading'  (duration: 259.441736ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:31:23.55435Z","caller":"traceutil/trace.go:171","msg":"trace[1313947487] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"118.912496ms","start":"2026-09-23T10:31:23.435402Z","end":"2026-09-23T10:31:23.554314Z","steps":["trace[1313947487] 'process raft request'  (duration: 118.728641ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:31:23.653004Z","caller":"traceutil/trace.go:171","msg":"trace[783507631] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"155.767033ms","start":"2026-09-23T10:31:23.497211Z","end":"2026-09-23T10:31:23.652978Z","steps":["trace[783507631] 'process raft request'  (duration: 75.582467ms)","trace[783507631] 'compare'  (duration: 80.018781ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:31:24.226899Z","caller":"traceutil/trace.go:171","msg":"trace[608763058] linearizableReadLoop","detail":"{readStateIndex:2972; appliedIndex:2971; }","duration":"181.269589ms","start":"2026-09-23T10:31:24.045608Z","end":"2026-09-23T10:31:24.226878Z","steps":["trace[608763058] 'read index received'  (duration: 181.086464ms)","trace[608763058] 'applied index is now lower than readState.Index'  (duration: 182.065µs)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:31:24.227012Z","caller":"traceutil/trace.go:171","msg":"trace[36352727] transaction","detail":"{read_only:false; response_revision:2865; number_of_response:1; }","duration":"193.181332ms","start":"2026-09-23T10:31:24.03382Z","end":"2026-09-23T10:31:24.227001Z","steps":["trace[36352727] 'process raft request'  (duration: 192.919005ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:31:24.227181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.991046ms","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-23T10:31:24.227242Z","caller":"traceutil/trace.go:171","msg":"trace[1876163612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2865; }","duration":"173.088508ms","start":"2026-09-23T10:31:24.054138Z","end":"2026-09-23T10:31:24.227227Z","steps":["trace[1876163612] 'agreement among raft nodes before linearized reading'  (duration: 172.950405ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:31:24.227389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.191502ms","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-23T10:31:24.227409Z","caller":"traceutil/trace.go:171","msg":"trace[562512650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2865; }","duration":"173.236753ms","start":"2026-09-23T10:31:24.054167Z","end":"2026-09-23T10:31:24.227404Z","steps":["trace[562512650] 'agreement among raft nodes before linearized reading'  (duration: 173.184112ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:31:24.227412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.180061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"}
{"level":"info","ts":"2026-09-23T10:31:24.227453Z","caller":"traceutil/trace.go:171","msg":"trace[1930496069] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2865; }","duration":"173.265243ms","start":"2026-09-23T10:31:24.054174Z","end":"2026-09-23T10:31:24.227439Z","steps":["trace[1930496069] 'agreement among raft nodes before linearized reading'  (duration: 173.1353ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:31:24.22758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.982108ms","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-23T10:31:24.227603Z","caller":"traceutil/trace.go:171","msg":"trace[762332921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2865; }","duration":"182.062051ms","start":"2026-09-23T10:31:24.045535Z","end":"2026-09-23T10:31:24.227597Z","steps":["trace[762332921] 'agreement among raft nodes before linearized reading'  (duration: 181.993979ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:31:24.331129Z","caller":"traceutil/trace.go:171","msg":"trace[1408616884] transaction","detail":"{read_only:false; response_revision:2866; number_of_response:1; }","duration":"100.14137ms","start":"2026-09-23T10:31:24.230963Z","end":"2026-09-23T10:31:24.331105Z","steps":["trace[1408616884] 'process raft request'  (duration: 99.902494ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:31:24.336437Z","caller":"traceutil/trace.go:171","msg":"trace[1000039613] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"104.701259ms","start":"2026-09-23T10:31:24.231718Z","end":"2026-09-23T10:31:24.336419Z","steps":["trace[1000039613] 'process raft request'  (duration: 104.568206ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:31:24.336798Z","caller":"traceutil/trace.go:171","msg":"trace[1196491938] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"105.028439ms","start":"2026-09-23T10:31:24.231758Z","end":"2026-09-23T10:31:24.336787Z","steps":["trace[1196491938] 'process raft request'  (duration: 104.626648ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:31:24.336929Z","caller":"traceutil/trace.go:171","msg":"trace[1855818535] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"103.735062ms","start":"2026-09-23T10:31:24.233181Z","end":"2026-09-23T10:31:24.336916Z","steps":["trace[1855818535] 'process raft request'  (duration: 103.424824ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:35:43.129827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.307392ms","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-23T10:35:43.129933Z","caller":"traceutil/trace.go:171","msg":"trace[971154618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4835; }","duration":"133.456855ms","start":"2026-09-23T10:35:42.996464Z","end":"2026-09-23T10:35:43.129921Z","steps":["trace[971154618] 'range keys from in-memory index tree'  (duration: 133.154749ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:35:43.130181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.519795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"}
{"level":"info","ts":"2026-09-23T10:35:43.130232Z","caller":"traceutil/trace.go:171","msg":"trace[1896742250] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4835; }","duration":"171.583897ms","start":"2026-09-23T10:35:42.958641Z","end":"2026-09-23T10:35:43.130225Z","steps":["trace[1896742250] 'range keys from in-memory index tree'  (duration: 171.462394ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:35:43.131053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.866718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"}
{"level":"info","ts":"2026-09-23T10:35:43.131112Z","caller":"traceutil/trace.go:171","msg":"trace[2102945153] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4835; }","duration":"100.959029ms","start":"2026-09-23T10:35:43.030142Z","end":"2026-09-23T10:35:43.131101Z","steps":["trace[2102945153] 'range keys from in-memory index tree'  (duration: 99.813166ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:35:47.826034Z","caller":"traceutil/trace.go:171","msg":"trace[1204133646] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5125; }","duration":"100.438566ms","start":"2026-09-23T10:35:47.72558Z","end":"2026-09-23T10:35:47.826019Z","steps":["trace[1204133646] 'read index received'  (duration: 5.769958ms)","trace[1204133646] 'applied index is now lower than readState.Index'  (duration: 94.667848ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:35:47.826068Z","caller":"traceutil/trace.go:171","msg":"trace[734397887] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"101.538137ms","start":"2026-09-23T10:35:47.724509Z","end":"2026-09-23T10:35:47.826047Z","steps":["trace[734397887] 'compare'  (duration: 94.534715ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:35:47.826166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.575129ms","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-23T10:35:47.826295Z","caller":"traceutil/trace.go:171","msg":"trace[1347860338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4938; }","duration":"100.742111ms","start":"2026-09-23T10:35:47.725545Z","end":"2026-09-23T10:35:47.826287Z","steps":["trace[1347860338] 'agreement among raft nodes before linearized reading'  (duration: 100.549777ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:35:47.876138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.48249ms","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-23T10:35:47.876372Z","caller":"traceutil/trace.go:171","msg":"trace[548556524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4939; }","duration":"150.740095ms","start":"2026-09-23T10:35:47.725613Z","end":"2026-09-23T10:35:47.876353Z","steps":["trace[548556524] 'agreement among raft nodes before linearized reading'  (duration: 150.364528ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:35:47.876152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.366605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-23T10:35:47.87666Z","caller":"traceutil/trace.go:171","msg":"trace[1143843686] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4939; }","duration":"146.890466ms","start":"2026-09-23T10:35:47.729754Z","end":"2026-09-23T10:35:47.876644Z","steps":["trace[1143843686] 'agreement among raft nodes before linearized reading'  (duration: 146.284354ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:35:52.916207Z","caller":"traceutil/trace.go:171","msg":"trace[755063203] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"167.258676ms","start":"2026-09-23T10:35:52.748671Z","end":"2026-09-23T10:35:52.91593Z","steps":["trace[755063203] 'process raft request'  (duration: 120.643284ms)","trace[755063203] 'compare'  (duration: 46.412148ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:36:05.131503Z","caller":"traceutil/trace.go:171","msg":"trace[1222290459] linearizableReadLoop","detail":"{readStateIndex:5342; appliedIndex:5341; }","duration":"146.502247ms","start":"2026-09-23T10:36:04.984976Z","end":"2026-09-23T10:36:05.131479Z","steps":["trace[1222290459] 'read index received'  (duration: 46.74862ms)","trace[1222290459] 'applied index is now lower than readState.Index'  (duration: 99.752677ms)"],"step_count":2}
{"level":"info","ts":"2026-09-23T10:36:05.131579Z","caller":"traceutil/trace.go:171","msg":"trace[1382066847] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"170.991415ms","start":"2026-09-23T10:36:04.960558Z","end":"2026-09-23T10:36:05.131549Z","steps":["trace[1382066847] 'process raft request'  (duration: 71.101705ms)","trace[1382066847] 'compare'  (duration: 99.626954ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:36:05.131861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.871054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-09-23T10:36:05.132274Z","caller":"traceutil/trace.go:171","msg":"trace[877833304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5147; }","duration":"147.331553ms","start":"2026-09-23T10:36:04.984918Z","end":"2026-09-23T10:36:05.13225Z","steps":["trace[877833304] 'agreement among raft nodes before linearized reading'  (duration: 146.67154ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:36:25.500868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2872}
{"level":"info","ts":"2026-09-23T10:36:25.592692Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2872,"took":"90.397815ms","hash":1727431790,"current-db-size-bytes":52654080,"current-db-size":"53 MB","current-db-size-in-use-bytes":28180480,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-23T10:36:25.592767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1727431790,"revision":2872,"compact-revision":-1}
{"level":"info","ts":"2026-09-23T10:36:26.175297Z","caller":"traceutil/trace.go:171","msg":"trace[1178398962] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"140.837721ms","start":"2026-09-23T10:36:26.034439Z","end":"2026-09-23T10:36:26.175276Z","steps":["trace[1178398962] 'process raft request'  (duration: 140.484224ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:36:29.670974Z","caller":"traceutil/trace.go:171","msg":"trace[1539429407] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"235.303982ms","start":"2026-09-23T10:36:29.435647Z","end":"2026-09-23T10:36:29.670951Z","steps":["trace[1539429407] 'process raft request'  (duration: 235.137389ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:36:29.670959Z","caller":"traceutil/trace.go:171","msg":"trace[1868887503] linearizableReadLoop","detail":"{readStateIndex:5675; appliedIndex:5674; }","duration":"180.040748ms","start":"2026-09-23T10:36:29.49089Z","end":"2026-09-23T10:36:29.670931Z","steps":["trace[1868887503] 'read index received'  (duration: 179.821723ms)","trace[1868887503] 'applied index is now lower than readState.Index'  (duration: 218.025µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-23T10:36:29.671358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.446446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.102\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-09-23T10:36:29.671424Z","caller":"traceutil/trace.go:171","msg":"trace[1969113270] range","detail":"{range_begin:/registry/masterleases/162.253.55.102; range_end:; response_count:1; response_revision:5472; }","duration":"180.559999ms","start":"2026-09-23T10:36:29.490848Z","end":"2026-09-23T10:36:29.671408Z","steps":["trace[1969113270] 'agreement among raft nodes before linearized reading'  (duration: 180.16321ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-23T10:36:29.954642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.715066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-23T10:36:29.954738Z","caller":"traceutil/trace.go:171","msg":"trace[1660312905] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5473; }","duration":"151.919681ms","start":"2026-09-23T10:36:29.802796Z","end":"2026-09-23T10:36:29.954716Z","steps":["trace[1660312905] 'count revisions from in-memory index tree'  (duration: 151.541723ms)"],"step_count":1}
{"level":"info","ts":"2026-09-23T10:41:25.508966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5361}
{"level":"info","ts":"2026-09-23T10:41:25.58946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5361,"took":"79.0109ms","hash":2129814153,"current-db-size-bytes":52654080,"current-db-size":"53 MB","current-db-size-in-use-bytes":18845696,"current-db-size-in-use":"19 MB"}
{"level":"info","ts":"2026-09-23T10:41:25.589522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2129814153,"revision":5361,"compact-revision":2872}