{"level":"warn","ts":"2026-10-15T14:12:20.82313Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:12:20.823224Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.118:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.118:2380","--initial-cluster=instance=https://162.253.55.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.118:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T14:12:20.823313Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:12:20.823325Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.118:2380"]} {"level":"info","ts":"2026-10-15T14:12:20.823369Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T14:12:20.824142Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"]} {"level":"info","ts":"2026-10-15T14:12:20.824231Z","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.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.118:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T14:12:20.832709Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.096447ms"} {"level":"info","ts":"2026-10-15T14:12:20.841933Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39c975536cfc1086","cluster-id":"ac0a95643fc422f0"} {"level":"info","ts":"2026-10-15T14:12:20.842011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:12:20.842042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 0"} {"level":"info","ts":"2026-10-15T14:12:20.842068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39c975536cfc1086 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:12:20.842088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 1"} {"level":"info","ts":"2026-10-15T14:12:20.842132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"} {"level":"warn","ts":"2026-10-15T14:12:20.851434Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:12:20.853769Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:12:20.855588Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-15T14:12:20.857822Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39c975536cfc1086","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:12:20.858763Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39c975536cfc1086","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:12:20.858648Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T14:12:20.85898Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T14:12:20.85899Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T14:12:20.860763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"} {"level":"info","ts":"2026-10-15T14:12:20.862455Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T14:12:20.862683Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39c975536cfc1086","initial-advertise-peer-urls":["https://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:12:20.862721Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:12:20.863165Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.118:2380"} {"level":"info","ts":"2026-10-15T14:12:20.863218Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.118:2380"} {"level":"info","ts":"2026-10-15T14:12:20.863435Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","added-peer-id":"39c975536cfc1086","added-peer-peer-urls":["https://162.253.55.118:2380"]} {"level":"info","ts":"2026-10-15T14:12:21.843134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:12:21.843204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:12:21.843226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgPreVoteResp from 39c975536cfc1086 at term 1"} {"level":"info","ts":"2026-10-15T14:12:21.843244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:12:21.843255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgVoteResp from 39c975536cfc1086 at term 2"} {"level":"info","ts":"2026-10-15T14:12:21.843273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became leader at term 2"} {"level":"info","ts":"2026-10-15T14:12:21.843302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39c975536cfc1086 elected leader 39c975536cfc1086 at term 2"} {"level":"info","ts":"2026-10-15T14:12:21.844454Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:12:21.845592Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:12:21.845607Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39c975536cfc1086","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.118:2379]}","request-path":"/0/members/39c975536cfc1086/attributes","cluster-id":"ac0a95643fc422f0","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:12:21.845835Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:12:21.846004Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:12:21.84602Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:12:21.846092Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:12:21.846483Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:12:21.846528Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:12:21.849883Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.118:2379"} {"level":"info","ts":"2026-10-15T14:12:21.852184Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:12:41.611623Z","caller":"traceutil/trace.go:171","msg":"trace[1316117142] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"231.586353ms","start":"2026-10-15T14:12:41.380006Z","end":"2026-10-15T14:12:41.611592Z","steps":["trace[1316117142] 'process raft request' (duration: 231.353538ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:12:45.408289Z","caller":"traceutil/trace.go:171","msg":"trace[1356947744] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"105.587255ms","start":"2026-10-15T14:12:45.302675Z","end":"2026-10-15T14:12:45.408262Z","steps":["trace[1356947744] 'process raft request' (duration: 102.961067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:06.263962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.632273ms","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-10-15T14:13:06.264349Z","caller":"traceutil/trace.go:171","msg":"trace[1089270371] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:612; }","duration":"113.053371ms","start":"2026-10-15T14:13:06.151276Z","end":"2026-10-15T14:13:06.264329Z","steps":["trace[1089270371] 'range keys from in-memory index tree' (duration: 112.473431ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:13:06.263992Z","caller":"traceutil/trace.go:171","msg":"trace[1786013371] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"106.191225ms","start":"2026-10-15T14:13:06.157764Z","end":"2026-10-15T14:13:06.263955Z","steps":["trace[1786013371] 'process raft request' (duration: 61.455596ms)","trace[1786013371] 'compare' (duration: 44.634107ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:13:12.480097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23726002s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:13:12.480311Z","caller":"traceutil/trace.go:171","msg":"trace[1278025689] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.293242405s","start":"2026-10-15T14:13:11.187054Z","end":"2026-10-15T14:13:12.480296Z","steps":["trace[1278025689] 'process raft request' (duration: 1.293135513s)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:13:12.480323Z","caller":"traceutil/trace.go:171","msg":"trace[1493145933] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.330411789s","start":"2026-10-15T14:13:11.149879Z","end":"2026-10-15T14:13:12.480291Z","steps":["trace[1493145933] 'process raft request' (duration: 92.453659ms)","trace[1493145933] 'compare' (duration: 1.236987936s)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:13:12.480334Z","caller":"traceutil/trace.go:171","msg":"trace[627530035] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"1.329749009s","start":"2026-10-15T14:13:11.15056Z","end":"2026-10-15T14:13:12.480309Z","steps":["trace[627530035] 'read index received' (duration: 91.8617ms)","trace[627530035] 'applied index is now lower than readState.Index' (duration: 1.237885299s)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:13:12.480385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:11.187032Z","time spent":"1.293317895s","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:13:12.480471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:11.149854Z","time spent":"1.330570131s","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:13:12.480557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.151677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T14:13:12.480591Z","caller":"traceutil/trace.go:171","msg":"trace[684709927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"713.237419ms","start":"2026-10-15T14:13:11.767344Z","end":"2026-10-15T14:13:12.480582Z","steps":["trace[684709927] 'agreement among raft nodes before linearized reading' (duration: 713.118997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:12.480644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.623202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xdj94\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-10-15T14:13:12.480664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:11.767322Z","time spent":"713.33435ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T14:13:12.480713Z","caller":"traceutil/trace.go:171","msg":"trace[1267092316] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xdj94; range_end:; response_count:1; response_revision:646; }","duration":"464.693483ms","start":"2026-10-15T14:13:12.015997Z","end":"2026-10-15T14:13:12.48069Z","steps":["trace[1267092316] 'agreement among raft nodes before linearized reading' (duration: 464.471269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:12.480694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330108775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-15T14:13:12.480718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.788474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-15T14:13:12.480768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:12.015935Z","time spent":"464.821015ms","remote":"127.0.0.1:43348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-xdj94\" "} {"level":"info","ts":"2026-10-15T14:13:12.480786Z","caller":"traceutil/trace.go:171","msg":"trace[188941028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"1.330210567s","start":"2026-10-15T14:13:11.150552Z","end":"2026-10-15T14:13:12.480762Z","steps":["trace[188941028] 'agreement among raft nodes before linearized reading' (duration: 1.329902332s)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:13:12.480802Z","caller":"traceutil/trace.go:171","msg":"trace[1543450683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"625.912966ms","start":"2026-10-15T14:13:11.854863Z","end":"2026-10-15T14:13:12.480776Z","steps":["trace[1543450683] 'agreement among raft nodes before linearized reading' (duration: 625.668812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:12.480829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:11.150488Z","time spent":"1.330332729s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T14:13:12.480856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:11.854843Z","time spent":"626.005377ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T14:13:12.777666Z","caller":"traceutil/trace.go:171","msg":"trace[817622098] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"292.209274ms","start":"2026-10-15T14:13:12.48543Z","end":"2026-10-15T14:13:12.777639Z","steps":["trace[817622098] 'process raft request' (duration: 198.896139ms)","trace[817622098] 'compare' (duration: 93.063781ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:13:13.6554Z","caller":"traceutil/trace.go:171","msg":"trace[1821460915] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.16971825s","start":"2026-10-15T14:13:12.485646Z","end":"2026-10-15T14:13:13.655364Z","steps":["trace[1821460915] 'process raft request' (duration: 1.169372404s)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:13:13.655464Z","caller":"traceutil/trace.go:171","msg":"trace[257222088] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.169669099s","start":"2026-10-15T14:13:12.485755Z","end":"2026-10-15T14:13:13.655424Z","steps":["trace[257222088] 'process raft request' (duration: 1.169504936s)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:13:13.655638Z","caller":"traceutil/trace.go:171","msg":"trace[1445221048] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:671; }","duration":"1.161831251s","start":"2026-10-15T14:13:12.493761Z","end":"2026-10-15T14:13:13.655592Z","steps":["trace[1445221048] 'read index received' (duration: 190.533585ms)","trace[1445221048] 'applied index is now lower than readState.Index' (duration: 971.296826ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:13:13.655737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:12.485637Z","time spent":"1.169849842s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:13:13.6558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162038564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:13:13.655837Z","caller":"traceutil/trace.go:171","msg":"trace[1996634142] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:650; }","duration":"1.162107925s","start":"2026-10-15T14:13:12.493717Z","end":"2026-10-15T14:13:13.655825Z","steps":["trace[1996634142] 'agreement among raft nodes before linearized reading' (duration: 1.162050594s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:13.655868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:12.493698Z","time spent":"1.162162536s","remote":"127.0.0.1:43402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-10-15T14:13:13.65575Z","caller":"traceutil/trace.go:171","msg":"trace[1377148082] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.154089656s","start":"2026-10-15T14:13:12.501651Z","end":"2026-10-15T14:13:13.655741Z","steps":["trace[1377148082] 'process raft request' (duration: 1.153654031s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:13.655996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:12.501636Z","time spent":"1.15431544s","remote":"127.0.0.1:43348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:13:13.656067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122440428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-15T14:13:13.656104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002795643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:13:13.656102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:12.485726Z","time spent":"1.169821432s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:13:13.65614Z","caller":"traceutil/trace.go:171","msg":"trace[1619784441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"1.002860383s","start":"2026-10-15T14:13:12.653268Z","end":"2026-10-15T14:13:13.656129Z","steps":["trace[1619784441] 'agreement among raft nodes before linearized reading' (duration: 1.002809903s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:13.656176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:12.653253Z","time spent":"1.002913055s","remote":"127.0.0.1:43152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T14:13:13.656203Z","caller":"traceutil/trace.go:171","msg":"trace[587844769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"1.122555429s","start":"2026-10-15T14:13:12.533571Z","end":"2026-10-15T14:13:13.656126Z","steps":["trace[587844769] 'agreement among raft nodes before linearized reading' (duration: 1.122309266s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:13.656362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:12.533557Z","time spent":"1.122789693s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T14:13:30.214986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.312793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:13:30.215096Z","caller":"traceutil/trace.go:171","msg":"trace[597607882] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"342.71998ms","start":"2026-10-15T14:13:29.872352Z","end":"2026-10-15T14:13:30.215072Z","steps":["trace[597607882] 'read index received' (duration: 55.187694ms)","trace[597607882] 'applied index is now lower than readState.Index' (duration: 287.531026ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:13:30.21535Z","caller":"traceutil/trace.go:171","msg":"trace[1392911237] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"455.222369ms","start":"2026-10-15T14:13:29.760114Z","end":"2026-10-15T14:13:30.215336Z","steps":["trace[1392911237] 'process raft request' (duration: 167.47964ms)","trace[1392911237] 'compare' (duration: 287.02155ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:13:30.215421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:29.760098Z","time spent":"455.289829ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:13:30.215529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.587753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T14:13:30.215605Z","caller":"traceutil/trace.go:171","msg":"trace[1987693309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"326.722494ms","start":"2026-10-15T14:13:29.888865Z","end":"2026-10-15T14:13:30.215588Z","steps":["trace[1987693309] 'agreement among raft nodes before linearized reading' (duration: 326.521861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:30.215645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:29.888843Z","time spent":"326.794226ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T14:13:30.215928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.582483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T14:13:30.215981Z","caller":"traceutil/trace.go:171","msg":"trace[212894281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:815; }","duration":"343.699993ms","start":"2026-10-15T14:13:29.872269Z","end":"2026-10-15T14:13:30.215969Z","steps":["trace[212894281] 'agreement among raft nodes before linearized reading' (duration: 343.543041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:30.216016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:13:29.87223Z","time spent":"343.777175ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T14:14:02.622724Z","caller":"traceutil/trace.go:171","msg":"trace[960159899] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"154.518164ms","start":"2026-10-15T14:14:02.468176Z","end":"2026-10-15T14:14:02.622694Z","steps":["trace[960159899] 'read index received' (duration: 154.287011ms)","trace[960159899] 'applied index is now lower than readState.Index' (duration: 230.113µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:02.623108Z","caller":"traceutil/trace.go:171","msg":"trace[1914388185] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"240.409561ms","start":"2026-10-15T14:14:02.382681Z","end":"2026-10-15T14:14:02.62309Z","steps":["trace[1914388185] 'process raft request' (duration: 239.869424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:02.623522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.084589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T14:14:02.623746Z","caller":"traceutil/trace.go:171","msg":"trace[1632601178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1225; }","duration":"149.397583ms","start":"2026-10-15T14:14:02.474328Z","end":"2026-10-15T14:14:02.623725Z","steps":["trace[1632601178] 'agreement among raft nodes before linearized reading' (duration: 149.012129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:02.623604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.416584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T14:14:02.624078Z","caller":"traceutil/trace.go:171","msg":"trace[1175511740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1225; }","duration":"155.9211ms","start":"2026-10-15T14:14:02.468125Z","end":"2026-10-15T14:14:02.624046Z","steps":["trace[1175511740] 'agreement among raft nodes before linearized reading' (duration: 155.349553ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:18.905436Z","caller":"traceutil/trace.go:171","msg":"trace[1107603558] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"164.741858ms","start":"2026-10-15T14:14:18.740665Z","end":"2026-10-15T14:14:18.905407Z","steps":["trace[1107603558] 'process raft request' (duration: 164.02129ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:45.009859Z","caller":"traceutil/trace.go:171","msg":"trace[1702099371] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"115.889494ms","start":"2026-10-15T14:14:44.893933Z","end":"2026-10-15T14:14:45.009822Z","steps":["trace[1702099371] 'process raft request' (duration: 115.668712ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:45.009839Z","caller":"traceutil/trace.go:171","msg":"trace[2056927937] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"122.883388ms","start":"2026-10-15T14:14:44.886911Z","end":"2026-10-15T14:14:45.009794Z","steps":["trace[2056927937] 'process raft request' (duration: 118.599703ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:50.008641Z","caller":"traceutil/trace.go:171","msg":"trace[1524919047] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"152.706346ms","start":"2026-10-15T14:14:49.855819Z","end":"2026-10-15T14:14:50.008525Z","steps":["trace[1524919047] 'process raft request' (duration: 108.659919ms)","trace[1524919047] 'compare' (duration: 43.847095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:14:50.580193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.244078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-10-15T14:14:50.580191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:50.230979Z","time spent":"349.20052ms","remote":"127.0.0.1:47824","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-15T14:14:50.580312Z","caller":"traceutil/trace.go:171","msg":"trace[1058927890] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1880; }","duration":"375.441741ms","start":"2026-10-15T14:14:50.204847Z","end":"2026-10-15T14:14:50.580289Z","steps":["trace[1058927890] 'range keys from in-memory index tree' (duration: 375.002466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:50.580358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:50.204783Z","time spent":"375.564153ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T14:14:50.580436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.483353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:50.580577Z","caller":"traceutil/trace.go:171","msg":"trace[1996793013] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1880; }","duration":"241.685004ms","start":"2026-10-15T14:14:50.338837Z","end":"2026-10-15T14:14:50.580522Z","steps":["trace[1996793013] 'count revisions from in-memory index tree' (duration: 241.294911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:50.580671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.628348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T14:14:50.580705Z","caller":"traceutil/trace.go:171","msg":"trace[1187924979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1880; }","duration":"391.704859ms","start":"2026-10-15T14:14:50.18899Z","end":"2026-10-15T14:14:50.580695Z","steps":["trace[1187924979] 'range keys from in-memory index tree' (duration: 391.441816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:50.580699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.649101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5b8c9f79-2247-44de-885e-1354c71779ae\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-15T14:14:50.580748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:50.188962Z","time spent":"391.77918ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T14:14:50.580783Z","caller":"traceutil/trace.go:171","msg":"trace[1886837011] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5b8c9f79-2247-44de-885e-1354c71779ae; range_end:; response_count:1; response_revision:1880; }","duration":"397.821443ms","start":"2026-10-15T14:14:50.182932Z","end":"2026-10-15T14:14:50.580753Z","steps":["trace[1886837011] 'range keys from in-memory index tree' (duration: 397.339608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:50.580851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:50.182878Z","time spent":"397.951283ms","remote":"127.0.0.1:43348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5b8c9f79-2247-44de-885e-1354c71779ae\" "} {"level":"info","ts":"2026-10-15T14:14:50.581758Z","caller":"traceutil/trace.go:171","msg":"trace[1661567642] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"217.539904ms","start":"2026-10-15T14:14:50.364192Z","end":"2026-10-15T14:14:50.581732Z","steps":["trace[1661567642] 'process raft request' (duration: 121.111685ms)","trace[1661567642] 'compare' (duration: 95.908094ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:14:50.807423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.303376ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T14:14:50.807858Z","caller":"traceutil/trace.go:171","msg":"trace[538694662] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"226.442577ms","start":"2026-10-15T14:14:50.581359Z","end":"2026-10-15T14:14:50.807802Z","steps":["trace[538694662] 'read index received' (duration: 108.622456ms)","trace[538694662] 'applied index is now lower than readState.Index' (duration: 117.811971ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:50.808019Z","caller":"traceutil/trace.go:171","msg":"trace[671771142] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"221.686717ms","start":"2026-10-15T14:14:50.586307Z","end":"2026-10-15T14:14:50.807994Z","steps":["trace[671771142] 'process raft request' (duration: 221.375434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:50.808291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.811366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-10-15T14:14:50.808387Z","caller":"traceutil/trace.go:171","msg":"trace[841838885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1882; }","duration":"241.954237ms","start":"2026-10-15T14:14:50.566416Z","end":"2026-10-15T14:14:50.80837Z","steps":["trace[841838885] 'agreement among raft nodes before linearized reading' (duration: 241.479112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:50.808531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.38229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:50.808367Z","caller":"traceutil/trace.go:171","msg":"trace[82512892] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"218.316403ms","start":"2026-10-15T14:14:50.590023Z","end":"2026-10-15T14:14:50.808339Z","steps":["trace[82512892] 'process raft request' (duration: 217.861998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:50.808625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.915569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:551"} {"level":"info","ts":"2026-10-15T14:14:50.808724Z","caller":"traceutil/trace.go:171","msg":"trace[257237475] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1883; }","duration":"223.053642ms","start":"2026-10-15T14:14:50.585647Z","end":"2026-10-15T14:14:50.808701Z","steps":["trace[257237475] 'agreement among raft nodes before linearized reading' (duration: 222.668737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:50.808625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.098646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-10-15T14:14:50.808903Z","caller":"traceutil/trace.go:171","msg":"trace[1975258934] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1883; }","duration":"208.423241ms","start":"2026-10-15T14:14:50.600462Z","end":"2026-10-15T14:14:50.808885Z","steps":["trace[1975258934] 'agreement among raft nodes before linearized reading' (duration: 207.953566ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:50.808657Z","caller":"traceutil/trace.go:171","msg":"trace[680567654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1883; }","duration":"156.542952ms","start":"2026-10-15T14:14:50.652089Z","end":"2026-10-15T14:14:50.808632Z","steps":["trace[680567654] 'agreement among raft nodes before linearized reading' (duration: 156.32228ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.121687Z","caller":"traceutil/trace.go:171","msg":"trace[962195408] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"191.975977ms","start":"2026-10-15T14:14:50.929665Z","end":"2026-10-15T14:14:51.121641Z","steps":["trace[962195408] 'process raft request' (duration: 127.923605ms)","trace[962195408] 'compare' (duration: 63.235604ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:51.121899Z","caller":"traceutil/trace.go:171","msg":"trace[759456210] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"191.978117ms","start":"2026-10-15T14:14:50.92989Z","end":"2026-10-15T14:14:51.121868Z","steps":["trace[759456210] 'process raft request' (duration: 191.31762ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:56.176036Z","caller":"traceutil/trace.go:171","msg":"trace[1772548144] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"133.260217ms","start":"2026-10-15T14:14:56.042747Z","end":"2026-10-15T14:14:56.176007Z","steps":["trace[1772548144] 'process raft request' (duration: 133.099785ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:58.723968Z","caller":"traceutil/trace.go:171","msg":"trace[588822780] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"157.712512ms","start":"2026-10-15T14:14:58.566224Z","end":"2026-10-15T14:14:58.723937Z","steps":["trace[588822780] 'process raft request' (duration: 157.49742ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:22.720159Z","caller":"traceutil/trace.go:171","msg":"trace[663150345] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"170.454961ms","start":"2026-10-15T14:15:22.549676Z","end":"2026-10-15T14:15:22.720131Z","steps":["trace[663150345] 'process raft request' (duration: 170.055225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:23.437884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.769313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T14:15:23.438017Z","caller":"traceutil/trace.go:171","msg":"trace[664909919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2151; }","duration":"115.97242ms","start":"2026-10-15T14:15:23.32202Z","end":"2026-10-15T14:15:23.437993Z","steps":["trace[664909919] 'range keys from in-memory index tree' (duration: 115.540594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:23.438154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.493484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T14:15:23.438244Z","caller":"traceutil/trace.go:171","msg":"trace[1139930230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2151; }","duration":"123.639829ms","start":"2026-10-15T14:15:23.314584Z","end":"2026-10-15T14:15:23.438224Z","steps":["trace[1139930230] 'range keys from in-memory index tree' (duration: 123.268805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:23.438765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.328427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T14:15:23.438883Z","caller":"traceutil/trace.go:171","msg":"trace[271971772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2151; }","duration":"118.543426ms","start":"2026-10-15T14:15:23.320311Z","end":"2026-10-15T14:15:23.438855Z","steps":["trace[271971772] 'range keys from in-memory index tree' (duration: 117.960434ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:18.248896Z","caller":"traceutil/trace.go:171","msg":"trace[269613165] transaction","detail":"{read_only:false; response_revision:2636; number_of_response:1; }","duration":"142.028031ms","start":"2026-10-15T14:16:18.106848Z","end":"2026-10-15T14:16:18.248876Z","steps":["trace[269613165] 'process raft request' (duration: 141.946259ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:18.248931Z","caller":"traceutil/trace.go:171","msg":"trace[67382883] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"142.962079ms","start":"2026-10-15T14:16:18.105946Z","end":"2026-10-15T14:16:18.248908Z","steps":["trace[67382883] 'process raft request' (duration: 115.712283ms)","trace[67382883] 'compare' (duration: 26.912236ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:45.795306Z","caller":"traceutil/trace.go:171","msg":"trace[1032301821] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"183.74631ms","start":"2026-10-15T14:16:45.611532Z","end":"2026-10-15T14:16:45.795278Z","steps":["trace[1032301821] 'process raft request' (duration: 183.558425ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:45.925509Z","caller":"traceutil/trace.go:171","msg":"trace[590854129] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"101.671007ms","start":"2026-10-15T14:16:45.823808Z","end":"2026-10-15T14:16:45.925479Z","steps":["trace[590854129] 'process raft request' (duration: 78.031419ms)","trace[590854129] 'compare' (duration: 23.455213ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:46.110771Z","caller":"traceutil/trace.go:171","msg":"trace[920030841] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"181.189233ms","start":"2026-10-15T14:16:45.929554Z","end":"2026-10-15T14:16:46.110743Z","steps":["trace[920030841] 'process raft request' (duration: 171.035933ms)","trace[920030841] 'compare' (duration: 10.010896ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:46.13047Z","caller":"traceutil/trace.go:171","msg":"trace[1572116856] linearizableReadLoop","detail":"{readStateIndex:2933; appliedIndex:2931; }","duration":"181.295594ms","start":"2026-10-15T14:16:45.949159Z","end":"2026-10-15T14:16:46.130454Z","steps":["trace[1572116856] 'read index received' (duration: 151.357847ms)","trace[1572116856] 'applied index is now lower than readState.Index' (duration: 29.936957ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:46.130562Z","caller":"traceutil/trace.go:171","msg":"trace[1936346381] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"200.646011ms","start":"2026-10-15T14:16:45.929897Z","end":"2026-10-15T14:16:46.130543Z","steps":["trace[1936346381] 'process raft request' (duration: 200.442776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:46.130774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.597872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T14:16:46.130808Z","caller":"traceutil/trace.go:171","msg":"trace[1899032592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2825; }","duration":"181.664813ms","start":"2026-10-15T14:16:45.949133Z","end":"2026-10-15T14:16:46.130798Z","steps":["trace[1899032592] 'agreement among raft nodes before linearized reading' (duration: 181.406346ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:10.888119Z","caller":"traceutil/trace.go:171","msg":"trace[2108819557] transaction","detail":"{read_only:false; response_revision:4353; number_of_response:1; }","duration":"126.241546ms","start":"2026-10-15T14:19:10.761853Z","end":"2026-10-15T14:19:10.888095Z","steps":["trace[2108819557] 'process raft request' (duration: 108.251887ms)","trace[2108819557] 'compare' (duration: 17.864208ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:10.96576Z","caller":"traceutil/trace.go:171","msg":"trace[1502852801] transaction","detail":"{read_only:false; response_revision:4354; number_of_response:1; }","duration":"141.034944ms","start":"2026-10-15T14:19:10.824699Z","end":"2026-10-15T14:19:10.965734Z","steps":["trace[1502852801] 'process raft request' (duration: 140.877531ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:11.191609Z","caller":"traceutil/trace.go:171","msg":"trace[1727276890] transaction","detail":"{read_only:false; response_revision:4355; number_of_response:1; }","duration":"298.132979ms","start":"2026-10-15T14:19:10.893428Z","end":"2026-10-15T14:19:11.191561Z","steps":["trace[1727276890] 'process raft request' (duration: 297.859264ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:11.191651Z","caller":"traceutil/trace.go:171","msg":"trace[262104275] transaction","detail":"{read_only:false; response_revision:4356; number_of_response:1; }","duration":"298.19314ms","start":"2026-10-15T14:19:10.893433Z","end":"2026-10-15T14:19:11.191626Z","steps":["trace[262104275] 'process raft request' (duration: 298.055648ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:11.219333Z","caller":"traceutil/trace.go:171","msg":"trace[96536946] transaction","detail":"{read_only:false; response_revision:4357; number_of_response:1; }","duration":"244.571254ms","start":"2026-10-15T14:19:10.974735Z","end":"2026-10-15T14:19:11.219306Z","steps":["trace[96536946] 'process raft request' (duration: 244.382671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:11.219914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.13845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:19:11.219964Z","caller":"traceutil/trace.go:171","msg":"trace[2080802389] linearizableReadLoop","detail":"{readStateIndex:4546; appliedIndex:4543; }","duration":"236.703493ms","start":"2026-10-15T14:19:10.982747Z","end":"2026-10-15T14:19:11.21945Z","steps":["trace[2080802389] 'read index received' (duration: 208.556314ms)","trace[2080802389] 'applied index is now lower than readState.Index' (duration: 28.145589ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:11.219998Z","caller":"traceutil/trace.go:171","msg":"trace[39871189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4358; }","duration":"237.271063ms","start":"2026-10-15T14:19:10.982708Z","end":"2026-10-15T14:19:11.219979Z","steps":["trace[39871189] 'agreement among raft nodes before linearized reading' (duration: 237.005878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:11.220247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.05438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-15T14:19:11.220292Z","caller":"traceutil/trace.go:171","msg":"trace[472344967] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4358; }","duration":"228.131921ms","start":"2026-10-15T14:19:10.992149Z","end":"2026-10-15T14:19:11.220281Z","steps":["trace[472344967] 'agreement among raft nodes before linearized reading' (duration: 227.901977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:11.220798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.312177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T14:19:11.220864Z","caller":"traceutil/trace.go:171","msg":"trace[1410324337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4358; }","duration":"146.44136ms","start":"2026-10-15T14:19:11.074408Z","end":"2026-10-15T14:19:11.220849Z","steps":["trace[1410324337] 'agreement among raft nodes before linearized reading' (duration: 146.203136ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:13.485898Z","caller":"traceutil/trace.go:171","msg":"trace[1935178267] linearizableReadLoop","detail":"{readStateIndex:4646; appliedIndex:4642; }","duration":"169.368221ms","start":"2026-10-15T14:19:13.316506Z","end":"2026-10-15T14:19:13.485874Z","steps":["trace[1935178267] 'read index received' (duration: 50.122412ms)","trace[1935178267] 'applied index is now lower than readState.Index' (duration: 119.244799ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:13.486219Z","caller":"traceutil/trace.go:171","msg":"trace[93544230] transaction","detail":"{read_only:false; response_revision:4456; number_of_response:1; }","duration":"195.219568ms","start":"2026-10-15T14:19:13.290983Z","end":"2026-10-15T14:19:13.486203Z","steps":["trace[93544230] 'process raft request' (duration: 194.857383ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:13.486273Z","caller":"traceutil/trace.go:171","msg":"trace[1862272283] transaction","detail":"{read_only:false; response_revision:4454; number_of_response:1; }","duration":"196.988938ms","start":"2026-10-15T14:19:13.289251Z","end":"2026-10-15T14:19:13.48624Z","steps":["trace[1862272283] 'process raft request' (duration: 185.04652ms)","trace[1862272283] 'compare' (duration: 10.990513ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:13.48654Z","caller":"traceutil/trace.go:171","msg":"trace[334111846] transaction","detail":"{read_only:false; response_revision:4455; number_of_response:1; }","duration":"195.647376ms","start":"2026-10-15T14:19:13.29088Z","end":"2026-10-15T14:19:13.486527Z","steps":["trace[334111846] 'process raft request' (duration: 194.911914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:13.486842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.318096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-15T14:19:13.486879Z","caller":"traceutil/trace.go:171","msg":"trace[465864749] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4456; }","duration":"170.395377ms","start":"2026-10-15T14:19:13.316473Z","end":"2026-10-15T14:19:13.486868Z","steps":["trace[465864749] 'agreement among raft nodes before linearized reading' (duration: 170.292675ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:13.918142Z","caller":"traceutil/trace.go:171","msg":"trace[1290382635] transaction","detail":"{read_only:false; response_revision:4460; number_of_response:1; }","duration":"132.731722ms","start":"2026-10-15T14:19:13.785383Z","end":"2026-10-15T14:19:13.918114Z","steps":["trace[1290382635] 'process raft request' (duration: 132.551139ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:15.095605Z","caller":"traceutil/trace.go:171","msg":"trace[403795835] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"259.517055ms","start":"2026-10-15T14:19:14.836046Z","end":"2026-10-15T14:19:15.095563Z","steps":["trace[403795835] 'process raft request' (duration: 259.369252ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:21.514611Z","caller":"traceutil/trace.go:171","msg":"trace[1667344475] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"184.331523ms","start":"2026-10-15T14:19:21.330259Z","end":"2026-10-15T14:19:21.514591Z","steps":["trace[1667344475] 'process raft request' (duration: 184.15821ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:21.713009Z","caller":"traceutil/trace.go:171","msg":"trace[670171133] linearizableReadLoop","detail":"{readStateIndex:4836; appliedIndex:4835; }","duration":"306.245144ms","start":"2026-10-15T14:19:21.406741Z","end":"2026-10-15T14:19:21.712987Z","steps":["trace[670171133] 'read index received' (duration: 107.879793ms)","trace[670171133] 'applied index is now lower than readState.Index' (duration: 198.364261ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:21.713259Z","caller":"traceutil/trace.go:171","msg":"trace[1327473021] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"333.949116ms","start":"2026-10-15T14:19:21.37916Z","end":"2026-10-15T14:19:21.713109Z","steps":["trace[1327473021] 'process raft request' (duration: 333.655781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:21.71341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:21.379136Z","time spent":"334.18672ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:19:21.713431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.920727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T14:19:21.713503Z","caller":"traceutil/trace.go:171","msg":"trace[478404633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4639; }","duration":"197.05035ms","start":"2026-10-15T14:19:21.516435Z","end":"2026-10-15T14:19:21.713485Z","steps":["trace[478404633] 'agreement among raft nodes before linearized reading' (duration: 196.822256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:21.713588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.809133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T14:19:21.713607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.170406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T14:19:21.713651Z","caller":"traceutil/trace.go:171","msg":"trace[30264449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4639; }","duration":"306.964345ms","start":"2026-10-15T14:19:21.406671Z","end":"2026-10-15T14:19:21.713635Z","steps":["trace[30264449] 'agreement among raft nodes before linearized reading' (duration: 306.778862ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:21.71366Z","caller":"traceutil/trace.go:171","msg":"trace[1873352590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4639; }","duration":"185.229427ms","start":"2026-10-15T14:19:21.528418Z","end":"2026-10-15T14:19:21.713647Z","steps":["trace[1873352590] 'agreement among raft nodes before linearized reading' (duration: 185.068294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:21.713683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:21.406637Z","time spent":"307.037857ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T14:19:21.713792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.312762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-15T14:19:21.713832Z","caller":"traceutil/trace.go:171","msg":"trace[1048413923] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4639; }","duration":"192.379993ms","start":"2026-10-15T14:19:21.521441Z","end":"2026-10-15T14:19:21.713821Z","steps":["trace[1048413923] 'agreement among raft nodes before linearized reading' (duration: 192.249421ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:22.269457Z","caller":"traceutil/trace.go:171","msg":"trace[144883171] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"122.318535ms","start":"2026-10-15T14:19:22.147103Z","end":"2026-10-15T14:19:22.269421Z","steps":["trace[144883171] 'process raft request' (duration: 73.488008ms)","trace[144883171] 'compare' (duration: 48.390419ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:26.34335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.579068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-10-15T14:19:26.343597Z","caller":"traceutil/trace.go:171","msg":"trace[627275067] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4722; }","duration":"129.803341ms","start":"2026-10-15T14:19:26.213711Z","end":"2026-10-15T14:19:26.343514Z","steps":["trace[627275067] 'range keys from in-memory index tree' (duration: 129.349284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:26.34362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.585094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-15T14:19:26.343678Z","caller":"traceutil/trace.go:171","msg":"trace[1089404153] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4722; }","duration":"109.654635ms","start":"2026-10-15T14:19:26.234008Z","end":"2026-10-15T14:19:26.343663Z","steps":["trace[1089404153] 'range keys from in-memory index tree' (duration: 109.457412ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:31.661038Z","caller":"traceutil/trace.go:171","msg":"trace[1028343873] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"115.017675ms","start":"2026-10-15T14:19:31.545993Z","end":"2026-10-15T14:19:31.661011Z","steps":["trace[1028343873] 'process raft request' (duration: 114.842313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:35.707775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.26887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:19:35.707873Z","caller":"traceutil/trace.go:171","msg":"trace[1019722866] linearizableReadLoop","detail":"{readStateIndex:5048; appliedIndex:5047; }","duration":"685.306817ms","start":"2026-10-15T14:19:35.02255Z","end":"2026-10-15T14:19:35.707857Z","steps":["trace[1019722866] 'read index received' (duration: 81.743891ms)","trace[1019722866] 'applied index is now lower than readState.Index' (duration: 603.561576ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:35.707998Z","caller":"traceutil/trace.go:171","msg":"trace[1274646825] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"768.200409ms","start":"2026-10-15T14:19:34.939749Z","end":"2026-10-15T14:19:35.707949Z","steps":["trace[1274646825] 'process raft request' (duration: 164.678925ms)","trace[1274646825] 'compare' (duration: 602.960085ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:35.708145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:34.939729Z","time spent":"768.351422ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:19:35.70827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.721063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"info","ts":"2026-10-15T14:19:35.708362Z","caller":"traceutil/trace.go:171","msg":"trace[1964943692] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4843; }","duration":"685.864626ms","start":"2026-10-15T14:19:35.022487Z","end":"2026-10-15T14:19:35.708351Z","steps":["trace[1964943692] 'agreement among raft nodes before linearized reading' (duration: 685.545551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:35.708449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.022467Z","time spent":"685.971167ms","remote":"127.0.0.1:43348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10539,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-15T14:19:35.864642Z","caller":"traceutil/trace.go:171","msg":"trace[444057992] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"227.588936ms","start":"2026-10-15T14:19:35.637031Z","end":"2026-10-15T14:19:35.86462Z","steps":["trace[444057992] 'process raft request' (duration: 227.411293ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:35.885386Z","caller":"traceutil/trace.go:171","msg":"trace[1182831696] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5049; }","duration":"177.410376ms","start":"2026-10-15T14:19:35.707956Z","end":"2026-10-15T14:19:35.885367Z","steps":["trace[1182831696] 'read index received' (duration: 156.771558ms)","trace[1182831696] 'applied index is now lower than readState.Index' (duration: 20.638058ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:35.885559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.883423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:35.885694Z","caller":"traceutil/trace.go:171","msg":"trace[734267542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4847; }","duration":"232.085447ms","start":"2026-10-15T14:19:35.65359Z","end":"2026-10-15T14:19:35.885676Z","steps":["trace[734267542] 'agreement among raft nodes before linearized reading' (duration: 231.899874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:35.885761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.969854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T14:19:35.885821Z","caller":"traceutil/trace.go:171","msg":"trace[1496801592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4847; }","duration":"102.076965ms","start":"2026-10-15T14:19:35.783728Z","end":"2026-10-15T14:19:35.885805Z","steps":["trace[1496801592] 'agreement among raft nodes before linearized reading' (duration: 101.923783ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:35.885573Z","caller":"traceutil/trace.go:171","msg":"trace[615428040] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"164.079814ms","start":"2026-10-15T14:19:35.721467Z","end":"2026-10-15T14:19:35.885547Z","steps":["trace[615428040] 'process raft request' (duration: 163.811229ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:35.885575Z","caller":"traceutil/trace.go:171","msg":"trace[518239445] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"214.669859ms","start":"2026-10-15T14:19:35.670839Z","end":"2026-10-15T14:19:35.885509Z","steps":["trace[518239445] 'process raft request' (duration: 214.309513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:35.886099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.372166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-10-15T14:19:35.886198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.333046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:19:35.886244Z","caller":"traceutil/trace.go:171","msg":"trace[537000205] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4847; }","duration":"528.384427ms","start":"2026-10-15T14:19:35.357848Z","end":"2026-10-15T14:19:35.886232Z","steps":["trace[537000205] 'agreement among raft nodes before linearized reading' (duration: 528.315416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:35.886273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.357775Z","time spent":"528.488129ms","remote":"127.0.0.1:59312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-15T14:19:35.886248Z","caller":"traceutil/trace.go:171","msg":"trace[215594393] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4847; }","duration":"860.548319ms","start":"2026-10-15T14:19:35.025671Z","end":"2026-10-15T14:19:35.88622Z","steps":["trace[215594393] 'agreement among raft nodes before linearized reading' (duration: 860.061621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:35.886399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.025663Z","time spent":"860.681081ms","remote":"127.0.0.1:43320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-15T14:19:35.886491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.371042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.118\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-15T14:19:35.886483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.312432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:19:35.886549Z","caller":"traceutil/trace.go:171","msg":"trace[2100494597] range","detail":"{range_begin:/registry/masterleases/162.253.55.118; range_end:; response_count:1; response_revision:4847; }","duration":"620.451504ms","start":"2026-10-15T14:19:35.266062Z","end":"2026-10-15T14:19:35.886513Z","steps":["trace[2100494597] 'agreement among raft nodes before linearized reading' (duration: 619.553269ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:35.886557Z","caller":"traceutil/trace.go:171","msg":"trace[1585059308] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4847; }","duration":"556.388773ms","start":"2026-10-15T14:19:35.330154Z","end":"2026-10-15T14:19:35.886543Z","steps":["trace[1585059308] 'agreement among raft nodes before linearized reading' (duration: 556.280231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:35.886575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.266044Z","time spent":"620.524685ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.118\" "} {"level":"warn","ts":"2026-10-15T14:19:35.886611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.330136Z","time spent":"556.449654ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":16,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:19:36.488833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.562037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:19:36.489278Z","caller":"traceutil/trace.go:171","msg":"trace[1488922642] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"587.242542ms","start":"2026-10-15T14:19:35.90202Z","end":"2026-10-15T14:19:36.489263Z","steps":["trace[1488922642] 'process raft request' (duration: 587.202882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.489344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.902005Z","time spent":"587.305183ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:36.489735Z","caller":"traceutil/trace.go:171","msg":"trace[405304490] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"595.520043ms","start":"2026-10-15T14:19:35.894195Z","end":"2026-10-15T14:19:36.489715Z","steps":["trace[405304490] 'process raft request' (duration: 119.979761ms)","trace[405304490] 'compare' (duration: 474.386904ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:36.48981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.894172Z","time spent":"595.597915ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:36.489963Z","caller":"traceutil/trace.go:171","msg":"trace[1034619344] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"595.103367ms","start":"2026-10-15T14:19:35.894847Z","end":"2026-10-15T14:19:36.48995Z","steps":["trace[1034619344] 'process raft request' (duration: 594.070211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.490053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.894831Z","time spent":"595.158527ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:36.490151Z","caller":"traceutil/trace.go:171","msg":"trace[1402857867] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"593.360229ms","start":"2026-10-15T14:19:35.89677Z","end":"2026-10-15T14:19:36.49013Z","steps":["trace[1402857867] 'process raft request' (duration: 592.358253ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:36.490196Z","caller":"traceutil/trace.go:171","msg":"trace[1166518165] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"592.494034ms","start":"2026-10-15T14:19:35.89769Z","end":"2026-10-15T14:19:36.490184Z","steps":["trace[1166518165] 'process raft request' (duration: 591.467739ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:36.490218Z","caller":"traceutil/trace.go:171","msg":"trace[2006556143] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"594.508087ms","start":"2026-10-15T14:19:35.895689Z","end":"2026-10-15T14:19:36.490197Z","steps":["trace[2006556143] 'process raft request' (duration: 593.336339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.490253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.896761Z","time spent":"593.43824ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:19:36.490268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.89768Z","time spent":"592.547585ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:19:36.49031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.895673Z","time spent":"594.585048ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:36.490545Z","caller":"traceutil/trace.go:171","msg":"trace[1729249913] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5053; }","duration":"590.113357ms","start":"2026-10-15T14:19:35.900388Z","end":"2026-10-15T14:19:36.490501Z","steps":["trace[1729249913] 'read index received' (duration: 113.802072ms)","trace[1729249913] 'applied index is now lower than readState.Index' (duration: 476.309155ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:36.490568Z","caller":"traceutil/trace.go:171","msg":"trace[2139188678] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"594.856852ms","start":"2026-10-15T14:19:35.895694Z","end":"2026-10-15T14:19:36.490551Z","steps":["trace[2139188678] 'process raft request' (duration: 593.299558ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:36.490666Z","caller":"traceutil/trace.go:171","msg":"trace[1646154214] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"589.009229ms","start":"2026-10-15T14:19:35.901645Z","end":"2026-10-15T14:19:36.490654Z","steps":["trace[1646154214] 'process raft request' (duration: 587.547276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.490716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.895681Z","time spent":"594.988904ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:19:36.490776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.901606Z","time spent":"589.08482ms","remote":"127.0.0.1:43440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:36.490855Z","caller":"traceutil/trace.go:171","msg":"trace[1713234792] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"594.232983ms","start":"2026-10-15T14:19:35.896599Z","end":"2026-10-15T14:19:36.490832Z","steps":["trace[1713234792] 'process raft request' (duration: 592.483026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.490928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.87373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-10-15T14:19:36.490952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.896587Z","time spent":"594.313614ms","remote":"127.0.0.1:43440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:36.490983Z","caller":"traceutil/trace.go:171","msg":"trace[503723197] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4856; }","duration":"384.980941ms","start":"2026-10-15T14:19:36.105987Z","end":"2026-10-15T14:19:36.490968Z","steps":["trace[503723197] 'agreement among raft nodes before linearized reading' (duration: 384.826679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.490991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.401513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6df67cc8cf-qknc7\" ","response":"range_response_count:1 size:11848"} {"level":"warn","ts":"2026-10-15T14:19:36.49101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:36.105968Z","time spent":"385.036193ms","remote":"127.0.0.1:43320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-15T14:19:36.491042Z","caller":"traceutil/trace.go:171","msg":"trace[277288200] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-6df67cc8cf-qknc7; range_end:; response_count:1; response_revision:4856; }","duration":"597.486295ms","start":"2026-10-15T14:19:35.89354Z","end":"2026-10-15T14:19:36.491027Z","steps":["trace[277288200] 'agreement among raft nodes before linearized reading' (duration: 597.330903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.491079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:35.893501Z","time spent":"597.567257ms","remote":"127.0.0.1:43348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11871,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6df67cc8cf-qknc7\" "} {"level":"warn","ts":"2026-10-15T14:19:36.491162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:36.491217Z","caller":"traceutil/trace.go:171","msg":"trace[309712236] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4856; }","duration":"245.966171ms","start":"2026-10-15T14:19:36.245234Z","end":"2026-10-15T14:19:36.4912Z","steps":["trace[309712236] 'agreement among raft nodes before linearized reading' (duration: 245.87821ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:36.811159Z","caller":"traceutil/trace.go:171","msg":"trace[723387582] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5062; }","duration":"310.254055ms","start":"2026-10-15T14:19:36.500877Z","end":"2026-10-15T14:19:36.811131Z","steps":["trace[723387582] 'read index received' (duration: 289.534355ms)","trace[723387582] 'applied index is now lower than readState.Index' (duration: 20.71857ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:36.811472Z","caller":"traceutil/trace.go:171","msg":"trace[2055565789] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"311.016646ms","start":"2026-10-15T14:19:36.50044Z","end":"2026-10-15T14:19:36.811456Z","steps":["trace[2055565789] 'process raft request' (duration: 289.958241ms)","trace[2055565789] 'compare' (duration: 20.631828ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:36.811597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:36.50042Z","time spent":"311.089787ms","remote":"127.0.0.1:43348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11655,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:19:36.811652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.761671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-15T14:19:36.811714Z","caller":"traceutil/trace.go:171","msg":"trace[890961648] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4857; }","duration":"310.843612ms","start":"2026-10-15T14:19:36.500853Z","end":"2026-10-15T14:19:36.811696Z","steps":["trace[890961648] 'agreement among raft nodes before linearized reading' (duration: 310.644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.811755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:36.500841Z","time spent":"310.901913ms","remote":"127.0.0.1:43320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-10-15T14:19:36.820925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.635618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:36.82099Z","caller":"traceutil/trace.go:171","msg":"trace[827051108] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4858; }","duration":"269.740109ms","start":"2026-10-15T14:19:36.551233Z","end":"2026-10-15T14:19:36.820974Z","steps":["trace[827051108] 'agreement among raft nodes before linearized reading' (duration: 269.623297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.821026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.591827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:19:36.821092Z","caller":"traceutil/trace.go:171","msg":"trace[1606696725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4858; }","duration":"186.687659ms","start":"2026-10-15T14:19:36.634386Z","end":"2026-10-15T14:19:36.821074Z","steps":["trace[1606696725] 'agreement among raft nodes before linearized reading' (duration: 186.468125ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:36.821092Z","caller":"traceutil/trace.go:171","msg":"trace[841045271] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"319.49637ms","start":"2026-10-15T14:19:36.501572Z","end":"2026-10-15T14:19:36.821068Z","steps":["trace[841045271] 'process raft request' (duration: 319.080384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.821164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.426082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-10-15T14:19:36.821217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:36.501558Z","time spent":"319.591053ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:36.821247Z","caller":"traceutil/trace.go:171","msg":"trace[1003450698] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4858; }","duration":"289.558184ms","start":"2026-10-15T14:19:36.531648Z","end":"2026-10-15T14:19:36.821206Z","steps":["trace[1003450698] 'agreement among raft nodes before linearized reading' (duration: 289.3257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.821344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.720445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T14:19:36.821449Z","caller":"traceutil/trace.go:171","msg":"trace[276787648] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:4858; }","duration":"319.840406ms","start":"2026-10-15T14:19:36.501593Z","end":"2026-10-15T14:19:36.821433Z","steps":["trace[276787648] 'agreement among raft nodes before linearized reading' (duration: 319.597762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:36.821941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:36.501581Z","time spent":"320.345044ms","remote":"127.0.0.1:43440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-10-15T14:19:37.07218Z","caller":"traceutil/trace.go:171","msg":"trace[1889792001] linearizableReadLoop","detail":"{readStateIndex:5068; appliedIndex:5066; }","duration":"218.134807ms","start":"2026-10-15T14:19:36.854024Z","end":"2026-10-15T14:19:37.072159Z","steps":["trace[1889792001] 'read index received' (duration: 172.957341ms)","trace[1889792001] 'applied index is now lower than readState.Index' (duration: 45.176506ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:37.072281Z","caller":"traceutil/trace.go:171","msg":"trace[102733101] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"243.987337ms","start":"2026-10-15T14:19:36.828283Z","end":"2026-10-15T14:19:37.072271Z","steps":["trace[102733101] 'process raft request' (duration: 243.760243ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:37.07232Z","caller":"traceutil/trace.go:171","msg":"trace[843970077] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"246.582589ms","start":"2026-10-15T14:19:36.825709Z","end":"2026-10-15T14:19:37.072291Z","steps":["trace[843970077] 'process raft request' (duration: 201.259761ms)","trace[843970077] 'compare' (duration: 44.822401ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:37.072649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.330861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-10-15T14:19:37.072709Z","caller":"traceutil/trace.go:171","msg":"trace[1809320594] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4862; }","duration":"247.440822ms","start":"2026-10-15T14:19:36.825252Z","end":"2026-10-15T14:19:37.072693Z","steps":["trace[1809320594] 'agreement among raft nodes before linearized reading' (duration: 247.224328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:37.074912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.473415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-10-15T14:19:37.075138Z","caller":"traceutil/trace.go:171","msg":"trace[43081004] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4862; }","duration":"249.734228ms","start":"2026-10-15T14:19:36.825388Z","end":"2026-10-15T14:19:37.075122Z","steps":["trace[43081004] 'agreement among raft nodes before linearized reading' (duration: 246.962044ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:37.123568Z","caller":"traceutil/trace.go:171","msg":"trace[576499163] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"182.514649ms","start":"2026-10-15T14:19:36.941027Z","end":"2026-10-15T14:19:37.123542Z","steps":["trace[576499163] 'process raft request' (duration: 182.439038ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:37.123832Z","caller":"traceutil/trace.go:171","msg":"trace[1429131423] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"249.892011ms","start":"2026-10-15T14:19:36.873881Z","end":"2026-10-15T14:19:37.123773Z","steps":["trace[1429131423] 'process raft request' (duration: 249.459964ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:53.331219Z","caller":"traceutil/trace.go:171","msg":"trace[1998882405] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"245.552017ms","start":"2026-10-15T14:20:53.085643Z","end":"2026-10-15T14:20:53.331195Z","steps":["trace[1998882405] 'process raft request' (duration: 245.429145ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:53.331481Z","caller":"traceutil/trace.go:171","msg":"trace[840519039] linearizableReadLoop","detail":"{readStateIndex:5818; appliedIndex:5818; }","duration":"239.826475ms","start":"2026-10-15T14:20:53.091634Z","end":"2026-10-15T14:20:53.33146Z","steps":["trace[840519039] 'read index received' (duration: 239.816244ms)","trace[840519039] 'applied index is now lower than readState.Index' (duration: 8.46µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:20:53.331698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.040958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T14:20:53.33176Z","caller":"traceutil/trace.go:171","msg":"trace[1443044969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5580; }","duration":"240.13434ms","start":"2026-10-15T14:20:53.091609Z","end":"2026-10-15T14:20:53.331743Z","steps":["trace[1443044969] 'agreement among raft nodes before linearized reading' (duration: 239.954077ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:53.376384Z","caller":"traceutil/trace.go:171","msg":"trace[1307503825] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"248.590741ms","start":"2026-10-15T14:20:53.127771Z","end":"2026-10-15T14:20:53.376362Z","steps":["trace[1307503825] 'process raft request' (duration: 248.495959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:53.377302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.447624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-15T14:20:53.377326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.705133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8314"} {"level":"info","ts":"2026-10-15T14:20:53.377419Z","caller":"traceutil/trace.go:171","msg":"trace[1375234616] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5581; }","duration":"128.846416ms","start":"2026-10-15T14:20:53.248556Z","end":"2026-10-15T14:20:53.377402Z","steps":["trace[1375234616] 'agreement among raft nodes before linearized reading' (duration: 128.602872ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:53.377381Z","caller":"traceutil/trace.go:171","msg":"trace[257443922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5581; }","duration":"284.575455ms","start":"2026-10-15T14:20:53.092787Z","end":"2026-10-15T14:20:53.377363Z","steps":["trace[257443922] 'agreement among raft nodes before linearized reading' (duration: 284.343772ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:05.826463Z","caller":"traceutil/trace.go:171","msg":"trace[1913154247] transaction","detail":"{read_only:false; response_revision:5691; number_of_response:1; }","duration":"159.960594ms","start":"2026-10-15T14:21:05.666479Z","end":"2026-10-15T14:21:05.82644Z","steps":["trace[1913154247] 'process raft request' (duration: 159.559798ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:31.862473Z","caller":"traceutil/trace.go:171","msg":"trace[985427480] linearizableReadLoop","detail":"{readStateIndex:6134; appliedIndex:6133; }","duration":"144.701571ms","start":"2026-10-15T14:21:31.717731Z","end":"2026-10-15T14:21:31.862432Z","steps":["trace[985427480] 'read index received' (duration: 143.297491ms)","trace[985427480] 'applied index is now lower than readState.Index' (duration: 1.40267ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:21:31.862859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.634734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:21:31.862913Z","caller":"traceutil/trace.go:171","msg":"trace[1228696537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5881; }","duration":"129.730575ms","start":"2026-10-15T14:21:31.73317Z","end":"2026-10-15T14:21:31.8629Z","steps":["trace[1228696537] 'agreement among raft nodes before linearized reading' (duration: 129.557062ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:31.862889Z","caller":"traceutil/trace.go:171","msg":"trace[1238758274] transaction","detail":"{read_only:false; response_revision:5881; number_of_response:1; }","duration":"148.254181ms","start":"2026-10-15T14:21:31.714603Z","end":"2026-10-15T14:21:31.862858Z","steps":["trace[1238758274] 'process raft request' (duration: 146.521577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:21:31.863475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.735076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:21:31.863523Z","caller":"traceutil/trace.go:171","msg":"trace[2101785733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5881; }","duration":"145.812168ms","start":"2026-10-15T14:21:31.717699Z","end":"2026-10-15T14:21:31.863511Z","steps":["trace[2101785733] 'agreement among raft nodes before linearized reading' (duration: 145.104667ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:21.908174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3059} {"level":"info","ts":"2026-10-15T14:22:22.097904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3059,"took":"188.285447ms","hash":925030847,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T14:22:22.098035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":925030847,"revision":3059,"compact-revision":-1} {"level":"info","ts":"2026-10-15T14:22:36.977639Z","caller":"traceutil/trace.go:171","msg":"trace[310386159] transaction","detail":"{read_only:false; response_revision:6340; number_of_response:1; }","duration":"107.180582ms","start":"2026-10-15T14:22:36.870435Z","end":"2026-10-15T14:22:36.977616Z","steps":["trace[310386159] 'process raft request' (duration: 107.072081ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:58.194253Z","caller":"traceutil/trace.go:171","msg":"trace[1630670814] transaction","detail":"{read_only:false; response_revision:6515; number_of_response:1; }","duration":"120.770186ms","start":"2026-10-15T14:22:58.073456Z","end":"2026-10-15T14:22:58.194226Z","steps":["trace[1630670814] 'process raft request' (duration: 64.513517ms)","trace[1630670814] 'compare' (duration: 56.133877ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:10.654193Z","caller":"traceutil/trace.go:171","msg":"trace[1946060088] transaction","detail":"{read_only:false; response_revision:6716; number_of_response:1; }","duration":"115.440039ms","start":"2026-10-15T14:23:10.538725Z","end":"2026-10-15T14:23:10.654165Z","steps":["trace[1946060088] 'process raft request' (duration: 115.314047ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:22.467534Z","caller":"traceutil/trace.go:171","msg":"trace[623763850] transaction","detail":"{read_only:false; response_revision:6791; number_of_response:1; }","duration":"210.550309ms","start":"2026-10-15T14:23:22.256868Z","end":"2026-10-15T14:23:22.467418Z","steps":["trace[623763850] 'process raft request' (duration: 158.905177ms)","trace[623763850] 'compare' (duration: 51.387608ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:22.633956Z","caller":"traceutil/trace.go:171","msg":"trace[291878003] linearizableReadLoop","detail":"{readStateIndex:7095; appliedIndex:7093; }","duration":"347.090056ms","start":"2026-10-15T14:23:22.28684Z","end":"2026-10-15T14:23:22.63393Z","steps":["trace[291878003] 'read index received' (duration: 129.09166ms)","trace[291878003] 'applied index is now lower than readState.Index' (duration: 217.997196ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:22.634099Z","caller":"traceutil/trace.go:171","msg":"trace[895958959] transaction","detail":"{read_only:false; response_revision:6792; number_of_response:1; }","duration":"366.174134ms","start":"2026-10-15T14:23:22.267907Z","end":"2026-10-15T14:23:22.634081Z","steps":["trace[895958959] 'process raft request' (duration: 317.213257ms)","trace[895958959] 'compare' (duration: 48.700324ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:22.63422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:22.267891Z","time spent":"366.239265ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:23:22.634344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.479231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4837"} {"level":"info","ts":"2026-10-15T14:23:22.634503Z","caller":"traceutil/trace.go:171","msg":"trace[1435345614] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6792; }","duration":"347.677153ms","start":"2026-10-15T14:23:22.286801Z","end":"2026-10-15T14:23:22.634479Z","steps":["trace[1435345614] 'agreement among raft nodes before linearized reading' (duration: 347.343719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:22.634555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:22.286788Z","time spent":"347.753424ms","remote":"127.0.0.1:43402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4860,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-10-15T14:23:43.244743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1190816447335836597,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T14:23:43.290508Z","caller":"traceutil/trace.go:171","msg":"trace[1329284544] transaction","detail":"{read_only:false; response_revision:6980; number_of_response:1; }","duration":"553.553843ms","start":"2026-10-15T14:23:42.73691Z","end":"2026-10-15T14:23:43.290464Z","steps":["trace[1329284544] 'process raft request' (duration: 553.427851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.290725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:42.7369Z","time spent":"553.704144ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:23:43.31992Z","caller":"traceutil/trace.go:171","msg":"trace[167920768] transaction","detail":"{read_only:false; response_revision:6981; number_of_response:1; }","duration":"281.093672ms","start":"2026-10-15T14:23:43.038806Z","end":"2026-10-15T14:23:43.319899Z","steps":["trace[167920768] 'process raft request' (duration: 280.957251ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:43.320098Z","caller":"traceutil/trace.go:171","msg":"trace[2019257642] linearizableReadLoop","detail":"{readStateIndex:7296; appliedIndex:7293; }","duration":"575.584159ms","start":"2026-10-15T14:23:42.744507Z","end":"2026-10-15T14:23:43.320091Z","steps":["trace[2019257642] 'read index received' (duration: 545.792593ms)","trace[2019257642] 'applied index is now lower than readState.Index' (duration: 29.790506ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:43.320287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.347872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:23:43.320322Z","caller":"traceutil/trace.go:171","msg":"trace[627835415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6982; }","duration":"550.409943ms","start":"2026-10-15T14:23:42.769892Z","end":"2026-10-15T14:23:43.320302Z","steps":["trace[627835415] 'agreement among raft nodes before linearized reading' (duration: 550.317452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.320338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:42.769878Z","time spent":"550.454944ms","remote":"127.0.0.1:43436","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-10-15T14:23:43.320338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.801652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T14:23:43.320406Z","caller":"traceutil/trace.go:171","msg":"trace[2135807512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6982; }","duration":"575.941943ms","start":"2026-10-15T14:23:42.744447Z","end":"2026-10-15T14:23:43.320389Z","steps":["trace[2135807512] 'agreement among raft nodes before linearized reading' (duration: 575.71176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.320449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:42.744423Z","time spent":"576.017425ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T14:23:43.320556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.475777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:23:43.320576Z","caller":"traceutil/trace.go:171","msg":"trace[1743744831] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:6982; }","duration":"493.565808ms","start":"2026-10-15T14:23:42.827005Z","end":"2026-10-15T14:23:43.320571Z","steps":["trace[1743744831] 'agreement among raft nodes before linearized reading' (duration: 493.494107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.320591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:42.826986Z","time spent":"493.600589ms","remote":"127.0.0.1:43266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:23:43.320567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.595474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:23:43.320666Z","caller":"traceutil/trace.go:171","msg":"trace[2000035591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6982; }","duration":"382.740296ms","start":"2026-10-15T14:23:42.9379Z","end":"2026-10-15T14:23:43.320641Z","steps":["trace[2000035591] 'agreement among raft nodes before linearized reading' (duration: 382.486293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.320716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:42.937883Z","time spent":"382.824887ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T14:23:43.320849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.95368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-15T14:23:43.32087Z","caller":"traceutil/trace.go:171","msg":"trace[1362311079] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6982; }","duration":"547.987121ms","start":"2026-10-15T14:23:42.772878Z","end":"2026-10-15T14:23:43.320865Z","steps":["trace[1362311079] 'agreement among raft nodes before linearized reading' (duration: 547.943781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.320882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:42.772869Z","time spent":"548.010221ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-10-15T14:23:43.320906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.427186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-15T14:23:43.320989Z","caller":"traceutil/trace.go:171","msg":"trace[853434785] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6982; }","duration":"370.524547ms","start":"2026-10-15T14:23:42.950443Z","end":"2026-10-15T14:23:43.320968Z","steps":["trace[853434785] 'agreement among raft nodes before linearized reading' (duration: 370.390075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.321036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:42.950428Z","time spent":"370.600348ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T14:23:43.320231Z","caller":"traceutil/trace.go:171","msg":"trace[1937201565] transaction","detail":"{read_only:false; response_revision:6982; number_of_response:1; }","duration":"111.921667ms","start":"2026-10-15T14:23:43.208287Z","end":"2026-10-15T14:23:43.320208Z","steps":["trace[1937201565] 'process raft request' (duration: 111.587612ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:43.476607Z","caller":"traceutil/trace.go:171","msg":"trace[7499238] transaction","detail":"{read_only:false; response_revision:6984; number_of_response:1; }","duration":"151.491248ms","start":"2026-10-15T14:23:43.325069Z","end":"2026-10-15T14:23:43.47656Z","steps":["trace[7499238] 'process raft request' (duration: 149.854056ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:43.477094Z","caller":"traceutil/trace.go:171","msg":"trace[1857338947] transaction","detail":"{read_only:false; response_revision:6985; number_of_response:1; }","duration":"149.161507ms","start":"2026-10-15T14:23:43.327902Z","end":"2026-10-15T14:23:43.477063Z","steps":["trace[1857338947] 'process raft request' (duration: 148.496838ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:43.478165Z","caller":"traceutil/trace.go:171","msg":"trace[1573180981] transaction","detail":"{read_only:false; response_revision:6986; number_of_response:1; }","duration":"150.17807ms","start":"2026-10-15T14:23:43.327945Z","end":"2026-10-15T14:23:43.478124Z","steps":["trace[1573180981] 'process raft request' (duration: 149.034446ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:43.478625Z","caller":"traceutil/trace.go:171","msg":"trace[1921156069] linearizableReadLoop","detail":"{readStateIndex:7298; appliedIndex:7296; }","duration":"153.098488ms","start":"2026-10-15T14:23:43.325499Z","end":"2026-10-15T14:23:43.478598Z","steps":["trace[1921156069] 'read index received' (duration: 21.451287ms)","trace[1921156069] 'applied index is now lower than readState.Index' (duration: 131.646161ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:43.478796Z","caller":"traceutil/trace.go:171","msg":"trace[1984300040] transaction","detail":"{read_only:false; response_revision:6987; number_of_response:1; }","duration":"150.850799ms","start":"2026-10-15T14:23:43.327935Z","end":"2026-10-15T14:23:43.478786Z","steps":["trace[1984300040] 'process raft request' (duration: 150.055078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.479019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.502203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-10-15T14:23:43.479052Z","caller":"traceutil/trace.go:171","msg":"trace[1092908840] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6987; }","duration":"153.574803ms","start":"2026-10-15T14:23:43.325466Z","end":"2026-10-15T14:23:43.479041Z","steps":["trace[1092908840] 'agreement among raft nodes before linearized reading' (duration: 153.450692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.479056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.930309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T14:23:43.47911Z","caller":"traceutil/trace.go:171","msg":"trace[608632674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6987; }","duration":"103.041731ms","start":"2026-10-15T14:23:43.376054Z","end":"2026-10-15T14:23:43.479096Z","steps":["trace[608632674] 'agreement among raft nodes before linearized reading' (duration: 102.837658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:43.732404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.834199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:23:43.732474Z","caller":"traceutil/trace.go:171","msg":"trace[1938996380] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:6990; }","duration":"116.947471ms","start":"2026-10-15T14:23:43.615509Z","end":"2026-10-15T14:23:43.732457Z","steps":["trace[1938996380] 'count revisions from in-memory index tree' (duration: 116.766598ms)"],"step_count":1}