{"level":"warn","ts":"2026-04-17T00:29:53.982035Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:29:53.982111Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-04-17T00:29:53.982171Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:29:53.98218Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-17T00:29:53.982198Z","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-04-17T00:29:53.982815Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-04-17T00:29:53.982879Z","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://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-04-17T00:29:53.986719Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.64882ms"} {"level":"info","ts":"2026-04-17T00:29:53.996515Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-04-17T00:29:53.996615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T00:29:53.996652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-04-17T00:29:53.996679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T00:29:53.996704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-04-17T00:29:53.996751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-04-17T00:29:54.007579Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T00:29:54.009617Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T00:29:54.010945Z","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-04-17T00:29:54.015381Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T00:29:54.021726Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T00:29:54.021835Z","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-04-17T00:29:54.021888Z","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-04-17T00:29:54.0219Z","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-04-17T00:29:54.024517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-04-17T00:29:54.024654Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-17T00:29:54.025001Z","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-04-17T00:29:54.025204Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-17T00:29:54.025256Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-17T00:29:54.025398Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T00:29:54.025436Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T00:29:54.397078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T00:29:54.397122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T00:29:54.397135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-04-17T00:29:54.397146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-04-17T00:29:54.397151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-17T00:29:54.397159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-04-17T00:29:54.397167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-17T00:29:54.397837Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T00:29:54.397873Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:29:54.397991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:29:54.398013Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:29:54.398319Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T00:29:54.398408Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T00:29:54.39853Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:29:54.398589Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:29:54.398606Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:29:54.401378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T00:29:54.405879Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"warn","ts":"2026-04-17T00:30:14.517961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.776325ms","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-04-17T00:30:14.518067Z","caller":"traceutil/trace.go:171","msg":"trace[2091439949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"155.897597ms","start":"2026-04-17T00:30:14.362147Z","end":"2026-04-17T00:30:14.518045Z","steps":["trace[2091439949] 'range keys from in-memory index tree' (duration: 155.620122ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:14.717856Z","caller":"traceutil/trace.go:171","msg":"trace[492389790] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"195.742977ms","start":"2026-04-17T00:30:14.522087Z","end":"2026-04-17T00:30:14.717829Z","steps":["trace[492389790] 'process raft request' (duration: 195.594994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:14.916497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.184852ms","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-04-17T00:30:14.916592Z","caller":"traceutil/trace.go:171","msg":"trace[1500584841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"103.325984ms","start":"2026-04-17T00:30:14.813236Z","end":"2026-04-17T00:30:14.916562Z","steps":["trace[1500584841] 'range keys from in-memory index tree' (duration: 103.045269ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:15.092557Z","caller":"traceutil/trace.go:171","msg":"trace[377804636] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"171.743351ms","start":"2026-04-17T00:30:14.920791Z","end":"2026-04-17T00:30:15.092534Z","steps":["trace[377804636] 'process raft request' (duration: 171.615359ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:29.252716Z","caller":"traceutil/trace.go:171","msg":"trace[1642069255] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"105.975539ms","start":"2026-04-17T00:30:29.146718Z","end":"2026-04-17T00:30:29.252693Z","steps":["trace[1642069255] 'process raft request' (duration: 105.835796ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:29.258Z","caller":"traceutil/trace.go:171","msg":"trace[1490565140] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"108.980814ms","start":"2026-04-17T00:30:29.149001Z","end":"2026-04-17T00:30:29.257982Z","steps":["trace[1490565140] 'process raft request' (duration: 108.778801ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:29.257995Z","caller":"traceutil/trace.go:171","msg":"trace[715064990] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"108.939874ms","start":"2026-04-17T00:30:29.148998Z","end":"2026-04-17T00:30:29.257938Z","steps":["trace[715064990] 'process raft request' (duration: 108.665549ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:29.258133Z","caller":"traceutil/trace.go:171","msg":"trace[1004840559] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"101.09477ms","start":"2026-04-17T00:30:29.157029Z","end":"2026-04-17T00:30:29.258124Z","steps":["trace[1004840559] 'process raft request' (duration: 100.808335ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:29.259037Z","caller":"traceutil/trace.go:171","msg":"trace[1941504861] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:538; }","duration":"102.115449ms","start":"2026-04-17T00:30:29.156899Z","end":"2026-04-17T00:30:29.259015Z","steps":["trace[1941504861] 'read index received' (duration: 95.602039ms)","trace[1941504861] 'applied index is now lower than readState.Index' (duration: 6.51207ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:30:29.25927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.347623ms","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-04-17T00:30:29.259333Z","caller":"traceutil/trace.go:171","msg":"trace[333589294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"102.444895ms","start":"2026-04-17T00:30:29.156873Z","end":"2026-04-17T00:30:29.259318Z","steps":["trace[333589294] 'agreement among raft nodes before linearized reading' (duration: 102.228441ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:30.648787Z","caller":"traceutil/trace.go:171","msg":"trace[1166998802] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"150.793406ms","start":"2026-04-17T00:30:30.497967Z","end":"2026-04-17T00:30:30.64876Z","steps":["trace[1166998802] 'process raft request' (duration: 150.42698ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:30.648777Z","caller":"traceutil/trace.go:171","msg":"trace[1446856548] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"133.53822ms","start":"2026-04-17T00:30:30.515206Z","end":"2026-04-17T00:30:30.648744Z","steps":["trace[1446856548] 'read index received' (duration: 132.9685ms)","trace[1446856548] 'applied index is now lower than readState.Index' (duration: 568.06µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:30:30.64925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.996678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-04-17T00:30:30.649328Z","caller":"traceutil/trace.go:171","msg":"trace[160323733] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:599; }","duration":"134.111031ms","start":"2026-04-17T00:30:30.51517Z","end":"2026-04-17T00:30:30.649281Z","steps":["trace[160323733] 'agreement among raft nodes before linearized reading' (duration: 133.849856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:35.412819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.238365ms","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-04-17T00:30:35.412971Z","caller":"traceutil/trace.go:171","msg":"trace[1785147590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"110.421249ms","start":"2026-04-17T00:30:35.302497Z","end":"2026-04-17T00:30:35.412918Z","steps":["trace[1785147590] 'range keys from in-memory index tree' (duration: 110.059842ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:35.688983Z","caller":"traceutil/trace.go:171","msg":"trace[767876601] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"262.866057ms","start":"2026-04-17T00:30:35.426092Z","end":"2026-04-17T00:30:35.688958Z","steps":["trace[767876601] 'process raft request' (duration: 262.677164ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:35.714984Z","caller":"traceutil/trace.go:171","msg":"trace[1060420028] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"153.414935ms","start":"2026-04-17T00:30:35.561545Z","end":"2026-04-17T00:30:35.71496Z","steps":["trace[1060420028] 'process raft request' (duration: 152.922286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:36.357535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.289718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:30:36.357725Z","caller":"traceutil/trace.go:171","msg":"trace[557228510] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"588.198006ms","start":"2026-04-17T00:30:35.769506Z","end":"2026-04-17T00:30:36.357704Z","steps":["trace[557228510] 'process raft request' (duration: 326.946519ms)","trace[557228510] 'compare' (duration: 260.013773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:30:36.357796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:35.769476Z","time spent":"588.290968ms","remote":"127.0.0.1:52854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:30:36.357919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.088978ms","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-04-17T00:30:36.358Z","caller":"traceutil/trace.go:171","msg":"trace[1197428700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"364.226841ms","start":"2026-04-17T00:30:35.993756Z","end":"2026-04-17T00:30:36.357983Z","steps":["trace[1197428700] 'agreement among raft nodes before linearized reading' (duration: 364.046687ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:36.357656Z","caller":"traceutil/trace.go:171","msg":"trace[1034421395] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"363.815013ms","start":"2026-04-17T00:30:35.993822Z","end":"2026-04-17T00:30:36.357637Z","steps":["trace[1034421395] 'read index received' (duration: 102.544645ms)","trace[1034421395] 'applied index is now lower than readState.Index' (duration: 261.268978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:30:36.358051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:35.993731Z","time spent":"364.310863ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T00:30:36.358189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.720833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-17T00:30:36.358217Z","caller":"traceutil/trace.go:171","msg":"trace[389413910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"114.786234ms","start":"2026-04-17T00:30:36.243421Z","end":"2026-04-17T00:30:36.358207Z","steps":["trace[389413910] 'agreement among raft nodes before linearized reading' (duration: 114.658731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:51.759163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.128328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:51.759272Z","caller":"traceutil/trace.go:171","msg":"trace[1381949507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"176.2877ms","start":"2026-04-17T00:30:51.582961Z","end":"2026-04-17T00:30:51.759249Z","steps":["trace[1381949507] 'range keys from in-memory index tree' (duration: 175.998286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:51.759167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.437613ms","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-04-17T00:30:51.759445Z","caller":"traceutil/trace.go:171","msg":"trace[1292934985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"222.720509ms","start":"2026-04-17T00:30:51.53668Z","end":"2026-04-17T00:30:51.7594Z","steps":["trace[1292934985] 'range keys from in-memory index tree' (duration: 222.24985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:51.759638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.538865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zvvpv\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-17T00:30:51.759703Z","caller":"traceutil/trace.go:171","msg":"trace[1073746460] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zvvpv; range_end:; response_count:1; response_revision:792; }","duration":"324.82136ms","start":"2026-04-17T00:30:51.434866Z","end":"2026-04-17T00:30:51.759687Z","steps":["trace[1073746460] 'range keys from in-memory index tree' (duration: 324.387652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:51.759743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:51.434843Z","time spent":"324.888531ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zvvpv\" "} {"level":"warn","ts":"2026-04-17T00:32:06.552523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.455064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:32:06.552689Z","caller":"traceutil/trace.go:171","msg":"trace[844867571] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"197.936135ms","start":"2026-04-17T00:32:06.354715Z","end":"2026-04-17T00:32:06.552651Z","steps":["trace[844867571] 'process raft request' (duration: 22.073442ms)","trace[844867571] 'compare' (duration: 175.342732ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:06.901119Z","caller":"traceutil/trace.go:171","msg":"trace[1410614149] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"126.840888ms","start":"2026-04-17T00:32:06.774254Z","end":"2026-04-17T00:32:06.901095Z","steps":["trace[1410614149] 'read index received' (duration: 26.899471ms)","trace[1410614149] 'applied index is now lower than readState.Index' (duration: 99.940367ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:06.901251Z","caller":"traceutil/trace.go:171","msg":"trace[583420406] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"153.719629ms","start":"2026-04-17T00:32:06.747515Z","end":"2026-04-17T00:32:06.901235Z","steps":["trace[583420406] 'process raft request' (duration: 53.656509ms)","trace[583420406] 'compare' (duration: 99.809905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:06.901403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.013849ms","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-04-17T00:32:06.901478Z","caller":"traceutil/trace.go:171","msg":"trace[135452702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1844; }","duration":"101.136562ms","start":"2026-04-17T00:32:06.800327Z","end":"2026-04-17T00:32:06.901464Z","steps":["trace[135452702] 'agreement among raft nodes before linearized reading' (duration: 100.972759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:06.901621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.436768ms","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-04-17T00:32:06.901641Z","caller":"traceutil/trace.go:171","msg":"trace[1067170319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1844; }","duration":"100.467518ms","start":"2026-04-17T00:32:06.801169Z","end":"2026-04-17T00:32:06.901636Z","steps":["trace[1067170319] 'agreement among raft nodes before linearized reading' (duration: 100.401167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:06.901746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.476481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-654d1b86-6680-4e81-8cdd-ae5d3fbb7ad7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-17T00:32:06.901819Z","caller":"traceutil/trace.go:171","msg":"trace[403141000] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-654d1b86-6680-4e81-8cdd-ae5d3fbb7ad7; range_end:; response_count:1; response_revision:1844; }","duration":"127.599694ms","start":"2026-04-17T00:32:06.774202Z","end":"2026-04-17T00:32:06.901801Z","steps":["trace[403141000] 'agreement among raft nodes before linearized reading' (duration: 127.377749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:07.150828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.063459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:32:07.151095Z","caller":"traceutil/trace.go:171","msg":"trace[731282692] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"245.252075ms","start":"2026-04-17T00:32:06.905792Z","end":"2026-04-17T00:32:07.151044Z","steps":["trace[731282692] 'process raft request' (duration: 127.876199ms)","trace[731282692] 'compare' (duration: 116.961818ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:07.151174Z","caller":"traceutil/trace.go:171","msg":"trace[1987260414] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"245.395238ms","start":"2026-04-17T00:32:06.905756Z","end":"2026-04-17T00:32:07.151151Z","steps":["trace[1987260414] 'process raft request' (duration: 245.179454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:07.151316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.504479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-654d1b86-6680-4e81-8cdd-ae5d3fbb7ad7\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-17T00:32:07.1514Z","caller":"traceutil/trace.go:171","msg":"trace[1102105967] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-654d1b86-6680-4e81-8cdd-ae5d3fbb7ad7; range_end:; response_count:1; response_revision:1848; }","duration":"140.662863ms","start":"2026-04-17T00:32:07.010726Z","end":"2026-04-17T00:32:07.151389Z","steps":["trace[1102105967] 'agreement among raft nodes before linearized reading' (duration: 140.483119ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:07.151175Z","caller":"traceutil/trace.go:171","msg":"trace[640471414] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1898; }","duration":"140.376797ms","start":"2026-04-17T00:32:07.010774Z","end":"2026-04-17T00:32:07.15115Z","steps":["trace[640471414] 'read index received' (duration: 22.816808ms)","trace[640471414] 'applied index is now lower than readState.Index' (duration: 117.557479ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:07.151827Z","caller":"traceutil/trace.go:171","msg":"trace[1308049368] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"242.479698ms","start":"2026-04-17T00:32:06.909328Z","end":"2026-04-17T00:32:07.151808Z","steps":["trace[1308049368] 'process raft request' (duration: 241.664962ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:12.253972Z","caller":"traceutil/trace.go:171","msg":"trace[717253655] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"100.707468ms","start":"2026-04-17T00:32:12.153239Z","end":"2026-04-17T00:32:12.253946Z","steps":["trace[717253655] 'process raft request' (duration: 100.532975ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:14.790067Z","caller":"traceutil/trace.go:171","msg":"trace[1316762251] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"140.162171ms","start":"2026-04-17T00:32:14.649887Z","end":"2026-04-17T00:32:14.790049Z","steps":["trace[1316762251] 'read index received' (duration: 140.014828ms)","trace[1316762251] 'applied index is now lower than readState.Index' (duration: 146.663µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:14.790154Z","caller":"traceutil/trace.go:171","msg":"trace[463723455] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"193.346484ms","start":"2026-04-17T00:32:14.596778Z","end":"2026-04-17T00:32:14.790125Z","steps":["trace[463723455] 'process raft request' (duration: 193.167951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:14.790293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.400076ms","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-04-17T00:32:14.790327Z","caller":"traceutil/trace.go:171","msg":"trace[35068314] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1937; }","duration":"140.478617ms","start":"2026-04-17T00:32:14.64984Z","end":"2026-04-17T00:32:14.790319Z","steps":["trace[35068314] 'agreement among raft nodes before linearized reading' (duration: 140.351035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:49.314759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.077306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:32:49.315043Z","caller":"traceutil/trace.go:171","msg":"trace[1268552841] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"267.08384ms","start":"2026-04-17T00:32:49.047938Z","end":"2026-04-17T00:32:49.315022Z","steps":["trace[1268552841] 'process raft request' (duration: 266.950518ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:49.315258Z","caller":"traceutil/trace.go:171","msg":"trace[769246634] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"336.627506ms","start":"2026-04-17T00:32:48.97845Z","end":"2026-04-17T00:32:49.315078Z","steps":["trace[769246634] 'process raft request' (duration: 101.133532ms)","trace[769246634] 'compare' (duration: 234.83353ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:49.315342Z","caller":"traceutil/trace.go:171","msg":"trace[196528781] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"308.842858ms","start":"2026-04-17T00:32:49.006445Z","end":"2026-04-17T00:32:49.315288Z","steps":["trace[196528781] 'read index received' (duration: 73.122029ms)","trace[196528781] 'applied index is now lower than readState.Index' (duration: 235.690348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:49.315578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.131775ms","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-04-17T00:32:49.315641Z","caller":"traceutil/trace.go:171","msg":"trace[788116854] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2184; }","duration":"309.232377ms","start":"2026-04-17T00:32:49.006392Z","end":"2026-04-17T00:32:49.315624Z","steps":["trace[788116854] 'agreement among raft nodes before linearized reading' (duration: 309.036553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:49.315638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.971251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:32:49.315689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:49.006355Z","time spent":"309.322519ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-17T00:32:49.315695Z","caller":"traceutil/trace.go:171","msg":"trace[1476628944] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2184; }","duration":"232.104724ms","start":"2026-04-17T00:32:49.083576Z","end":"2026-04-17T00:32:49.31568Z","steps":["trace[1476628944] 'agreement among raft nodes before linearized reading' (duration: 231.93472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:49.315806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.762321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-17T00:32:49.315897Z","caller":"traceutil/trace.go:171","msg":"trace[148507477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2184; }","duration":"211.889104ms","start":"2026-04-17T00:32:49.103991Z","end":"2026-04-17T00:32:49.31588Z","steps":["trace[148507477] 'agreement among raft nodes before linearized reading' (duration: 211.66921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:49.316067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:48.978425Z","time spent":"336.990795ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:32:49.626551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.508926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:32:49.626651Z","caller":"traceutil/trace.go:171","msg":"trace[1965303010] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"277.281533ms","start":"2026-04-17T00:32:49.349353Z","end":"2026-04-17T00:32:49.626634Z","steps":["trace[1965303010] 'read index received' (duration: 103.519461ms)","trace[1965303010] 'applied index is now lower than readState.Index' (duration: 173.760872ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:49.626743Z","caller":"traceutil/trace.go:171","msg":"trace[715121817] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"305.633392ms","start":"2026-04-17T00:32:49.321094Z","end":"2026-04-17T00:32:49.626728Z","steps":["trace[715121817] 'process raft request' (duration: 131.86301ms)","trace[715121817] 'compare' (duration: 173.26749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:49.626963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.595519ms","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-04-17T00:32:49.62706Z","caller":"traceutil/trace.go:171","msg":"trace[801693188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2185; }","duration":"276.744262ms","start":"2026-04-17T00:32:49.350294Z","end":"2026-04-17T00:32:49.627038Z","steps":["trace[801693188] 'agreement among raft nodes before linearized reading' (duration: 276.506547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:49.626988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:49.321074Z","time spent":"305.776365ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:32:49.627307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.934106ms","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-04-17T00:32:49.62742Z","caller":"traceutil/trace.go:171","msg":"trace[1596215231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2185; }","duration":"278.089299ms","start":"2026-04-17T00:32:49.349312Z","end":"2026-04-17T00:32:49.627401Z","steps":["trace[1596215231] 'agreement among raft nodes before linearized reading' (duration: 277.842524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:49.627488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.2389ms","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-04-17T00:32:49.627565Z","caller":"traceutil/trace.go:171","msg":"trace[923510757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2185; }","duration":"133.346702ms","start":"2026-04-17T00:32:49.494199Z","end":"2026-04-17T00:32:49.627546Z","steps":["trace[923510757] 'agreement among raft nodes before linearized reading' (duration: 133.20856ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:14.115772Z","caller":"traceutil/trace.go:171","msg":"trace[772247941] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"120.330322ms","start":"2026-04-17T00:33:13.995417Z","end":"2026-04-17T00:33:14.115748Z","steps":["trace[772247941] 'process raft request' (duration: 87.466056ms)","trace[772247941] 'compare' (duration: 32.657112ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:14.115839Z","caller":"traceutil/trace.go:171","msg":"trace[232298785] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"119.201299ms","start":"2026-04-17T00:33:13.996607Z","end":"2026-04-17T00:33:14.115808Z","steps":["trace[232298785] 'process raft request' (duration: 119.038356ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:34.57968Z","caller":"traceutil/trace.go:171","msg":"trace[876176654] linearizableReadLoop","detail":"{readStateIndex:2691; appliedIndex:2690; }","duration":"255.03498ms","start":"2026-04-17T00:33:34.324628Z","end":"2026-04-17T00:33:34.579663Z","steps":["trace[876176654] 'read index received' (duration: 254.961509ms)","trace[876176654] 'applied index is now lower than readState.Index' (duration: 72.681µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:34.579744Z","caller":"traceutil/trace.go:171","msg":"trace[1009567827] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"302.873172ms","start":"2026-04-17T00:33:34.276839Z","end":"2026-04-17T00:33:34.579712Z","steps":["trace[1009567827] 'process raft request' (duration: 302.701299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:34.579855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.217493ms","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":"info","ts":"2026-04-17T00:33:34.579882Z","caller":"traceutil/trace.go:171","msg":"trace[625165152] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2598; }","duration":"255.299796ms","start":"2026-04-17T00:33:34.324574Z","end":"2026-04-17T00:33:34.579874Z","steps":["trace[625165152] 'agreement among raft nodes before linearized reading' (duration: 255.176333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:34.579899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:34.276818Z","time spent":"302.995224ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:33:34.580901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.802732ms","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-04-17T00:33:34.580973Z","caller":"traceutil/trace.go:171","msg":"trace[1725967847] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2599; }","duration":"250.902824ms","start":"2026-04-17T00:33:34.330053Z","end":"2026-04-17T00:33:34.580956Z","steps":["trace[1725967847] 'agreement among raft nodes before linearized reading' (duration: 250.71125ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:01.931702Z","caller":"traceutil/trace.go:171","msg":"trace[992850540] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"129.791659ms","start":"2026-04-17T00:34:01.801889Z","end":"2026-04-17T00:34:01.93168Z","steps":["trace[992850540] 'process raft request' (duration: 129.259237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:02.186567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.381706ms","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-04-17T00:34:02.186733Z","caller":"traceutil/trace.go:171","msg":"trace[64625368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2790; }","duration":"187.642392ms","start":"2026-04-17T00:34:01.999072Z","end":"2026-04-17T00:34:02.186714Z","steps":["trace[64625368] 'range keys from in-memory index tree' (duration: 187.199642ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:13.768116Z","caller":"traceutil/trace.go:171","msg":"trace[892941589] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"103.343456ms","start":"2026-04-17T00:34:13.664749Z","end":"2026-04-17T00:34:13.768092Z","steps":["trace[892941589] 'process raft request' (duration: 103.172342ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:30.097411Z","caller":"traceutil/trace.go:171","msg":"trace[68827961] linearizableReadLoop","detail":"{readStateIndex:3621; appliedIndex:3620; }","duration":"103.752964ms","start":"2026-04-17T00:35:29.993632Z","end":"2026-04-17T00:35:30.097385Z","steps":["trace[68827961] 'read index received' (duration: 103.372975ms)","trace[68827961] 'applied index is now lower than readState.Index' (duration: 378.709µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:30.097576Z","caller":"traceutil/trace.go:171","msg":"trace[1088722187] transaction","detail":"{read_only:false; response_revision:3477; number_of_response:1; }","duration":"123.630604ms","start":"2026-04-17T00:35:29.973917Z","end":"2026-04-17T00:35:30.097547Z","steps":["trace[1088722187] 'process raft request' (duration: 123.130382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:30.097809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.156883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-04-17T00:35:30.097897Z","caller":"traceutil/trace.go:171","msg":"trace[1236869093] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3477; }","duration":"104.299817ms","start":"2026-04-17T00:35:29.99358Z","end":"2026-04-17T00:35:30.097879Z","steps":["trace[1236869093] 'agreement among raft nodes before linearized reading' (duration: 103.99298ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:25.104492Z","caller":"traceutil/trace.go:171","msg":"trace[84002395] transaction","detail":"{read_only:false; response_revision:4358; number_of_response:1; }","duration":"106.302167ms","start":"2026-04-17T00:36:24.998167Z","end":"2026-04-17T00:36:25.104469Z","steps":["trace[84002395] 'process raft request' (duration: 80.795081ms)","trace[84002395] 'compare' (duration: 25.383933ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:25.30988Z","caller":"traceutil/trace.go:171","msg":"trace[1288790886] linearizableReadLoop","detail":"{readStateIndex:4548; appliedIndex:4546; }","duration":"118.920768ms","start":"2026-04-17T00:36:25.190939Z","end":"2026-04-17T00:36:25.30986Z","steps":["trace[1288790886] 'read index received' (duration: 16.564508ms)","trace[1288790886] 'applied index is now lower than readState.Index' (duration: 102.35529ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:25.309942Z","caller":"traceutil/trace.go:171","msg":"trace[1620540904] transaction","detail":"{read_only:false; response_revision:4362; number_of_response:1; }","duration":"130.589986ms","start":"2026-04-17T00:36:25.179308Z","end":"2026-04-17T00:36:25.309898Z","steps":["trace[1620540904] 'process raft request' (duration: 130.417202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:25.310143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.157853ms","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-04-17T00:36:25.310187Z","caller":"traceutil/trace.go:171","msg":"trace[720576552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4362; }","duration":"119.256195ms","start":"2026-04-17T00:36:25.19092Z","end":"2026-04-17T00:36:25.310176Z","steps":["trace[720576552] 'agreement among raft nodes before linearized reading' (duration: 119.05412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:28.334986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.381848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:36:28.335132Z","caller":"traceutil/trace.go:171","msg":"trace[102282408] linearizableReadLoop","detail":"{readStateIndex:4635; appliedIndex:4634; }","duration":"146.379613ms","start":"2026-04-17T00:36:28.188732Z","end":"2026-04-17T00:36:28.335112Z","steps":["trace[102282408] 'read index received' (duration: 1.715388ms)","trace[102282408] 'applied index is now lower than readState.Index' (duration: 144.662175ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:28.335219Z","caller":"traceutil/trace.go:171","msg":"trace[964958525] transaction","detail":"{read_only:false; response_revision:4444; number_of_response:1; }","duration":"155.135357ms","start":"2026-04-17T00:36:28.18007Z","end":"2026-04-17T00:36:28.335206Z","steps":["trace[964958525] 'compare' (duration: 144.163533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:28.335438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.685241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-m9trc-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:28.335512Z","caller":"traceutil/trace.go:171","msg":"trace[141119363] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-m9trc-tls; range_end:; response_count:0; response_revision:4444; }","duration":"146.790033ms","start":"2026-04-17T00:36:28.188703Z","end":"2026-04-17T00:36:28.335493Z","steps":["trace[141119363] 'agreement among raft nodes before linearized reading' (duration: 146.618479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:28.587787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.99961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:2378"} {"level":"info","ts":"2026-04-17T00:36:28.587902Z","caller":"traceutil/trace.go:171","msg":"trace[201114087] linearizableReadLoop","detail":"{readStateIndex:4640; appliedIndex:4639; }","duration":"172.437281ms","start":"2026-04-17T00:36:28.415449Z","end":"2026-04-17T00:36:28.587887Z","steps":["trace[201114087] 'read index received' (duration: 1.241258ms)","trace[201114087] 'applied index is now lower than readState.Index' (duration: 171.194843ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:28.587962Z","caller":"traceutil/trace.go:171","msg":"trace[2047644699] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4448; }","duration":"174.047166ms","start":"2026-04-17T00:36:28.413868Z","end":"2026-04-17T00:36:28.587915Z","steps":["trace[2047644699] 'process raft request' (duration: 48.840662ms)","trace[2047644699] 'compare' (duration: 124.935649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:36:28.58806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.599524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-17T00:36:28.588096Z","caller":"traceutil/trace.go:171","msg":"trace[1324962461] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4448; }","duration":"172.658056ms","start":"2026-04-17T00:36:28.415426Z","end":"2026-04-17T00:36:28.588084Z","steps":["trace[1324962461] 'agreement among raft nodes before linearized reading' (duration: 172.526773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:28.593988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.945803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-m9trc-tls\" ","response":"range_response_count:1 size:4432"} {"level":"warn","ts":"2026-04-17T00:36:28.594013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.37792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:28.594067Z","caller":"traceutil/trace.go:171","msg":"trace[411529741] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-m9trc-tls; range_end:; response_count:1; response_revision:4449; }","duration":"169.044325ms","start":"2026-04-17T00:36:28.425005Z","end":"2026-04-17T00:36:28.59405Z","steps":["trace[411529741] 'agreement among raft nodes before linearized reading' (duration: 168.862811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:28.594069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.827386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-m9trc-tls-lcwgv\" ","response":"range_response_count:1 size:2625"} {"level":"info","ts":"2026-04-17T00:36:28.594113Z","caller":"traceutil/trace.go:171","msg":"trace[754427657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4449; }","duration":"130.526243ms","start":"2026-04-17T00:36:28.463557Z","end":"2026-04-17T00:36:28.594084Z","steps":["trace[754427657] 'agreement among raft nodes before linearized reading' (duration: 130.332849ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:28.594209Z","caller":"traceutil/trace.go:171","msg":"trace[1066077569] transaction","detail":"{read_only:false; response_revision:4449; number_of_response:1; }","duration":"171.462498ms","start":"2026-04-17T00:36:28.422731Z","end":"2026-04-17T00:36:28.594194Z","steps":["trace[1066077569] 'process raft request' (duration: 170.944667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:28.594735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.636678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-17T00:36:28.59478Z","caller":"traceutil/trace.go:171","msg":"trace[1795358401] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4449; }","duration":"169.798872ms","start":"2026-04-17T00:36:28.42497Z","end":"2026-04-17T00:36:28.594769Z","steps":["trace[1795358401] 'agreement among raft nodes before linearized reading' (duration: 169.597758ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:28.594137Z","caller":"traceutil/trace.go:171","msg":"trace[1799350490] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-m9trc-tls-lcwgv; range_end:; response_count:1; response_revision:4449; }","duration":"171.931289ms","start":"2026-04-17T00:36:28.42219Z","end":"2026-04-17T00:36:28.594122Z","steps":["trace[1799350490] 'agreement among raft nodes before linearized reading' (duration: 171.718184ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:34.933073Z","caller":"traceutil/trace.go:171","msg":"trace[209151865] linearizableReadLoop","detail":"{readStateIndex:4767; appliedIndex:4766; }","duration":"153.361942ms","start":"2026-04-17T00:36:34.779689Z","end":"2026-04-17T00:36:34.933051Z","steps":["trace[209151865] 'read index received' (duration: 153.173408ms)","trace[209151865] 'applied index is now lower than readState.Index' (duration: 187.184µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:34.933341Z","caller":"traceutil/trace.go:171","msg":"trace[2057609918] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"204.614186ms","start":"2026-04-17T00:36:34.728691Z","end":"2026-04-17T00:36:34.933305Z","steps":["trace[2057609918] 'process raft request' (duration: 204.229357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:34.933742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.030378ms","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":"info","ts":"2026-04-17T00:36:34.933877Z","caller":"traceutil/trace.go:171","msg":"trace[1401256440] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4571; }","duration":"154.209481ms","start":"2026-04-17T00:36:34.779647Z","end":"2026-04-17T00:36:34.933857Z","steps":["trace[1401256440] 'agreement among raft nodes before linearized reading' (duration: 153.853383ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:35.195937Z","caller":"traceutil/trace.go:171","msg":"trace[2007490038] linearizableReadLoop","detail":"{readStateIndex:4769; appliedIndex:4767; }","duration":"193.275404ms","start":"2026-04-17T00:36:35.002638Z","end":"2026-04-17T00:36:35.195914Z","steps":["trace[2007490038] 'read index received' (duration: 151.410678ms)","trace[2007490038] 'applied index is now lower than readState.Index' (duration: 41.863736ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:35.195983Z","caller":"traceutil/trace.go:171","msg":"trace[2079722633] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"254.727534ms","start":"2026-04-17T00:36:34.941233Z","end":"2026-04-17T00:36:35.195961Z","steps":["trace[2079722633] 'process raft request' (duration: 254.593061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:35.196198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.212635ms","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-04-17T00:36:35.196256Z","caller":"traceutil/trace.go:171","msg":"trace[104042722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4573; }","duration":"189.285576ms","start":"2026-04-17T00:36:35.006956Z","end":"2026-04-17T00:36:35.196242Z","steps":["trace[104042722] 'agreement among raft nodes before linearized reading' (duration: 189.105692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:35.196297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.015579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a56e262-d456-47fb-9822-68533482a6e4\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-17T00:36:35.196206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.718203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7ddbfd676b-txdt6.18a6fdd4ee635a27\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-04-17T00:36:35.19634Z","caller":"traceutil/trace.go:171","msg":"trace[2008850511] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a56e262-d456-47fb-9822-68533482a6e4; range_end:; response_count:1; response_revision:4573; }","duration":"165.081151ms","start":"2026-04-17T00:36:35.031246Z","end":"2026-04-17T00:36:35.196327Z","steps":["trace[2008850511] 'agreement among raft nodes before linearized reading' (duration: 164.989989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:35.196334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.672903ms","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-04-17T00:36:35.196393Z","caller":"traceutil/trace.go:171","msg":"trace[1448342565] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7ddbfd676b-txdt6.18a6fdd4ee635a27; range_end:; response_count:1; response_revision:4573; }","duration":"164.910977ms","start":"2026-04-17T00:36:35.03145Z","end":"2026-04-17T00:36:35.196361Z","steps":["trace[1448342565] 'agreement among raft nodes before linearized reading' (duration: 164.672962ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:35.196418Z","caller":"traceutil/trace.go:171","msg":"trace[331720454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4573; }","duration":"193.769885ms","start":"2026-04-17T00:36:35.002632Z","end":"2026-04-17T00:36:35.196402Z","steps":["trace[331720454] 'agreement among raft nodes before linearized reading' (duration: 193.411417ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:35.195985Z","caller":"traceutil/trace.go:171","msg":"trace[2034920320] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"254.761874ms","start":"2026-04-17T00:36:34.941159Z","end":"2026-04-17T00:36:35.195921Z","steps":["trace[2034920320] 'process raft request' (duration: 212.810047ms)","trace[2034920320] 'compare' (duration: 41.61635ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:35.570148Z","caller":"traceutil/trace.go:171","msg":"trace[949252790] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"181.864693ms","start":"2026-04-17T00:36:35.388258Z","end":"2026-04-17T00:36:35.570123Z","steps":["trace[949252790] 'process raft request' (duration: 181.700289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:36.049445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.890494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:36:36.049604Z","caller":"traceutil/trace.go:171","msg":"trace[704539443] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"208.075172ms","start":"2026-04-17T00:36:35.841494Z","end":"2026-04-17T00:36:36.049569Z","steps":["trace[704539443] 'process raft request' (duration: 57.896981ms)","trace[704539443] 'compare' (duration: 149.496856ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:36.049572Z","caller":"traceutil/trace.go:171","msg":"trace[1868165434] linearizableReadLoop","detail":"{readStateIndex:4781; appliedIndex:4780; }","duration":"207.858316ms","start":"2026-04-17T00:36:35.841692Z","end":"2026-04-17T00:36:36.049551Z","steps":["trace[1868165434] 'read index received' (duration: 57.575313ms)","trace[1868165434] 'applied index is now lower than readState.Index' (duration: 150.281013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:36:36.049809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.089901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9761"} {"level":"info","ts":"2026-04-17T00:36:36.049897Z","caller":"traceutil/trace.go:171","msg":"trace[1488256982] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4584; }","duration":"208.192244ms","start":"2026-04-17T00:36:35.841679Z","end":"2026-04-17T00:36:36.049871Z","steps":["trace[1488256982] 'agreement among raft nodes before linearized reading' (duration: 207.958389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:36.049972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.002676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:36.050092Z","caller":"traceutil/trace.go:171","msg":"trace[901395027] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4584; }","duration":"145.187021ms","start":"2026-04-17T00:36:35.90487Z","end":"2026-04-17T00:36:36.050057Z","steps":["trace[901395027] 'agreement among raft nodes before linearized reading' (duration: 145.000796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:36.049974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.28663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a56e262-d456-47fb-9822-68533482a6e4\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-17T00:36:36.05025Z","caller":"traceutil/trace.go:171","msg":"trace[628714317] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a56e262-d456-47fb-9822-68533482a6e4; range_end:; response_count:1; response_revision:4584; }","duration":"110.564246ms","start":"2026-04-17T00:36:35.939641Z","end":"2026-04-17T00:36:36.050205Z","steps":["trace[628714317] 'agreement among raft nodes before linearized reading' (duration: 110.236647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:39.048543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.072143ms","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-04-17T00:36:39.048617Z","caller":"traceutil/trace.go:171","msg":"trace[659202693] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4627; }","duration":"101.234307ms","start":"2026-04-17T00:36:38.947368Z","end":"2026-04-17T00:36:39.048602Z","steps":["trace[659202693] 'range keys from in-memory index tree' (duration: 100.829178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.128354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.072492ms","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":"info","ts":"2026-04-17T00:36:48.128437Z","caller":"traceutil/trace.go:171","msg":"trace[433107463] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4762; }","duration":"698.188165ms","start":"2026-04-17T00:36:47.430227Z","end":"2026-04-17T00:36:48.128415Z","steps":["trace[433107463] 'range keys from in-memory index tree' (duration: 697.926009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.128476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.430209Z","time spent":"698.258406ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-17T00:36:48.128687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.634143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T00:36:48.128745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.809997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T00:36:48.128766Z","caller":"traceutil/trace.go:171","msg":"trace[306093822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4762; }","duration":"239.747595ms","start":"2026-04-17T00:36:47.889001Z","end":"2026-04-17T00:36:48.128748Z","steps":["trace[306093822] 'range keys from in-memory index tree' (duration: 239.472519ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:48.128799Z","caller":"traceutil/trace.go:171","msg":"trace[918712771] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:4762; }","duration":"790.880539ms","start":"2026-04-17T00:36:47.337887Z","end":"2026-04-17T00:36:48.128768Z","steps":["trace[918712771] 'range keys from in-memory index tree' (duration: 790.685814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.12883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.337866Z","time spent":"790.95696ms","remote":"127.0.0.1:52686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-04-17T00:36:48.129053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.425546ms","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-04-17T00:36:48.129089Z","caller":"traceutil/trace.go:171","msg":"trace[153157974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4762; }","duration":"774.503047ms","start":"2026-04-17T00:36:47.354577Z","end":"2026-04-17T00:36:48.12908Z","steps":["trace[153157974] 'range keys from in-memory index tree' (duration: 774.222761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.129114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.354554Z","time spent":"774.554798ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T00:36:48.129235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.370508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:48.129274Z","caller":"traceutil/trace.go:171","msg":"trace[297180400] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4762; }","duration":"352.44782ms","start":"2026-04-17T00:36:47.776813Z","end":"2026-04-17T00:36:48.129261Z","steps":["trace[297180400] 'count revisions from in-memory index tree' (duration: 352.307267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.129305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.776795Z","time spent":"352.500911ms","remote":"127.0.0.1:52120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:36:48.129329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.609956ms","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-04-17T00:36:48.129358Z","caller":"traceutil/trace.go:171","msg":"trace[52555981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4762; }","duration":"762.665076ms","start":"2026-04-17T00:36:47.366684Z","end":"2026-04-17T00:36:48.129349Z","steps":["trace[52555981] 'range keys from in-memory index tree' (duration: 762.512783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.12935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.392623ms","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":"warn","ts":"2026-04-17T00:36:48.129388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.366669Z","time spent":"762.712118ms","remote":"127.0.0.1:52880","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-04-17T00:36:48.129428Z","caller":"traceutil/trace.go:171","msg":"trace[1668531394] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4762; }","duration":"695.489166ms","start":"2026-04-17T00:36:47.433917Z","end":"2026-04-17T00:36:48.129406Z","steps":["trace[1668531394] 'range keys from in-memory index tree' (duration: 695.263351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.129476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.433911Z","time spent":"695.555087ms","remote":"127.0.0.1:52742","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":"warn","ts":"2026-04-17T00:36:48.129586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.393124ms","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-04-17T00:36:48.129613Z","caller":"traceutil/trace.go:171","msg":"trace[840960017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4762; }","duration":"705.442325ms","start":"2026-04-17T00:36:47.424162Z","end":"2026-04-17T00:36:48.129605Z","steps":["trace[840960017] 'range keys from in-memory index tree' (duration: 705.308002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.129637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.424148Z","time spent":"705.483586ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T00:36:48.129701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.729574ms","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-04-17T00:36:48.129723Z","caller":"traceutil/trace.go:171","msg":"trace[602768093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4762; }","duration":"506.793965ms","start":"2026-04-17T00:36:47.622921Z","end":"2026-04-17T00:36:48.129715Z","steps":["trace[602768093] 'range keys from in-memory index tree' (duration: 506.634591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.129742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.622902Z","time spent":"506.835866ms","remote":"127.0.0.1:52880","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-04-17T00:36:48.129844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.01711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:48.12986Z","caller":"traceutil/trace.go:171","msg":"trace[229284737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4762; }","duration":"546.072701ms","start":"2026-04-17T00:36:47.583782Z","end":"2026-04-17T00:36:48.129855Z","steps":["trace[229284737] 'range keys from in-memory index tree' (duration: 545.926918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.129875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:47.583743Z","time spent":"546.128112ms","remote":"127.0.0.1:52658","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-04-17T00:36:48.300005Z","caller":"traceutil/trace.go:171","msg":"trace[769997087] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"129.74097ms","start":"2026-04-17T00:36:48.170233Z","end":"2026-04-17T00:36:48.299974Z","steps":["trace[769997087] 'process raft request' (duration: 64.779568ms)","trace[769997087] 'compare' (duration: 64.730607ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:48.30004Z","caller":"traceutil/trace.go:171","msg":"trace[244907077] linearizableReadLoop","detail":"{readStateIndex:4974; appliedIndex:4972; }","duration":"106.59805ms","start":"2026-04-17T00:36:48.19342Z","end":"2026-04-17T00:36:48.300018Z","steps":["trace[244907077] 'read index received' (duration: 41.614848ms)","trace[244907077] 'applied index is now lower than readState.Index' (duration: 64.982232ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:48.300143Z","caller":"traceutil/trace.go:171","msg":"trace[926620938] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"106.695592ms","start":"2026-04-17T00:36:48.19343Z","end":"2026-04-17T00:36:48.300126Z","steps":["trace[926620938] 'process raft request' (duration: 106.507888ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:48.30017Z","caller":"traceutil/trace.go:171","msg":"trace[1784429131] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"105.198929ms","start":"2026-04-17T00:36:48.19496Z","end":"2026-04-17T00:36:48.300159Z","steps":["trace[1784429131] 'process raft request' (duration: 105.022145ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:48.300381Z","caller":"traceutil/trace.go:171","msg":"trace[1996110858] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"107.763935ms","start":"2026-04-17T00:36:48.192584Z","end":"2026-04-17T00:36:48.300348Z","steps":["trace[1996110858] 'process raft request' (duration: 107.287995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:48.300522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.082391ms","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-04-17T00:36:48.301294Z","caller":"traceutil/trace.go:171","msg":"trace[83856077] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4771; }","duration":"107.169664ms","start":"2026-04-17T00:36:48.193387Z","end":"2026-04-17T00:36:48.300557Z","steps":["trace[83856077] 'agreement among raft nodes before linearized reading' (duration: 106.99339ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:48.617467Z","caller":"traceutil/trace.go:171","msg":"trace[361564731] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"192.632387ms","start":"2026-04-17T00:36:48.424788Z","end":"2026-04-17T00:36:48.617421Z","steps":["trace[361564731] 'process raft request' (duration: 144.392833ms)","trace[361564731] 'compare' (duration: 48.026119ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:37:15.226744Z","caller":"traceutil/trace.go:171","msg":"trace[216662646] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5081; }","duration":"130.066171ms","start":"2026-04-17T00:37:15.096656Z","end":"2026-04-17T00:37:15.226723Z","steps":["trace[216662646] 'process raft request' (duration: 129.961768ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:37:36.559885Z","caller":"traceutil/trace.go:171","msg":"trace[972341713] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"100.2202ms","start":"2026-04-17T00:37:36.459629Z","end":"2026-04-17T00:37:36.55985Z","steps":["trace[972341713] 'process raft request' (duration: 67.394002ms)","trace[972341713] 'compare' (duration: 32.605823ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:37:36.572528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.31369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T00:37:36.572577Z","caller":"traceutil/trace.go:171","msg":"trace[1063115395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5252; }","duration":"110.390101ms","start":"2026-04-17T00:37:36.462174Z","end":"2026-04-17T00:37:36.572565Z","steps":["trace[1063115395] 'agreement among raft nodes before linearized reading' (duration: 110.225957ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:39:54.510108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3232} {"level":"info","ts":"2026-04-17T00:39:54.612355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3232,"took":"100.954965ms","hash":3981171051,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":31338496,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-17T00:39:54.612426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3981171051,"revision":3232,"compact-revision":-1} {"level":"info","ts":"2026-04-17T00:40:43.601192Z","caller":"traceutil/trace.go:171","msg":"trace[1010813308] linearizableReadLoop","detail":"{readStateIndex:7128; appliedIndex:7127; }","duration":"110.681179ms","start":"2026-04-17T00:40:43.490495Z","end":"2026-04-17T00:40:43.601176Z","steps":["trace[1010813308] 'read index received' (duration: 110.570727ms)","trace[1010813308] 'applied index is now lower than readState.Index' (duration: 109.222µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:40:43.601299Z","caller":"traceutil/trace.go:171","msg":"trace[1608417291] transaction","detail":"{read_only:false; response_revision:6819; number_of_response:1; }","duration":"163.971995ms","start":"2026-04-17T00:40:43.437318Z","end":"2026-04-17T00:40:43.60129Z","steps":["trace[1608417291] 'process raft request' (duration: 163.744041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:40:43.601792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.276692ms","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-04-17T00:40:43.601825Z","caller":"traceutil/trace.go:171","msg":"trace[2028902976] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6819; }","duration":"111.352634ms","start":"2026-04-17T00:40:43.490464Z","end":"2026-04-17T00:40:43.601817Z","steps":["trace[2028902976] 'agreement among raft nodes before linearized reading' (duration: 111.281113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:41:56.22195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:41:56.72215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:41:57.222886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:41:57.723754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:41:58.224571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:41:58.724719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:41:59.225112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:41:59.584784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000064511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-17T00:41:59.58485Z","caller":"traceutil/trace.go:171","msg":"trace[54726821] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000169553s","start":"2026-04-17T00:41:57.584663Z","end":"2026-04-17T00:41:59.584833Z","steps":["trace[54726821] 'agreement among raft nodes before linearized reading' (duration: 2.000088991s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:41:59.584885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:57.584647Z","time spent":"2.000231354s","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 00:41:59 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T00:41:59.726222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:42:00.227107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:42:00.458007Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.749137993s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T00:42:00.466037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999956908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-17T00:42:00.466101Z","caller":"traceutil/trace.go:171","msg":"trace[1565172798] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00006307s","start":"2026-04-17T00:41:58.466023Z","end":"2026-04-17T00:42:00.466086Z","steps":["trace[1565172798] 'agreement among raft nodes before linearized reading' (duration: 1.999980729s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.466175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:58.466009Z","time spent":"2.000158802s","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 00:42:00 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T00:42:00.724609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.569068425s","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-04-17T00:42:00.724703Z","caller":"traceutil/trace.go:171","msg":"trace[1749950788] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7302; }","duration":"5.569212238s","start":"2026-04-17T00:41:55.155472Z","end":"2026-04-17T00:42:00.724685Z","steps":["trace[1749950788] 'count revisions from in-memory index tree' (duration: 5.568980603s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.72476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.155453Z","time spent":"5.56929627s","remote":"127.0.0.1:49222","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":"warn","ts":"2026-04-17T00:42:00.72561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.14148643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:00.725656Z","caller":"traceutil/trace.go:171","msg":"trace[765034526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7302; }","duration":"5.141555972s","start":"2026-04-17T00:41:55.584088Z","end":"2026-04-17T00:42:00.725644Z","steps":["trace[765034526] 'range keys from in-memory index tree' (duration: 5.141433229s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.72569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.584074Z","time spent":"5.141609823s","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:42:00.72584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.132554339s","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-04-17T00:42:00.725876Z","caller":"traceutil/trace.go:171","msg":"trace[581728497] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7302; }","duration":"5.132624531s","start":"2026-04-17T00:41:55.593242Z","end":"2026-04-17T00:42:00.725866Z","steps":["trace[581728497] 'range keys from in-memory index tree' (duration: 5.132427557s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.725904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.593222Z","time spent":"5.132675422s","remote":"127.0.0.1:52742","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":"warn","ts":"2026-04-17T00:42:00.72606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.132706843s","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":"info","ts":"2026-04-17T00:42:00.726094Z","caller":"traceutil/trace.go:171","msg":"trace[111741580] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7302; }","duration":"5.132749365s","start":"2026-04-17T00:41:55.593336Z","end":"2026-04-17T00:42:00.726086Z","steps":["trace[111741580] 'range keys from in-memory index tree' (duration: 5.132665183s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.726127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.59333Z","time spent":"5.132790005s","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-17T00:42:00.732603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.319645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:00.732642Z","caller":"traceutil/trace.go:171","msg":"trace[173353872] transaction","detail":"{read_only:false; response_revision:7303; number_of_response:1; }","duration":"5.023882077s","start":"2026-04-17T00:41:55.708753Z","end":"2026-04-17T00:42:00.732635Z","steps":["trace[173353872] 'process raft request' (duration: 4.74949758s)","trace[173353872] 'compare' (duration: 265.865724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:00.732666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.70873Z","time spent":"5.023924108s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:00.734298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.010713835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-17T00:42:00.734322Z","caller":"traceutil/trace.go:171","msg":"trace[389963625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.010753305s","start":"2026-04-17T00:41:55.723563Z","end":"2026-04-17T00:42:00.734317Z","steps":["trace[389963625] 'agreement among raft nodes before linearized reading' (duration: 5.010726775s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.734339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.723559Z","time spent":"5.010774787s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/17 00:42:00 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-17T00:42:00.79431Z","caller":"traceutil/trace.go:171","msg":"trace[1038148581] transaction","detail":"{read_only:false; response_revision:7304; number_of_response:1; }","duration":"4.953885425s","start":"2026-04-17T00:41:55.840396Z","end":"2026-04-17T00:42:00.794281Z","steps":["trace[1038148581] 'process raft request' (duration: 4.953719782s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.794511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.84037Z","time spent":"4.954054288s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:00.794648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:59.588571Z","time spent":"1.206071235s","remote":"127.0.0.1:52722","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T00:42:00.794791Z","caller":"traceutil/trace.go:171","msg":"trace[437138412] linearizableReadLoop","detail":"{readStateIndex:7643; appliedIndex:7639; }","duration":"5.073140914s","start":"2026-04-17T00:41:55.721626Z","end":"2026-04-17T00:42:00.794767Z","steps":["trace[437138412] 'read index received' (duration: 4.736645825s)","trace[437138412] 'applied index is now lower than readState.Index' (duration: 336.494049ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:00.794931Z","caller":"traceutil/trace.go:171","msg":"trace[145571171] transaction","detail":"{read_only:false; response_revision:7305; number_of_response:1; }","duration":"1.032628893s","start":"2026-04-17T00:41:59.762279Z","end":"2026-04-17T00:42:00.794908Z","steps":["trace[145571171] 'process raft request' (duration: 1.032326627s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.795828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:59.762253Z","time spent":"1.033530972s","remote":"127.0.0.1:52880","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-04-17T00:42:00.795009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.073325048s","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-04-17T00:42:00.795972Z","caller":"traceutil/trace.go:171","msg":"trace[593765150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7305; }","duration":"5.074361821s","start":"2026-04-17T00:41:55.721597Z","end":"2026-04-17T00:42:00.795959Z","steps":["trace[593765150] 'agreement among raft nodes before linearized reading' (duration: 5.073313707s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.796007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.721583Z","time spent":"5.074415862s","remote":"127.0.0.1:52880","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-04-17T00:42:00.795095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.039213016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-17T00:42:00.79607Z","caller":"traceutil/trace.go:171","msg":"trace[1059738484] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:7305; }","duration":"5.040209179s","start":"2026-04-17T00:41:55.755852Z","end":"2026-04-17T00:42:00.796061Z","steps":["trace[1059738484] 'agreement among raft nodes before linearized reading' (duration: 5.039136095s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.796094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.755847Z","time spent":"5.040240959s","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157031733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-17T00:42:00.79619Z","caller":"traceutil/trace.go:171","msg":"trace[245351091] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7305; }","duration":"1.15825162s","start":"2026-04-17T00:41:59.637932Z","end":"2026-04-17T00:42:00.796183Z","steps":["trace[245351091] 'agreement among raft nodes before linearized reading' (duration: 1.157053894s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.79622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:59.637919Z","time spent":"1.158295451s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202486658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:00.796298Z","caller":"traceutil/trace.go:171","msg":"trace[1938132266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7305; }","duration":"1.203696465s","start":"2026-04-17T00:41:59.592596Z","end":"2026-04-17T00:42:00.796293Z","steps":["trace[1938132266] 'agreement among raft nodes before linearized reading' (duration: 1.202516509s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.796311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:59.592576Z","time spent":"1.203732406s","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.926957ms","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-04-17T00:42:00.796386Z","caller":"traceutil/trace.go:171","msg":"trace[1215438094] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7305; }","duration":"399.212475ms","start":"2026-04-17T00:42:00.397168Z","end":"2026-04-17T00:42:00.796381Z","steps":["trace[1215438094] 'agreement among raft nodes before linearized reading' (duration: 397.874656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.7964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.397153Z","time spent":"399.242586ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582152274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:00.796481Z","caller":"traceutil/trace.go:171","msg":"trace[236762326] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:7305; }","duration":"1.583516743s","start":"2026-04-17T00:41:59.212958Z","end":"2026-04-17T00:42:00.796475Z","steps":["trace[236762326] 'agreement among raft nodes before linearized reading' (duration: 1.582176984s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.796496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:59.212942Z","time spent":"1.583548805s","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:00.795178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656180882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:00.796583Z","caller":"traceutil/trace.go:171","msg":"trace[2038282309] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:7305; }","duration":"1.657607893s","start":"2026-04-17T00:41:59.138971Z","end":"2026-04-17T00:42:00.796579Z","steps":["trace[2038282309] 'agreement among raft nodes before linearized reading' (duration: 1.656191642s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.796598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:59.138958Z","time spent":"1.657635894s","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:00.795168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.656705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:00.796753Z","caller":"traceutil/trace.go:171","msg":"trace[103923568] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:7305; }","duration":"773.2924ms","start":"2026-04-17T00:42:00.023449Z","end":"2026-04-17T00:42:00.796741Z","steps":["trace[103923568] 'agreement among raft nodes before linearized reading' (duration: 771.671545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.796799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.023423Z","time spent":"773.360772ms","remote":"127.0.0.1:52922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:00.795197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.124292335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:00.796932Z","caller":"traceutil/trace.go:171","msg":"trace[1884371841] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:7305; }","duration":"2.126055423s","start":"2026-04-17T00:41:58.670865Z","end":"2026-04-17T00:42:00.79692Z","steps":["trace[1884371841] 'agreement among raft nodes before linearized reading' (duration: 2.124321285s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.79697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:58.670847Z","time spent":"2.126112585s","remote":"127.0.0.1:34946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:00.795216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.236467052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:00.797111Z","caller":"traceutil/trace.go:171","msg":"trace[661814356] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:7305; }","duration":"2.238385893s","start":"2026-04-17T00:41:58.558712Z","end":"2026-04-17T00:42:00.797098Z","steps":["trace[661814356] 'agreement among raft nodes before linearized reading' (duration: 2.236492362s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.797145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:58.558693Z","time spent":"2.238441854s","remote":"127.0.0.1:50756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:00.795241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.440633142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:00.797249Z","caller":"traceutil/trace.go:171","msg":"trace[931634777] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:7305; }","duration":"2.442655215s","start":"2026-04-17T00:41:58.354574Z","end":"2026-04-17T00:42:00.797229Z","steps":["trace[931634777] 'agreement among raft nodes before linearized reading' (duration: 2.440649322s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.795296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.444153393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-17T00:42:00.797309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:58.354559Z","time spent":"2.442727608s","remote":"127.0.0.1:53062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-17T00:42:00.797321Z","caller":"traceutil/trace.go:171","msg":"trace[1580990548] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:7305; }","duration":"3.446210787s","start":"2026-04-17T00:41:57.351103Z","end":"2026-04-17T00:42:00.797314Z","steps":["trace[1580990548] 'agreement among raft nodes before linearized reading' (duration: 3.444146183s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.797348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:57.351087Z","time spent":"3.446249078s","remote":"127.0.0.1:52686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.891590453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-17T00:42:00.79742Z","caller":"traceutil/trace.go:171","msg":"trace[1687250900] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7305; }","duration":"3.893679738s","start":"2026-04-17T00:41:56.903718Z","end":"2026-04-17T00:42:00.797398Z","steps":["trace[1687250900] 'agreement among raft nodes before linearized reading' (duration: 3.891585833s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.797507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:56.903706Z","time spent":"3.89377251s","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11519,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.518248269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:00.797754Z","caller":"traceutil/trace.go:171","msg":"trace[1186906001] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:7305; }","duration":"4.52067091s","start":"2026-04-17T00:41:56.277068Z","end":"2026-04-17T00:42:00.797739Z","steps":["trace[1186906001] 'agreement among raft nodes before linearized reading' (duration: 4.518272849s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.797797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:56.27705Z","time spent":"4.520731072s","remote":"127.0.0.1:49136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:00.79539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.802551017s","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-04-17T00:42:00.797976Z","caller":"traceutil/trace.go:171","msg":"trace[2062676708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7305; }","duration":"4.805154364s","start":"2026-04-17T00:41:55.992811Z","end":"2026-04-17T00:42:00.797965Z","steps":["trace[2062676708] 'agreement among raft nodes before linearized reading' (duration: 4.802551808s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.798007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.992801Z","time spent":"4.805197315s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.804337906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T00:42:00.79812Z","caller":"traceutil/trace.go:171","msg":"trace[533579505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7305; }","duration":"4.807075295s","start":"2026-04-17T00:41:55.991036Z","end":"2026-04-17T00:42:00.798111Z","steps":["trace[533579505] 'agreement among raft nodes before linearized reading' (duration: 4.804362057s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.798148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.990997Z","time spent":"4.807143037s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.804441319s","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-04-17T00:42:00.798217Z","caller":"traceutil/trace.go:171","msg":"trace[485043063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7305; }","duration":"4.807230829s","start":"2026-04-17T00:41:55.990974Z","end":"2026-04-17T00:42:00.798205Z","steps":["trace[485043063] 'agreement among raft nodes before linearized reading' (duration: 4.804456659s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.798264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.990954Z","time spent":"4.80729785s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T00:42:00.795369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.475089412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:00.798359Z","caller":"traceutil/trace.go:171","msg":"trace[2062383954] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:7305; }","duration":"4.478112247s","start":"2026-04-17T00:41:56.320234Z","end":"2026-04-17T00:42:00.798346Z","steps":["trace[2062383954] 'agreement among raft nodes before linearized reading' (duration: 4.475094502s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.798475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:56.320217Z","time spent":"4.478233029s","remote":"127.0.0.1:53116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:00.795504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.035580478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130326"} {"level":"warn","ts":"2026-04-17T00:42:00.795508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.222001742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:42:00.795534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.278248508s","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":"warn","ts":"2026-04-17T00:42:00.795526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.112340049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-17T00:42:00.795603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.22189509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:00.799005Z","caller":"traceutil/trace.go:171","msg":"trace[1063137325] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:7305; }","duration":"5.039107375s","start":"2026-04-17T00:41:55.759884Z","end":"2026-04-17T00:42:00.798991Z","steps":["trace[1063137325] 'agreement among raft nodes before linearized reading' (duration: 5.035577688s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.799549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:55.759865Z","time spent":"5.039650916s","remote":"127.0.0.1:52700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130349,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-17T00:42:00.799927Z","caller":"traceutil/trace.go:171","msg":"trace[1002712639] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7305; }","duration":"4.116758215s","start":"2026-04-17T00:41:56.683142Z","end":"2026-04-17T00:42:00.7999Z","steps":["trace[1002712639] 'agreement among raft nodes before linearized reading' (duration: 4.112325479s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:00.799951Z","caller":"traceutil/trace.go:171","msg":"trace[1424791429] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:7305; }","duration":"4.226295614s","start":"2026-04-17T00:41:56.573629Z","end":"2026-04-17T00:42:00.799924Z","steps":["trace[1424791429] 'agreement among raft nodes before linearized reading' (duration: 4.22190305s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.800041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:56.573617Z","time spent":"4.226405997s","remote":"127.0.0.1:58484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-17T00:42:00.800142Z","caller":"traceutil/trace.go:171","msg":"trace[1427863175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7305; }","duration":"4.282873408s","start":"2026-04-17T00:41:56.517251Z","end":"2026-04-17T00:42:00.800124Z","steps":["trace[1427863175] 'agreement among raft nodes before linearized reading' (duration: 4.278267659s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.800232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:56.517235Z","time spent":"4.28297672s","remote":"127.0.0.1:49172","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":"warn","ts":"2026-04-17T00:42:00.800602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:56.683123Z","time spent":"4.116871467s","remote":"127.0.0.1:52880","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":"info","ts":"2026-04-17T00:42:00.801548Z","caller":"traceutil/trace.go:171","msg":"trace[1080156605] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:7305; }","duration":"4.226030769s","start":"2026-04-17T00:41:56.573456Z","end":"2026-04-17T00:42:00.799487Z","steps":["trace[1080156605] 'agreement among raft nodes before linearized reading' (duration: 4.222018862s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:00.801667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:41:56.573439Z","time spent":"4.228208356s","remote":"127.0.0.1:47296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:01.105658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.501696ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-17T00:42:01.105848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.798915Z","time spent":"306.927584ms","remote":"127.0.0.1:52686","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T00:42:01.106939Z","caller":"traceutil/trace.go:171","msg":"trace[799243734] transaction","detail":"{read_only:false; response_revision:7307; number_of_response:1; }","duration":"307.479507ms","start":"2026-04-17T00:42:00.799435Z","end":"2026-04-17T00:42:01.106914Z","steps":["trace[799243734] 'process raft request' (duration: 306.333302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.107091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.799414Z","time spent":"307.59088ms","remote":"127.0.0.1:52742","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-04-17T00:42:01.107134Z","caller":"traceutil/trace.go:171","msg":"trace[365756242] transaction","detail":"{read_only:false; response_revision:7308; number_of_response:1; }","duration":"305.516435ms","start":"2026-04-17T00:42:00.801571Z","end":"2026-04-17T00:42:01.107087Z","steps":["trace[365756242] 'process raft request' (duration: 305.282959ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:01.107349Z","caller":"traceutil/trace.go:171","msg":"trace[14098119] transaction","detail":"{read_only:false; response_revision:7316; number_of_response:1; }","duration":"293.449626ms","start":"2026-04-17T00:42:00.813886Z","end":"2026-04-17T00:42:01.107336Z","steps":["trace[14098119] 'process raft request' (duration: 293.415145ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:01.107688Z","caller":"traceutil/trace.go:171","msg":"trace[1478090320] transaction","detail":"{read_only:false; response_revision:7309; number_of_response:1; }","duration":"303.597544ms","start":"2026-04-17T00:42:00.804074Z","end":"2026-04-17T00:42:01.107672Z","steps":["trace[1478090320] 'process raft request' (duration: 302.815347ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:01.107683Z","caller":"traceutil/trace.go:171","msg":"trace[707972588] transaction","detail":"{read_only:false; response_revision:7312; number_of_response:1; }","duration":"303.506422ms","start":"2026-04-17T00:42:00.80416Z","end":"2026-04-17T00:42:01.107667Z","steps":["trace[707972588] 'process raft request' (duration: 303.041952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.107764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.80414Z","time spent":"303.588884ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:01.107901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.804069Z","time spent":"303.654985ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:01.107953Z","caller":"traceutil/trace.go:171","msg":"trace[269414744] transaction","detail":"{read_only:false; response_revision:7311; number_of_response:1; }","duration":"303.780288ms","start":"2026-04-17T00:42:00.804165Z","end":"2026-04-17T00:42:01.107945Z","steps":["trace[269414744] 'process raft request' (duration: 302.802897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.108004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.804153Z","time spent":"303.820959ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:01.107923Z","caller":"traceutil/trace.go:171","msg":"trace[1603481329] transaction","detail":"{read_only:false; response_revision:7310; number_of_response:1; }","duration":"303.765518ms","start":"2026-04-17T00:42:00.804146Z","end":"2026-04-17T00:42:01.107912Z","steps":["trace[1603481329] 'process raft request' (duration: 302.774637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.108307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.804129Z","time spent":"304.090244ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:01.108417Z","caller":"traceutil/trace.go:171","msg":"trace[964503514] transaction","detail":"{read_only:false; response_revision:7315; number_of_response:1; }","duration":"302.864618ms","start":"2026-04-17T00:42:00.805526Z","end":"2026-04-17T00:42:01.10839Z","steps":["trace[964503514] 'process raft request' (duration: 301.750745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.108542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.805519Z","time spent":"302.96194ms","remote":"127.0.0.1:52880","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-04-17T00:42:01.108633Z","caller":"traceutil/trace.go:171","msg":"trace[677594384] transaction","detail":"{read_only:false; response_revision:7313; number_of_response:1; }","duration":"303.787157ms","start":"2026-04-17T00:42:00.804841Z","end":"2026-04-17T00:42:01.108628Z","steps":["trace[677594384] 'process raft request' (duration: 302.389568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.108665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.804827Z","time spent":"303.825367ms","remote":"127.0.0.1:52880","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-04-17T00:42:01.10869Z","caller":"traceutil/trace.go:171","msg":"trace[211400254] linearizableReadLoop","detail":"{readStateIndex:7654; appliedIndex:7644; }","duration":"297.304048ms","start":"2026-04-17T00:42:00.81134Z","end":"2026-04-17T00:42:01.108644Z","steps":["trace[211400254] 'read index received' (duration: 56.828429ms)","trace[211400254] 'applied index is now lower than readState.Index' (duration: 240.472629ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:01.10892Z","caller":"traceutil/trace.go:171","msg":"trace[1353140895] transaction","detail":"{read_only:false; response_revision:7314; number_of_response:1; }","duration":"303.564362ms","start":"2026-04-17T00:42:00.805346Z","end":"2026-04-17T00:42:01.10891Z","steps":["trace[1353140895] 'process raft request' (duration: 301.911367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.108966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.805338Z","time spent":"303.602114ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:01.108987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.314548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-17T00:42:01.109024Z","caller":"traceutil/trace.go:171","msg":"trace[241932862] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7316; }","duration":"304.383249ms","start":"2026-04-17T00:42:00.80463Z","end":"2026-04-17T00:42:01.109013Z","steps":["trace[241932862] 'agreement among raft nodes before linearized reading' (duration: 304.218016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.109056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.804582Z","time spent":"304.466602ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11519,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-17T00:42:01.109418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.619885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:40 size:56991"} {"level":"info","ts":"2026-04-17T00:42:01.10945Z","caller":"traceutil/trace.go:171","msg":"trace[165003722] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:40; response_revision:7316; }","duration":"311.677106ms","start":"2026-04-17T00:42:00.797765Z","end":"2026-04-17T00:42:01.109442Z","steps":["trace[165003722] 'agreement among raft nodes before linearized reading' (duration: 311.337589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.109473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.797732Z","time spent":"311.735618ms","remote":"127.0.0.1:52812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":40,"response size":57014,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-04-17T00:42:01.109766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.744681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:40 size:56991"} {"level":"info","ts":"2026-04-17T00:42:01.109821Z","caller":"traceutil/trace.go:171","msg":"trace[2027014448] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:40; response_revision:7316; }","duration":"305.814242ms","start":"2026-04-17T00:42:00.803995Z","end":"2026-04-17T00:42:01.109809Z","steps":["trace[2027014448] 'agreement among raft nodes before linearized reading' (duration: 305.134276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.109864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.803988Z","time spent":"305.866253ms","remote":"127.0.0.1:52812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":40,"response size":57014,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-04-17T00:42:01.110922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.801561Z","time spent":"305.605016ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:01.175531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.586043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:01.176069Z","caller":"traceutil/trace.go:171","msg":"trace[601650822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7317; }","duration":"359.138415ms","start":"2026-04-17T00:42:00.81692Z","end":"2026-04-17T00:42:01.176058Z","steps":["trace[601650822] 'agreement among raft nodes before linearized reading' (duration: 358.543892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.176089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:00.816884Z","time spent":"359.199356ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:42:01.416863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.27012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:01.416989Z","caller":"traceutil/trace.go:171","msg":"trace[882896544] transaction","detail":"{read_only:false; response_revision:7318; number_of_response:1; }","duration":"237.580347ms","start":"2026-04-17T00:42:01.17938Z","end":"2026-04-17T00:42:01.41696Z","steps":["trace[882896544] 'process raft request' (duration: 28.128473ms)","trace[882896544] 'compare' (duration: 208.949313ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:01.417059Z","caller":"traceutil/trace.go:171","msg":"trace[1544279933] transaction","detail":"{read_only:false; response_revision:7319; number_of_response:1; }","duration":"236.174247ms","start":"2026-04-17T00:42:01.180863Z","end":"2026-04-17T00:42:01.417037Z","steps":["trace[1544279933] 'process raft request' (duration: 236.106565ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:01.417102Z","caller":"traceutil/trace.go:171","msg":"trace[1072407159] linearizableReadLoop","detail":"{readStateIndex:7657; appliedIndex:7656; }","duration":"236.964293ms","start":"2026-04-17T00:42:01.180108Z","end":"2026-04-17T00:42:01.417073Z","steps":["trace[1072407159] 'read index received' (duration: 27.404027ms)","trace[1072407159] 'applied index is now lower than readState.Index' (duration: 209.557416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:01.417307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.180248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T00:42:01.417346Z","caller":"traceutil/trace.go:171","msg":"trace[1488672042] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:7319; }","duration":"237.25008ms","start":"2026-04-17T00:42:01.180085Z","end":"2026-04-17T00:42:01.417335Z","steps":["trace[1488672042] 'agreement among raft nodes before linearized reading' (duration: 237.106567ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:01.443603Z","caller":"traceutil/trace.go:171","msg":"trace[787442367] transaction","detail":"{read_only:false; response_revision:7320; number_of_response:1; }","duration":"225.487408ms","start":"2026-04-17T00:42:01.218089Z","end":"2026-04-17T00:42:01.443576Z","steps":["trace[787442367] 'process raft request' (duration: 225.269794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.443624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.679859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:01.443701Z","caller":"traceutil/trace.go:171","msg":"trace[1028338051] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:7320; }","duration":"140.823791ms","start":"2026-04-17T00:42:01.302859Z","end":"2026-04-17T00:42:01.443683Z","steps":["trace[1028338051] 'agreement among raft nodes before linearized reading' (duration: 140.679558ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:01.781002Z","caller":"traceutil/trace.go:171","msg":"trace[1935656933] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7320; }","duration":"329.880997ms","start":"2026-04-17T00:42:01.451097Z","end":"2026-04-17T00:42:01.780978Z","steps":["trace[1935656933] 'process raft request' (duration: 280.422326ms)","trace[1935656933] 'compare' (duration: 49.41057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:01.781132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:01.451066Z","time spent":"330.00631ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:42:01.889422Z","caller":"traceutil/trace.go:171","msg":"trace[1818804560] linearizableReadLoop","detail":"{readStateIndex:7661; appliedIndex:7660; }","duration":"115.315024ms","start":"2026-04-17T00:42:01.774085Z","end":"2026-04-17T00:42:01.8894Z","steps":["trace[1818804560] 'read index received' (duration: 115.11475ms)","trace[1818804560] 'applied index is now lower than readState.Index' (duration: 199.194µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:01.88953Z","caller":"traceutil/trace.go:171","msg":"trace[1112596439] transaction","detail":"{read_only:false; response_revision:7321; number_of_response:1; }","duration":"228.037903ms","start":"2026-04-17T00:42:01.661481Z","end":"2026-04-17T00:42:01.889519Z","steps":["trace[1112596439] 'process raft request' (duration: 227.785798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.889947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.820443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-04-17T00:42:01.890073Z","caller":"traceutil/trace.go:171","msg":"trace[1626753545] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:7321; }","duration":"107.986657ms","start":"2026-04-17T00:42:01.782066Z","end":"2026-04-17T00:42:01.890053Z","steps":["trace[1626753545] 'agreement among raft nodes before linearized reading' (duration: 107.65294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:01.89147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.376519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" ","response":"range_response_count:69 size:4259720"} {"level":"info","ts":"2026-04-17T00:42:01.891556Z","caller":"traceutil/trace.go:171","msg":"trace[747758415] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:69; response_revision:7321; }","duration":"117.512722ms","start":"2026-04-17T00:42:01.774024Z","end":"2026-04-17T00:42:01.891536Z","steps":["trace[747758415] 'agreement among raft nodes before linearized reading' (duration: 115.615021ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:02.091972Z","caller":"traceutil/trace.go:171","msg":"trace[1071037880] transaction","detail":"{read_only:false; response_revision:7322; number_of_response:1; }","duration":"190.854024ms","start":"2026-04-17T00:42:01.901068Z","end":"2026-04-17T00:42:02.091922Z","steps":["trace[1071037880] 'process raft request' (duration: 124.875059ms)","trace[1071037880] 'compare' (duration: 65.842882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:02.693266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.128741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:02.693346Z","caller":"traceutil/trace.go:171","msg":"trace[1131896165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7322; }","duration":"109.254934ms","start":"2026-04-17T00:42:02.584072Z","end":"2026-04-17T00:42:02.693327Z","steps":["trace[1131896165] 'range keys from in-memory index tree' (duration: 109.017329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:02.827149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.951494ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T00:42:02.827253Z","caller":"traceutil/trace.go:171","msg":"trace[1105256967] linearizableReadLoop","detail":"{readStateIndex:7663; appliedIndex:7662; }","duration":"132.999563ms","start":"2026-04-17T00:42:02.694234Z","end":"2026-04-17T00:42:02.827234Z","steps":["trace[1105256967] 'read index received' (duration: 42.681µs)","trace[1105256967] 'applied index is now lower than readState.Index' (duration: 132.955342ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:02.827365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.123247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:02.827398Z","caller":"traceutil/trace.go:171","msg":"trace[36221138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7322; }","duration":"133.187788ms","start":"2026-04-17T00:42:02.694201Z","end":"2026-04-17T00:42:02.827389Z","steps":["trace[36221138] 'agreement among raft nodes before linearized reading' (duration: 133.105406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:02.873174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.918003ms","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-04-17T00:42:02.873259Z","caller":"traceutil/trace.go:171","msg":"trace[1837611233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7323; }","duration":"148.045276ms","start":"2026-04-17T00:42:02.725192Z","end":"2026-04-17T00:42:02.873237Z","steps":["trace[1837611233] 'agreement among raft nodes before linearized reading' (duration: 147.895453ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:03.050799Z","caller":"traceutil/trace.go:171","msg":"trace[1434036350] transaction","detail":"{read_only:false; response_revision:7324; number_of_response:1; }","duration":"172.991651ms","start":"2026-04-17T00:42:02.877776Z","end":"2026-04-17T00:42:03.050768Z","steps":["trace[1434036350] 'process raft request' (duration: 74.064349ms)","trace[1434036350] 'compare' (duration: 98.762008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:03.641948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.603808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:03.642066Z","caller":"traceutil/trace.go:171","msg":"trace[1137532592] linearizableReadLoop","detail":"{readStateIndex:7672; appliedIndex:7671; }","duration":"150.591659ms","start":"2026-04-17T00:42:03.491457Z","end":"2026-04-17T00:42:03.642049Z","steps":["trace[1137532592] 'read index received' (duration: 49.734426ms)","trace[1137532592] 'applied index is now lower than readState.Index' (duration: 100.856073ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:03.642171Z","caller":"traceutil/trace.go:171","msg":"trace[1072888012] transaction","detail":"{read_only:false; response_revision:7331; number_of_response:1; }","duration":"204.129018ms","start":"2026-04-17T00:42:03.438016Z","end":"2026-04-17T00:42:03.642145Z","steps":["trace[1072888012] 'process raft request' (duration: 103.241814ms)","trace[1072888012] 'compare' (duration: 100.469135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:03.642241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.769604ms","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-04-17T00:42:03.642277Z","caller":"traceutil/trace.go:171","msg":"trace[1845882128] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7331; }","duration":"150.850576ms","start":"2026-04-17T00:42:03.491416Z","end":"2026-04-17T00:42:03.642267Z","steps":["trace[1845882128] 'agreement among raft nodes before linearized reading' (duration: 150.678341ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:03.80026Z","caller":"traceutil/trace.go:171","msg":"trace[1391139292] transaction","detail":"{read_only:false; response_revision:7333; number_of_response:1; }","duration":"152.277336ms","start":"2026-04-17T00:42:03.647953Z","end":"2026-04-17T00:42:03.800231Z","steps":["trace[1391139292] 'process raft request' (duration: 152.204875ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:03.80027Z","caller":"traceutil/trace.go:171","msg":"trace[821748306] transaction","detail":"{read_only:false; response_revision:7332; number_of_response:1; }","duration":"154.670878ms","start":"2026-04-17T00:42:03.645561Z","end":"2026-04-17T00:42:03.800232Z","steps":["trace[821748306] 'process raft request' (duration: 105.458492ms)","trace[821748306] 'compare' (duration: 49.025252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:05.428478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.28878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T00:42:05.428531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.339159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T00:42:05.428556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.196022ms","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-04-17T00:42:05.428595Z","caller":"traceutil/trace.go:171","msg":"trace[1928885385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7334; }","duration":"374.440412ms","start":"2026-04-17T00:42:05.054139Z","end":"2026-04-17T00:42:05.42858Z","steps":["trace[1928885385] 'range keys from in-memory index tree' (duration: 374.199966ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:05.428614Z","caller":"traceutil/trace.go:171","msg":"trace[1870321443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7334; }","duration":"246.295423ms","start":"2026-04-17T00:42:05.182303Z","end":"2026-04-17T00:42:05.428598Z","steps":["trace[1870321443] 'range keys from in-memory index tree' (duration: 246.051048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:05.428638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:05.054127Z","time spent":"374.502083ms","remote":"127.0.0.1:52880","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":"warn","ts":"2026-04-17T00:42:05.428787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.402206ms","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-04-17T00:42:05.428823Z","caller":"traceutil/trace.go:171","msg":"trace[698236543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7334; }","duration":"295.461048ms","start":"2026-04-17T00:42:05.133352Z","end":"2026-04-17T00:42:05.428813Z","steps":["trace[698236543] 'range keys from in-memory index tree' (duration: 295.271264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:05.428863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.1341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-17T00:42:05.428904Z","caller":"traceutil/trace.go:171","msg":"trace[1255814546] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7334; }","duration":"246.240162ms","start":"2026-04-17T00:42:05.182653Z","end":"2026-04-17T00:42:05.428893Z","steps":["trace[1255814546] 'range keys from in-memory index tree' (duration: 245.861284ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:05.428565Z","caller":"traceutil/trace.go:171","msg":"trace[1306103937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7334; }","duration":"244.424703ms","start":"2026-04-17T00:42:05.184113Z","end":"2026-04-17T00:42:05.428538Z","steps":["trace[1306103937] 'range keys from in-memory index tree' (duration: 244.041194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:05.42901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.09678ms","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-04-17T00:42:05.429077Z","caller":"traceutil/trace.go:171","msg":"trace[1401755835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7334; }","duration":"246.205771ms","start":"2026-04-17T00:42:05.18286Z","end":"2026-04-17T00:42:05.429066Z","steps":["trace[1401755835] 'range keys from in-memory index tree' (duration: 245.888724ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:05.796533Z","caller":"traceutil/trace.go:171","msg":"trace[525167696] linearizableReadLoop","detail":"{readStateIndex:7681; appliedIndex:7676; }","duration":"211.673761ms","start":"2026-04-17T00:42:05.584837Z","end":"2026-04-17T00:42:05.796511Z","steps":["trace[525167696] 'read index received' (duration: 140.995024ms)","trace[525167696] 'applied index is now lower than readState.Index' (duration: 70.677727ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:05.796548Z","caller":"traceutil/trace.go:171","msg":"trace[1146436037] transaction","detail":"{read_only:false; response_revision:7338; number_of_response:1; }","duration":"363.558029ms","start":"2026-04-17T00:42:05.43296Z","end":"2026-04-17T00:42:05.796518Z","steps":["trace[1146436037] 'process raft request' (duration: 363.438596ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:05.796626Z","caller":"traceutil/trace.go:171","msg":"trace[1188008851] transaction","detail":"{read_only:false; response_revision:7336; number_of_response:1; }","duration":"364.10965ms","start":"2026-04-17T00:42:05.432504Z","end":"2026-04-17T00:42:05.796613Z","steps":["trace[1188008851] 'process raft request' (duration: 293.313081ms)","trace[1188008851] 'compare' (duration: 70.128974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:05.796699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:05.432938Z","time spent":"363.677541ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:05.796734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:05.432484Z","time spent":"364.177862ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:05.796867Z","caller":"traceutil/trace.go:171","msg":"trace[1193981644] transaction","detail":"{read_only:false; response_revision:7337; number_of_response:1; }","duration":"364.287664ms","start":"2026-04-17T00:42:05.432569Z","end":"2026-04-17T00:42:05.796856Z","steps":["trace[1193981644] 'process raft request' (duration: 363.767302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:05.796932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:05.432564Z","time spent":"364.335865ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:05.796552Z","caller":"traceutil/trace.go:171","msg":"trace[653736429] transaction","detail":"{read_only:false; response_revision:7339; number_of_response:1; }","duration":"362.858514ms","start":"2026-04-17T00:42:05.43367Z","end":"2026-04-17T00:42:05.796529Z","steps":["trace[653736429] 'process raft request' (duration: 362.761682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:05.797043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:05.433661Z","time spent":"363.354995ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:05.796687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.843754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:05.798081Z","caller":"traceutil/trace.go:171","msg":"trace[915740035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7340; }","duration":"213.258044ms","start":"2026-04-17T00:42:05.584792Z","end":"2026-04-17T00:42:05.79805Z","steps":["trace[915740035] 'agreement among raft nodes before linearized reading' (duration: 211.809554ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:05.799725Z","caller":"traceutil/trace.go:171","msg":"trace[588731566] transaction","detail":"{read_only:false; response_revision:7340; number_of_response:1; }","duration":"360.126255ms","start":"2026-04-17T00:42:05.439577Z","end":"2026-04-17T00:42:05.799704Z","steps":["trace[588731566] 'process raft request' (duration: 356.890916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:05.799814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:05.439558Z","time spent":"360.212618ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:06.098443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.903822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:06.09868Z","caller":"traceutil/trace.go:171","msg":"trace[2104476859] linearizableReadLoop","detail":"{readStateIndex:7682; appliedIndex:7681; }","duration":"284.596744ms","start":"2026-04-17T00:42:05.81404Z","end":"2026-04-17T00:42:06.098637Z","steps":["trace[2104476859] 'read index received' (duration: 109.327405ms)","trace[2104476859] 'applied index is now lower than readState.Index' (duration: 175.267809ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:06.098786Z","caller":"traceutil/trace.go:171","msg":"trace[1019347609] transaction","detail":"{read_only:false; response_revision:7342; number_of_response:1; }","duration":"282.668713ms","start":"2026-04-17T00:42:05.816106Z","end":"2026-04-17T00:42:06.098775Z","steps":["trace[1019347609] 'process raft request' (duration: 282.476669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:06.09878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.722807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:06.098778Z","caller":"traceutil/trace.go:171","msg":"trace[625795437] transaction","detail":"{read_only:false; response_revision:7341; number_of_response:1; }","duration":"285.076554ms","start":"2026-04-17T00:42:05.813658Z","end":"2026-04-17T00:42:06.098735Z","steps":["trace[625795437] 'process raft request' (duration: 109.773995ms)","trace[625795437] 'compare' (duration: 174.762818ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:06.098848Z","caller":"traceutil/trace.go:171","msg":"trace[1288131834] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:7341; }","duration":"284.802089ms","start":"2026-04-17T00:42:05.814036Z","end":"2026-04-17T00:42:06.098838Z","steps":["trace[1288131834] 'agreement among raft nodes before linearized reading' (duration: 284.669916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:06.615122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245264,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:42:06.780554Z","caller":"traceutil/trace.go:171","msg":"trace[738392515] transaction","detail":"{read_only:false; response_revision:7343; number_of_response:1; }","duration":"674.648313ms","start":"2026-04-17T00:42:06.10588Z","end":"2026-04-17T00:42:06.780528Z","steps":["trace[738392515] 'process raft request' (duration: 618.461838ms)","trace[738392515] 'compare' (duration: 56.070322ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:06.781222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:06.105871Z","time spent":"675.278136ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:06.859021Z","caller":"traceutil/trace.go:171","msg":"trace[1485073579] linearizableReadLoop","detail":"{readStateIndex:7685; appliedIndex:7683; }","duration":"744.380068ms","start":"2026-04-17T00:42:06.114621Z","end":"2026-04-17T00:42:06.859001Z","steps":["trace[1485073579] 'read index received' (duration: 609.73466ms)","trace[1485073579] 'applied index is now lower than readState.Index' (duration: 134.644368ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:06.859077Z","caller":"traceutil/trace.go:171","msg":"trace[1343332708] transaction","detail":"{read_only:false; response_revision:7344; number_of_response:1; }","duration":"752.280728ms","start":"2026-04-17T00:42:06.106781Z","end":"2026-04-17T00:42:06.859061Z","steps":["trace[1343332708] 'process raft request' (duration: 752.089124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:06.859163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:06.10677Z","time spent":"752.338619ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:06.85918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.20691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:06.859236Z","caller":"traceutil/trace.go:171","msg":"trace[133235572] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:7344; }","duration":"328.307672ms","start":"2026-04-17T00:42:06.530914Z","end":"2026-04-17T00:42:06.859222Z","steps":["trace[133235572] 'agreement among raft nodes before linearized reading' (duration: 328.21194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:06.859284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:06.530897Z","time spent":"328.371423ms","remote":"127.0.0.1:34968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:06.859365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.697595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3197"} {"level":"info","ts":"2026-04-17T00:42:06.859469Z","caller":"traceutil/trace.go:171","msg":"trace[1779732126] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:7344; }","duration":"744.885379ms","start":"2026-04-17T00:42:06.114558Z","end":"2026-04-17T00:42:06.859444Z","steps":["trace[1779732126] 'agreement among raft nodes before linearized reading' (duration: 744.567622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:06.859533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:06.114547Z","time spent":"744.974881ms","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3220,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-17T00:42:07.043451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.834475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:07.043544Z","caller":"traceutil/trace.go:171","msg":"trace[1305076309] transaction","detail":"{read_only:false; response_revision:7345; number_of_response:1; }","duration":"141.431674ms","start":"2026-04-17T00:42:06.902097Z","end":"2026-04-17T00:42:07.043528Z","steps":["trace[1305076309] 'process raft request' (duration: 25.453626ms)","trace[1305076309] 'compare' (duration: 115.702552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:07.306436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.90292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-17T00:42:07.306494Z","caller":"traceutil/trace.go:171","msg":"trace[1159789232] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7345; }","duration":"146.030573ms","start":"2026-04-17T00:42:07.160451Z","end":"2026-04-17T00:42:07.306481Z","steps":["trace[1159789232] 'range keys from in-memory index tree' (duration: 145.739716ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:07.60124Z","caller":"traceutil/trace.go:171","msg":"trace[2073734989] linearizableReadLoop","detail":"{readStateIndex:7687; appliedIndex:7686; }","duration":"249.024193ms","start":"2026-04-17T00:42:07.352198Z","end":"2026-04-17T00:42:07.601222Z","steps":["trace[2073734989] 'read index received' (duration: 248.842509ms)","trace[2073734989] 'applied index is now lower than readState.Index' (duration: 180.824µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:07.601649Z","caller":"traceutil/trace.go:171","msg":"trace[812094089] transaction","detail":"{read_only:false; response_revision:7346; number_of_response:1; }","duration":"253.200221ms","start":"2026-04-17T00:42:07.348435Z","end":"2026-04-17T00:42:07.601636Z","steps":["trace[812094089] 'process raft request' (duration: 252.6661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:07.601982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.753878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T00:42:07.602052Z","caller":"traceutil/trace.go:171","msg":"trace[464395958] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:7346; }","duration":"249.86413ms","start":"2026-04-17T00:42:07.352171Z","end":"2026-04-17T00:42:07.602036Z","steps":["trace[464395958] 'agreement among raft nodes before linearized reading' (duration: 249.649715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.324663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.935568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:08.325015Z","caller":"traceutil/trace.go:171","msg":"trace[652123501] linearizableReadLoop","detail":"{readStateIndex:7691; appliedIndex:7688; }","duration":"524.498301ms","start":"2026-04-17T00:42:07.8005Z","end":"2026-04-17T00:42:08.324998Z","steps":["trace[652123501] 'read index received' (duration: 318.061602ms)","trace[652123501] 'applied index is now lower than readState.Index' (duration: 206.435799ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:08.325255Z","caller":"traceutil/trace.go:171","msg":"trace[1829754452] transaction","detail":"{read_only:false; response_revision:7347; number_of_response:1; }","duration":"716.47855ms","start":"2026-04-17T00:42:07.608759Z","end":"2026-04-17T00:42:08.325238Z","steps":["trace[1829754452] 'process raft request' (duration: 509.860797ms)","trace[1829754452] 'compare' (duration: 205.639352ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:08.325353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:07.60874Z","time spent":"716.561141ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:08.325571Z","caller":"traceutil/trace.go:171","msg":"trace[373725537] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7347; }","duration":"716.58423ms","start":"2026-04-17T00:42:07.608974Z","end":"2026-04-17T00:42:08.325558Z","steps":["trace[373725537] 'process raft request' (duration: 715.800954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.325656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:07.608954Z","time spent":"716.663973ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:42:08.325932Z","caller":"traceutil/trace.go:171","msg":"trace[177379261] transaction","detail":"{read_only:false; response_revision:7348; number_of_response:1; }","duration":"595.998514ms","start":"2026-04-17T00:42:07.729917Z","end":"2026-04-17T00:42:08.325915Z","steps":["trace[177379261] 'process raft request' (duration: 595.016583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.326259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:07.729898Z","time spent":"596.085945ms","remote":"127.0.0.1:52686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:08.326687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.179317ms","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-04-17T00:42:08.326826Z","caller":"traceutil/trace.go:171","msg":"trace[829675096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7348; }","duration":"526.226548ms","start":"2026-04-17T00:42:07.800494Z","end":"2026-04-17T00:42:08.326721Z","steps":["trace[829675096] 'agreement among raft nodes before linearized reading' (duration: 526.098035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.326935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:07.80045Z","time spent":"526.412842ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T00:42:08.327275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.096635ms","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-04-17T00:42:08.327316Z","caller":"traceutil/trace.go:171","msg":"trace[291079493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7348; }","duration":"526.162657ms","start":"2026-04-17T00:42:07.801144Z","end":"2026-04-17T00:42:08.327306Z","steps":["trace[291079493] 'agreement among raft nodes before linearized reading' (duration: 526.059395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.327528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:07.80113Z","time spent":"526.30501ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T00:42:08.327928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.660066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3197"} {"level":"info","ts":"2026-04-17T00:42:08.328094Z","caller":"traceutil/trace.go:171","msg":"trace[1830642322] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:7348; }","duration":"229.752278ms","start":"2026-04-17T00:42:08.098217Z","end":"2026-04-17T00:42:08.327969Z","steps":["trace[1830642322] 'agreement among raft nodes before linearized reading' (duration: 229.617375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.328439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.462991ms","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-04-17T00:42:08.328487Z","caller":"traceutil/trace.go:171","msg":"trace[1928247237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7348; }","duration":"525.532583ms","start":"2026-04-17T00:42:07.802944Z","end":"2026-04-17T00:42:08.328476Z","steps":["trace[1928247237] 'agreement among raft nodes before linearized reading' (duration: 525.412451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.328676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:07.802936Z","time spent":"525.669786ms","remote":"127.0.0.1:52880","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-04-17T00:42:08.329111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.587698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-17T00:42:08.329159Z","caller":"traceutil/trace.go:171","msg":"trace[1435878574] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7348; }","duration":"527.653579ms","start":"2026-04-17T00:42:07.801494Z","end":"2026-04-17T00:42:08.329148Z","steps":["trace[1435878574] 'agreement among raft nodes before linearized reading' (duration: 527.530677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.329193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:07.801487Z","time spent":"527.69976ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-17T00:42:08.329532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.488476ms","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-04-17T00:42:08.329657Z","caller":"traceutil/trace.go:171","msg":"trace[2051299246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7348; }","duration":"527.580218ms","start":"2026-04-17T00:42:07.801996Z","end":"2026-04-17T00:42:08.329576Z","steps":["trace[2051299246] 'agreement among raft nodes before linearized reading' (duration: 527.378134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.329786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:07.801988Z","time spent":"527.786932ms","remote":"127.0.0.1:52880","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-04-17T00:42:08.774864Z","caller":"traceutil/trace.go:171","msg":"trace[189184997] transaction","detail":"{read_only:false; response_revision:7350; number_of_response:1; }","duration":"439.278352ms","start":"2026-04-17T00:42:08.335562Z","end":"2026-04-17T00:42:08.77484Z","steps":["trace[189184997] 'process raft request' (duration: 355.068826ms)","trace[189184997] 'compare' (duration: 83.806178ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:08.775018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.335544Z","time spent":"439.386635ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:08.775286Z","caller":"traceutil/trace.go:171","msg":"trace[1826744619] transaction","detail":"{read_only:false; response_revision:7352; number_of_response:1; }","duration":"438.854574ms","start":"2026-04-17T00:42:08.336403Z","end":"2026-04-17T00:42:08.775258Z","steps":["trace[1826744619] 'process raft request' (duration: 438.743362ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:08.775469Z","caller":"traceutil/trace.go:171","msg":"trace[503436203] linearizableReadLoop","detail":"{readStateIndex:7695; appliedIndex:7691; }","duration":"438.334073ms","start":"2026-04-17T00:42:08.337093Z","end":"2026-04-17T00:42:08.775427Z","steps":["trace[503436203] 'read index received' (duration: 70.934602ms)","trace[503436203] 'applied index is now lower than readState.Index' (duration: 367.398461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:08.775495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.336396Z","time spent":"438.995197ms","remote":"127.0.0.1:52880","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-04-17T00:42:08.775599Z","caller":"traceutil/trace.go:171","msg":"trace[1298033077] transaction","detail":"{read_only:false; response_revision:7353; number_of_response:1; }","duration":"434.074971ms","start":"2026-04-17T00:42:08.341512Z","end":"2026-04-17T00:42:08.775587Z","steps":["trace[1298033077] 'process raft request' (duration: 433.712683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.775728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.341497Z","time spent":"434.159403ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:08.775791Z","caller":"traceutil/trace.go:171","msg":"trace[992521415] transaction","detail":"{read_only:false; response_revision:7351; number_of_response:1; }","duration":"440.023589ms","start":"2026-04-17T00:42:08.335694Z","end":"2026-04-17T00:42:08.775718Z","steps":["trace[992521415] 'process raft request' (duration: 439.091159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.775895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.796222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-17T00:42:08.775934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.335687Z","time spent":"440.173602ms","remote":"127.0.0.1:52880","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-04-17T00:42:08.775943Z","caller":"traceutil/trace.go:171","msg":"trace[2067314876] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:7354; }","duration":"438.860604ms","start":"2026-04-17T00:42:08.337071Z","end":"2026-04-17T00:42:08.775931Z","steps":["trace[2067314876] 'agreement among raft nodes before linearized reading' (duration: 438.730742ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:08.776072Z","caller":"traceutil/trace.go:171","msg":"trace[2013058862] transaction","detail":"{read_only:false; response_revision:7354; number_of_response:1; }","duration":"431.078377ms","start":"2026-04-17T00:42:08.344983Z","end":"2026-04-17T00:42:08.776062Z","steps":["trace[2013058862] 'process raft request' (duration: 430.340291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.776075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.337063Z","time spent":"439.003777ms","remote":"127.0.0.1:52896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-17T00:42:08.776104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.605499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:08.776183Z","caller":"traceutil/trace.go:171","msg":"trace[659035461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7354; }","duration":"191.727912ms","start":"2026-04-17T00:42:08.584437Z","end":"2026-04-17T00:42:08.776165Z","steps":["trace[659035461] 'agreement among raft nodes before linearized reading' (duration: 191.588339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.776248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.902499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3197"} {"level":"info","ts":"2026-04-17T00:42:08.776358Z","caller":"traceutil/trace.go:171","msg":"trace[615313219] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:7354; }","duration":"435.051072ms","start":"2026-04-17T00:42:08.341292Z","end":"2026-04-17T00:42:08.776343Z","steps":["trace[615313219] 'agreement among raft nodes before linearized reading' (duration: 434.609313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:08.776443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.341273Z","time spent":"435.158604ms","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3220,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-17T00:42:08.776136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.344971Z","time spent":"431.130888ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16842,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:09.10872Z","caller":"traceutil/trace.go:171","msg":"trace[1821936529] transaction","detail":"{read_only:false; response_revision:7356; number_of_response:1; }","duration":"231.919035ms","start":"2026-04-17T00:42:08.876779Z","end":"2026-04-17T00:42:09.108698Z","steps":["trace[1821936529] 'process raft request' (duration: 231.781832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:09.296484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.907026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:09.296861Z","caller":"traceutil/trace.go:171","msg":"trace[339850335] linearizableReadLoop","detail":"{readStateIndex:7700; appliedIndex:7698; }","duration":"416.912703ms","start":"2026-04-17T00:42:08.879928Z","end":"2026-04-17T00:42:09.29684Z","steps":["trace[339850335] 'read index received' (duration: 228.604974ms)","trace[339850335] 'applied index is now lower than readState.Index' (duration: 188.306219ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:09.296959Z","caller":"traceutil/trace.go:171","msg":"trace[966868211] transaction","detail":"{read_only:false; response_revision:7357; number_of_response:1; }","duration":"419.242983ms","start":"2026-04-17T00:42:08.877682Z","end":"2026-04-17T00:42:09.296925Z","steps":["trace[966868211] 'process raft request' (duration: 281.798535ms)","trace[966868211] 'compare' (duration: 136.564959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:09.297761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.877667Z","time spent":"420.00948ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:09.2981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.328382ms","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-04-17T00:42:09.298196Z","caller":"traceutil/trace.go:171","msg":"trace[1996791308] 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:7358; }","duration":"300.428605ms","start":"2026-04-17T00:42:08.997754Z","end":"2026-04-17T00:42:09.298182Z","steps":["trace[1996791308] 'agreement among raft nodes before linearized reading' (duration: 300.296641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:09.298285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.997716Z","time spent":"300.553948ms","remote":"127.0.0.1:34970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-17T00:42:09.296988Z","caller":"traceutil/trace.go:171","msg":"trace[1103806327] transaction","detail":"{read_only:false; response_revision:7358; number_of_response:1; }","duration":"247.955908ms","start":"2026-04-17T00:42:09.048976Z","end":"2026-04-17T00:42:09.296932Z","steps":["trace[1103806327] 'process raft request' (duration: 247.689522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:09.298905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.587712ms","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":"warn","ts":"2026-04-17T00:42:09.297278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.326992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5223"} {"level":"info","ts":"2026-04-17T00:42:09.299027Z","caller":"traceutil/trace.go:171","msg":"trace[156075695] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7358; }","duration":"186.742326ms","start":"2026-04-17T00:42:09.11227Z","end":"2026-04-17T00:42:09.299012Z","steps":["trace[156075695] 'agreement among raft nodes before linearized reading' (duration: 186.515741ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:09.299058Z","caller":"traceutil/trace.go:171","msg":"trace[861062254] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:7358; }","duration":"419.13795ms","start":"2026-04-17T00:42:08.8799Z","end":"2026-04-17T00:42:09.299038Z","steps":["trace[861062254] 'agreement among raft nodes before linearized reading' (duration: 417.228419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:09.299639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:08.879889Z","time spent":"419.733074ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5246,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T00:42:09.631469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.777286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:09.631589Z","caller":"traceutil/trace.go:171","msg":"trace[1537630723] linearizableReadLoop","detail":"{readStateIndex:7703; appliedIndex:7702; }","duration":"201.585074ms","start":"2026-04-17T00:42:09.429983Z","end":"2026-04-17T00:42:09.631568Z","steps":["trace[1537630723] 'read index received' (duration: 28.640074ms)","trace[1537630723] 'applied index is now lower than readState.Index' (duration: 172.94356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:09.631741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.766518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-04-17T00:42:09.631773Z","caller":"traceutil/trace.go:171","msg":"trace[304667846] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:7360; }","duration":"201.836879ms","start":"2026-04-17T00:42:09.429928Z","end":"2026-04-17T00:42:09.631765Z","steps":["trace[304667846] 'agreement among raft nodes before linearized reading' (duration: 201.689186ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:09.631864Z","caller":"traceutil/trace.go:171","msg":"trace[1337619039] transaction","detail":"{read_only:false; response_revision:7360; number_of_response:1; }","duration":"237.76362ms","start":"2026-04-17T00:42:09.394061Z","end":"2026-04-17T00:42:09.631824Z","steps":["trace[1337619039] 'process raft request' (duration: 64.551524ms)","trace[1337619039] 'compare' (duration: 172.649753ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:10.56276Z","caller":"traceutil/trace.go:171","msg":"trace[146442653] linearizableReadLoop","detail":"{readStateIndex:7704; appliedIndex:7703; }","duration":"150.701643ms","start":"2026-04-17T00:42:10.412038Z","end":"2026-04-17T00:42:10.56274Z","steps":["trace[146442653] 'read index received' (duration: 150.524859ms)","trace[146442653] 'applied index is now lower than readState.Index' (duration: 175.744µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:10.562873Z","caller":"traceutil/trace.go:171","msg":"trace[1518667601] transaction","detail":"{read_only:false; response_revision:7361; number_of_response:1; }","duration":"222.622006ms","start":"2026-04-17T00:42:10.340176Z","end":"2026-04-17T00:42:10.562798Z","steps":["trace[1518667601] 'process raft request' (duration: 222.406391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:10.562984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.925637ms","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-04-17T00:42:10.563048Z","caller":"traceutil/trace.go:171","msg":"trace[756660084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7361; }","duration":"151.03548ms","start":"2026-04-17T00:42:10.411999Z","end":"2026-04-17T00:42:10.563034Z","steps":["trace[756660084] 'agreement among raft nodes before linearized reading' (duration: 150.835466ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:11.022859Z","caller":"traceutil/trace.go:171","msg":"trace[1667996879] linearizableReadLoop","detail":"{readStateIndex:7705; appliedIndex:7704; }","duration":"439.682931ms","start":"2026-04-17T00:42:10.583151Z","end":"2026-04-17T00:42:11.022834Z","steps":["trace[1667996879] 'read index received' (duration: 340.904453ms)","trace[1667996879] 'applied index is now lower than readState.Index' (duration: 98.777228ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:11.022931Z","caller":"traceutil/trace.go:171","msg":"trace[1263590267] transaction","detail":"{read_only:false; response_revision:7362; number_of_response:1; }","duration":"455.678104ms","start":"2026-04-17T00:42:10.56722Z","end":"2026-04-17T00:42:11.022898Z","steps":["trace[1263590267] 'process raft request' (duration: 356.903885ms)","trace[1263590267] 'compare' (duration: 98.415871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:11.023039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.133201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:42:11.023043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:10.567195Z","time spent":"455.779445ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:11.023034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.860295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:11.023072Z","caller":"traceutil/trace.go:171","msg":"trace[2105243965] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:7362; }","duration":"427.208453ms","start":"2026-04-17T00:42:10.595854Z","end":"2026-04-17T00:42:11.023063Z","steps":["trace[2105243965] 'agreement among raft nodes before linearized reading' (duration: 427.125161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:11.023106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:10.59584Z","time spent":"427.257624ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-04-17T00:42:11.023106Z","caller":"traceutil/trace.go:171","msg":"trace[1480712648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7362; }","duration":"439.972987ms","start":"2026-04-17T00:42:10.583112Z","end":"2026-04-17T00:42:11.023085Z","steps":["trace[1480712648] 'agreement among raft nodes before linearized reading' (duration: 439.851294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:11.023127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.795127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-17T00:42:11.023154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:10.583094Z","time spent":"440.045729ms","remote":"127.0.0.1:52658","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-04-17T00:42:11.02319Z","caller":"traceutil/trace.go:171","msg":"trace[1944993206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7362; }","duration":"242.899349ms","start":"2026-04-17T00:42:10.780274Z","end":"2026-04-17T00:42:11.023174Z","steps":["trace[1944993206] 'agreement among raft nodes before linearized reading' (duration: 242.731086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:11.023394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.645044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-17T00:42:11.023383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.131574ms","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-04-17T00:42:11.023426Z","caller":"traceutil/trace.go:171","msg":"trace[405136990] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7362; }","duration":"242.693125ms","start":"2026-04-17T00:42:10.780723Z","end":"2026-04-17T00:42:11.023416Z","steps":["trace[405136990] 'agreement among raft nodes before linearized reading' (duration: 242.595712ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:11.023451Z","caller":"traceutil/trace.go:171","msg":"trace[1738311504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7362; }","duration":"243.240906ms","start":"2026-04-17T00:42:10.780193Z","end":"2026-04-17T00:42:11.023434Z","steps":["trace[1738311504] 'agreement among raft nodes before linearized reading' (duration: 243.033092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:11.023472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.201976ms","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-04-17T00:42:11.023505Z","caller":"traceutil/trace.go:171","msg":"trace[681998101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7362; }","duration":"243.248336ms","start":"2026-04-17T00:42:10.780247Z","end":"2026-04-17T00:42:11.023496Z","steps":["trace[681998101] 'agreement among raft nodes before linearized reading' (duration: 243.119143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:11.31139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.694959ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:11.312367Z","caller":"traceutil/trace.go:171","msg":"trace[319684848] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7363; }","duration":"121.685681ms","start":"2026-04-17T00:42:11.190662Z","end":"2026-04-17T00:42:11.312348Z","steps":["trace[319684848] 'range keys from in-memory index tree' (duration: 120.679088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:11.311494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.942556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:11.312801Z","caller":"traceutil/trace.go:171","msg":"trace[31880012] transaction","detail":"{read_only:false; response_revision:7366; number_of_response:1; }","duration":"284.514453ms","start":"2026-04-17T00:42:11.028278Z","end":"2026-04-17T00:42:11.312792Z","steps":["trace[31880012] 'process raft request' (duration: 284.479963ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:11.313016Z","caller":"traceutil/trace.go:171","msg":"trace[949263542] transaction","detail":"{read_only:false; response_revision:7364; number_of_response:1; }","duration":"285.597556ms","start":"2026-04-17T00:42:11.027401Z","end":"2026-04-17T00:42:11.312999Z","steps":["trace[949263542] 'process raft request' (duration: 113.082595ms)","trace[949263542] 'compare' (duration: 170.852925ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:11.313357Z","caller":"traceutil/trace.go:171","msg":"trace[1612226358] transaction","detail":"{read_only:false; response_revision:7365; number_of_response:1; }","duration":"285.557915ms","start":"2026-04-17T00:42:11.027789Z","end":"2026-04-17T00:42:11.313347Z","steps":["trace[1612226358] 'process raft request' (duration: 284.870651ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:11.320803Z","caller":"traceutil/trace.go:171","msg":"trace[1636828998] transaction","detail":"{read_only:false; response_revision:7367; number_of_response:1; }","duration":"188.001423ms","start":"2026-04-17T00:42:11.132786Z","end":"2026-04-17T00:42:11.320788Z","steps":["trace[1636828998] 'process raft request' (duration: 187.90103ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:11.320923Z","caller":"traceutil/trace.go:171","msg":"trace[1696890347] transaction","detail":"{read_only:false; response_revision:7368; number_of_response:1; }","duration":"182.505496ms","start":"2026-04-17T00:42:11.138403Z","end":"2026-04-17T00:42:11.320908Z","steps":["trace[1696890347] 'process raft request' (duration: 182.359283ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:11.960185Z","caller":"traceutil/trace.go:171","msg":"trace[2124634074] transaction","detail":"{read_only:false; response_revision:7372; number_of_response:1; }","duration":"486.70867ms","start":"2026-04-17T00:42:11.473453Z","end":"2026-04-17T00:42:11.960162Z","steps":["trace[2124634074] 'process raft request' (duration: 393.289005ms)","trace[2124634074] 'compare' (duration: 93.339923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:11.960397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:11.473438Z","time spent":"486.796482ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:12.02095Z","caller":"traceutil/trace.go:171","msg":"trace[940808679] linearizableReadLoop","detail":"{readStateIndex:7716; appliedIndex:7714; }","duration":"241.029729ms","start":"2026-04-17T00:42:11.779898Z","end":"2026-04-17T00:42:12.020928Z","steps":["trace[940808679] 'read index received' (duration: 86.995285ms)","trace[940808679] 'applied index is now lower than readState.Index' (duration: 154.033524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:12.021157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.233185ms","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-04-17T00:42:12.021261Z","caller":"traceutil/trace.go:171","msg":"trace[1001613352] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7373; }","duration":"241.383828ms","start":"2026-04-17T00:42:11.77986Z","end":"2026-04-17T00:42:12.021244Z","steps":["trace[1001613352] 'agreement among raft nodes before linearized reading' (duration: 241.189724ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:12.021185Z","caller":"traceutil/trace.go:171","msg":"trace[2049882861] transaction","detail":"{read_only:false; response_revision:7373; number_of_response:1; }","duration":"542.932895ms","start":"2026-04-17T00:42:11.478051Z","end":"2026-04-17T00:42:12.020984Z","steps":["trace[2049882861] 'process raft request' (duration: 542.753851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:12.021518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:11.478037Z","time spent":"543.381455ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:13.036619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.848923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:13.036702Z","caller":"traceutil/trace.go:171","msg":"trace[2102548638] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:7374; }","duration":"300.980785ms","start":"2026-04-17T00:42:12.735701Z","end":"2026-04-17T00:42:13.036682Z","steps":["trace[2102548638] 'count revisions from in-memory index tree' (duration: 300.782421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:13.036748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:12.735683Z","time spent":"301.053437ms","remote":"127.0.0.1:35002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:13.036926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.756784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:13.037003Z","caller":"traceutil/trace.go:171","msg":"trace[1356316844] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:7374; }","duration":"247.988838ms","start":"2026-04-17T00:42:12.788994Z","end":"2026-04-17T00:42:13.036982Z","steps":["trace[1356316844] 'count revisions from in-memory index tree' (duration: 247.695183ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:13.422038Z","caller":"traceutil/trace.go:171","msg":"trace[319360772] linearizableReadLoop","detail":"{readStateIndex:7718; appliedIndex:7717; }","duration":"329.12964ms","start":"2026-04-17T00:42:13.092883Z","end":"2026-04-17T00:42:13.422013Z","steps":["trace[319360772] 'read index received' (duration: 328.986027ms)","trace[319360772] 'applied index is now lower than readState.Index' (duration: 142.183µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:13.422077Z","caller":"traceutil/trace.go:171","msg":"trace[1748178711] transaction","detail":"{read_only:false; response_revision:7375; number_of_response:1; }","duration":"381.120925ms","start":"2026-04-17T00:42:13.040924Z","end":"2026-04-17T00:42:13.422045Z","steps":["trace[1748178711] 'process raft request' (duration: 380.92536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:13.422197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.040911Z","time spent":"381.222586ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:13.422284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.455396ms","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-04-17T00:42:13.422346Z","caller":"traceutil/trace.go:171","msg":"trace[1480677223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7375; }","duration":"329.552518ms","start":"2026-04-17T00:42:13.092775Z","end":"2026-04-17T00:42:13.422328Z","steps":["trace[1480677223] 'agreement among raft nodes before linearized reading' (duration: 329.360364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:13.422412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.092758Z","time spent":"329.64561ms","remote":"127.0.0.1:52880","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-04-17T00:42:13.92234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828033232245352,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:42:14.000467Z","caller":"traceutil/trace.go:171","msg":"trace[519231819] transaction","detail":"{read_only:false; response_revision:7376; number_of_response:1; }","duration":"628.650193ms","start":"2026-04-17T00:42:13.371796Z","end":"2026-04-17T00:42:14.000447Z","steps":["trace[519231819] 'process raft request' (duration: 550.927676ms)","trace[519231819] 'compare' (duration: 77.418301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:14.000575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.371775Z","time spent":"628.744556ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:14.75734Z","caller":"traceutil/trace.go:171","msg":"trace[1570977096] linearizableReadLoop","detail":"{readStateIndex:7720; appliedIndex:7718; }","duration":"1.335207637s","start":"2026-04-17T00:42:13.422114Z","end":"2026-04-17T00:42:14.757322Z","steps":["trace[1570977096] 'read index received' (duration: 500.969324ms)","trace[1570977096] 'applied index is now lower than readState.Index' (duration: 834.236383ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:14.757491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439994236s","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":"warn","ts":"2026-04-17T00:42:14.757492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173164742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:14.757512Z","caller":"traceutil/trace.go:171","msg":"trace[772312820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7377; }","duration":"1.440040596s","start":"2026-04-17T00:42:13.317467Z","end":"2026-04-17T00:42:14.757507Z","steps":["trace[772312820] 'agreement among raft nodes before linearized reading' (duration: 1.439937924s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:14.757525Z","caller":"traceutil/trace.go:171","msg":"trace[6639109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7377; }","duration":"1.173238302s","start":"2026-04-17T00:42:13.584277Z","end":"2026-04-17T00:42:14.757515Z","steps":["trace[6639109] 'agreement among raft nodes before linearized reading' (duration: 1.173177771s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.757554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.584259Z","time spent":"1.173290324s","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T00:42:14.757576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.969858ms","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-04-17T00:42:14.757594Z","caller":"traceutil/trace.go:171","msg":"trace[2138927913] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7377; }","duration":"731.022609ms","start":"2026-04-17T00:42:14.026566Z","end":"2026-04-17T00:42:14.757588Z","steps":["trace[2138927913] 'agreement among raft nodes before linearized reading' (duration: 730.979968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.757608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.026545Z","time spent":"731.05936ms","remote":"127.0.0.1:52742","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":"warn","ts":"2026-04-17T00:42:14.757581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438707827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-17T00:42:14.757647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.699973ms","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":"info","ts":"2026-04-17T00:42:14.757653Z","caller":"traceutil/trace.go:171","msg":"trace[1941311705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7377; }","duration":"1.43882291s","start":"2026-04-17T00:42:13.318811Z","end":"2026-04-17T00:42:14.757634Z","steps":["trace[1941311705] 'agreement among raft nodes before linearized reading' (duration: 1.438554024s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.757674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.764888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"warn","ts":"2026-04-17T00:42:14.757713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.318802Z","time spent":"1.438902671s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T00:42:14.757734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.783812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:14.757749Z","caller":"traceutil/trace.go:171","msg":"trace[1672831984] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:7377; }","duration":"508.842313ms","start":"2026-04-17T00:42:14.248903Z","end":"2026-04-17T00:42:14.757745Z","steps":["trace[1672831984] 'agreement among raft nodes before linearized reading' (duration: 508.796992ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:14.757704Z","caller":"traceutil/trace.go:171","msg":"trace[1136465069] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7377; }","duration":"793.798895ms","start":"2026-04-17T00:42:13.963892Z","end":"2026-04-17T00:42:14.757691Z","steps":["trace[1136465069] 'agreement among raft nodes before linearized reading' (duration: 793.672912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.75753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.317462Z","time spent":"1.440063967s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T00:42:14.757753Z","caller":"traceutil/trace.go:171","msg":"trace[27999375] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7377; }","duration":"450.896551ms","start":"2026-04-17T00:42:14.306838Z","end":"2026-04-17T00:42:14.757735Z","steps":["trace[27999375] 'agreement among raft nodes before linearized reading' (duration: 450.656556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.757799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.306814Z","time spent":"450.975702ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11519,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-17T00:42:14.757811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.963871Z","time spent":"793.903747ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-17T00:42:14.757888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.989239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T00:42:14.757903Z","caller":"traceutil/trace.go:171","msg":"trace[441137360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7377; }","duration":"138.038441ms","start":"2026-04-17T00:42:14.619861Z","end":"2026-04-17T00:42:14.757899Z","steps":["trace[441137360] 'agreement among raft nodes before linearized reading' (duration: 137.99626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.757768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.248881Z","time spent":"508.882044ms","remote":"127.0.0.1:52798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":40,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:42:14.757961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.508167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:14.757992Z","caller":"traceutil/trace.go:171","msg":"trace[2134182873] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:7377; }","duration":"205.59838ms","start":"2026-04-17T00:42:14.552386Z","end":"2026-04-17T00:42:14.757984Z","steps":["trace[2134182873] 'agreement among raft nodes before linearized reading' (duration: 205.528278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.758036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440576929s","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-04-17T00:42:14.758088Z","caller":"traceutil/trace.go:171","msg":"trace[1636871536] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7377; }","duration":"1.4406673s","start":"2026-04-17T00:42:13.317408Z","end":"2026-04-17T00:42:14.758075Z","steps":["trace[1636871536] 'agreement among raft nodes before linearized reading' (duration: 1.440524656s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.758131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.317385Z","time spent":"1.440733692s","remote":"127.0.0.1:52880","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":"info","ts":"2026-04-17T00:42:14.758304Z","caller":"traceutil/trace.go:171","msg":"trace[1040651234] transaction","detail":"{read_only:false; response_revision:7377; number_of_response:1; }","duration":"1.331825166s","start":"2026-04-17T00:42:13.426462Z","end":"2026-04-17T00:42:14.758287Z","steps":["trace[1040651234] 'process raft request' (duration: 1.330757942s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:14.758462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:13.426436Z","time spent":"1.331966809s","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:15.090935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.38742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:42:15.091017Z","caller":"traceutil/trace.go:171","msg":"trace[681774657] transaction","detail":"{read_only:false; response_revision:7378; number_of_response:1; }","duration":"327.230608ms","start":"2026-04-17T00:42:14.763756Z","end":"2026-04-17T00:42:15.090987Z","steps":["trace[681774657] 'process raft request' (duration: 138.735065ms)","trace[681774657] 'compare' (duration: 188.318289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:15.091048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.763728Z","time spent":"327.306079ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:15.124603Z","caller":"traceutil/trace.go:171","msg":"trace[1630324092] transaction","detail":"{read_only:false; response_revision:7379; number_of_response:1; }","duration":"360.630424ms","start":"2026-04-17T00:42:14.763939Z","end":"2026-04-17T00:42:15.12457Z","steps":["trace[1630324092] 'process raft request' (duration: 360.40935ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:15.124652Z","caller":"traceutil/trace.go:171","msg":"trace[2122913597] linearizableReadLoop","detail":"{readStateIndex:7725; appliedIndex:7720; }","duration":"359.96176ms","start":"2026-04-17T00:42:14.764656Z","end":"2026-04-17T00:42:15.124618Z","steps":["trace[2122913597] 'read index received' (duration: 137.922698ms)","trace[2122913597] 'applied index is now lower than readState.Index' (duration: 222.038112ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:15.124682Z","caller":"traceutil/trace.go:171","msg":"trace[1367969891] transaction","detail":"{read_only:false; response_revision:7380; number_of_response:1; }","duration":"360.696655ms","start":"2026-04-17T00:42:14.763976Z","end":"2026-04-17T00:42:15.124673Z","steps":["trace[1367969891] 'process raft request' (duration: 360.500081ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:15.124674Z","caller":"traceutil/trace.go:171","msg":"trace[1133835599] transaction","detail":"{read_only:false; response_revision:7382; number_of_response:1; }","duration":"360.037162ms","start":"2026-04-17T00:42:14.764605Z","end":"2026-04-17T00:42:15.124642Z","steps":["trace[1133835599] 'process raft request' (duration: 359.93415ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:15.124755Z","caller":"traceutil/trace.go:171","msg":"trace[571369818] transaction","detail":"{read_only:false; response_revision:7383; number_of_response:1; }","duration":"357.246582ms","start":"2026-04-17T00:42:14.767495Z","end":"2026-04-17T00:42:15.124742Z","steps":["trace[571369818] 'process raft request' (duration: 357.087529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:15.124777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.763966Z","time spent":"360.754236ms","remote":"127.0.0.1:52880","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-04-17T00:42:15.124817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.764597Z","time spent":"360.140524ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:15.124862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.767481Z","time spent":"357.308663ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:15.124784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.763923Z","time spent":"360.752997ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:42:15.124631Z","caller":"traceutil/trace.go:171","msg":"trace[1790667698] transaction","detail":"{read_only:false; response_revision:7381; number_of_response:1; }","duration":"360.591153ms","start":"2026-04-17T00:42:14.764017Z","end":"2026-04-17T00:42:15.124609Z","steps":["trace[1790667698] 'process raft request' (duration: 360.494281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:15.125087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.764007Z","time spent":"361.052545ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:15.125431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.770408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12353"} {"level":"info","ts":"2026-04-17T00:42:15.125482Z","caller":"traceutil/trace.go:171","msg":"trace[1586044449] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:7383; }","duration":"360.838209ms","start":"2026-04-17T00:42:14.764631Z","end":"2026-04-17T00:42:15.125469Z","steps":["trace[1586044449] 'agreement among raft nodes before linearized reading' (duration: 360.697176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:15.125512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.764621Z","time spent":"360.884681ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":12376,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-17T00:42:15.125561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.449116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:15.125614Z","caller":"traceutil/trace.go:171","msg":"trace[990440725] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:7383; }","duration":"170.543638ms","start":"2026-04-17T00:42:14.955057Z","end":"2026-04-17T00:42:15.125601Z","steps":["trace[990440725] 'agreement among raft nodes before linearized reading' (duration: 170.474687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:15.125009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.779024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:15.128944Z","caller":"traceutil/trace.go:171","msg":"trace[432196966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7383; }","duration":"361.695347ms","start":"2026-04-17T00:42:14.767193Z","end":"2026-04-17T00:42:15.128889Z","steps":["trace[432196966] 'agreement among raft nodes before linearized reading' (duration: 357.66268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:15.129054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:14.767186Z","time spent":"361.842981ms","remote":"127.0.0.1:52658","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-04-17T00:42:15.327155Z","caller":"traceutil/trace.go:171","msg":"trace[383494512] transaction","detail":"{read_only:false; response_revision:7386; number_of_response:1; }","duration":"157.56993ms","start":"2026-04-17T00:42:15.169551Z","end":"2026-04-17T00:42:15.327121Z","steps":["trace[383494512] 'process raft request' (duration: 157.345035ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:15.47933Z","caller":"traceutil/trace.go:171","msg":"trace[520330637] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7386; }","duration":"138.240244ms","start":"2026-04-17T00:42:15.34105Z","end":"2026-04-17T00:42:15.479291Z","steps":["trace[520330637] 'process raft request' (duration: 138.121192ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:16.225479Z","caller":"traceutil/trace.go:171","msg":"trace[449042059] transaction","detail":"{read_only:false; response_revision:7390; number_of_response:1; }","duration":"107.226409ms","start":"2026-04-17T00:42:16.118227Z","end":"2026-04-17T00:42:16.225453Z","steps":["trace[449042059] 'process raft request' (duration: 79.729851ms)","trace[449042059] 'compare' (duration: 27.380506ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:16.256924Z","caller":"traceutil/trace.go:171","msg":"trace[1933869596] transaction","detail":"{read_only:false; response_revision:7391; number_of_response:1; }","duration":"137.224633ms","start":"2026-04-17T00:42:16.119676Z","end":"2026-04-17T00:42:16.256901Z","steps":["trace[1933869596] 'process raft request' (duration: 136.840654ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:27.48856Z","caller":"traceutil/trace.go:171","msg":"trace[1068411696] linearizableReadLoop","detail":"{readStateIndex:7811; appliedIndex:7810; }","duration":"231.193519ms","start":"2026-04-17T00:42:27.257341Z","end":"2026-04-17T00:42:27.488534Z","steps":["trace[1068411696] 'read index received' (duration: 164.468508ms)","trace[1068411696] 'applied index is now lower than readState.Index' (duration: 66.723891ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:27.488599Z","caller":"traceutil/trace.go:171","msg":"trace[79181342] transaction","detail":"{read_only:false; response_revision:7462; number_of_response:1; }","duration":"256.072291ms","start":"2026-04-17T00:42:27.23249Z","end":"2026-04-17T00:42:27.488562Z","steps":["trace[79181342] 'process raft request' (duration: 189.292229ms)","trace[79181342] 'compare' (duration: 66.468456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:27.488797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.432144ms","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":"warn","ts":"2026-04-17T00:42:27.488859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.875793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T00:42:27.488871Z","caller":"traceutil/trace.go:171","msg":"trace[1924832757] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7462; }","duration":"231.548555ms","start":"2026-04-17T00:42:27.257301Z","end":"2026-04-17T00:42:27.488849Z","steps":["trace[1924832757] 'agreement among raft nodes before linearized reading' (duration: 231.352831ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:27.488901Z","caller":"traceutil/trace.go:171","msg":"trace[577970241] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:7462; }","duration":"135.970666ms","start":"2026-04-17T00:42:27.352918Z","end":"2026-04-17T00:42:27.488888Z","steps":["trace[577970241] 'agreement among raft nodes before linearized reading' (duration: 135.780822ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:27.743537Z","caller":"traceutil/trace.go:171","msg":"trace[195597627] transaction","detail":"{read_only:false; response_revision:7463; number_of_response:1; }","duration":"163.697451ms","start":"2026-04-17T00:42:27.579806Z","end":"2026-04-17T00:42:27.743504Z","steps":["trace[195597627] 'process raft request' (duration: 128.667059ms)","trace[195597627] 'compare' (duration: 34.703235ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:27.774279Z","caller":"traceutil/trace.go:171","msg":"trace[193052197] transaction","detail":"{read_only:false; response_revision:7465; number_of_response:1; }","duration":"190.73626ms","start":"2026-04-17T00:42:27.583512Z","end":"2026-04-17T00:42:27.774248Z","steps":["trace[193052197] 'process raft request' (duration: 190.447664ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:27.774409Z","caller":"traceutil/trace.go:171","msg":"trace[1596295162] transaction","detail":"{read_only:false; response_revision:7464; number_of_response:1; }","duration":"191.553927ms","start":"2026-04-17T00:42:27.582832Z","end":"2026-04-17T00:42:27.774386Z","steps":["trace[1596295162] 'process raft request' (duration: 190.991095ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:29.233051Z","caller":"traceutil/trace.go:171","msg":"trace[31523316] linearizableReadLoop","detail":"{readStateIndex:7818; appliedIndex:7817; }","duration":"141.28077ms","start":"2026-04-17T00:42:29.091751Z","end":"2026-04-17T00:42:29.233031Z","steps":["trace[31523316] 'read index received' (duration: 141.124327ms)","trace[31523316] 'applied index is now lower than readState.Index' (duration: 155.373µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:29.233129Z","caller":"traceutil/trace.go:171","msg":"trace[923620147] transaction","detail":"{read_only:false; response_revision:7468; number_of_response:1; }","duration":"378.992357ms","start":"2026-04-17T00:42:28.854089Z","end":"2026-04-17T00:42:29.233082Z","steps":["trace[923620147] 'process raft request' (duration: 378.792733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:29.233447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:28.854071Z","time spent":"379.285504ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:29.233479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.439663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:29.233539Z","caller":"traceutil/trace.go:171","msg":"trace[1633203466] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:7468; }","duration":"141.8009ms","start":"2026-04-17T00:42:29.091721Z","end":"2026-04-17T00:42:29.233522Z","steps":["trace[1633203466] 'agreement among raft nodes before linearized reading' (duration: 141.425852ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:36.32496Z","caller":"traceutil/trace.go:171","msg":"trace[696194683] linearizableReadLoop","detail":"{readStateIndex:7872; appliedIndex:7871; }","duration":"128.152836ms","start":"2026-04-17T00:42:36.196785Z","end":"2026-04-17T00:42:36.324938Z","steps":["trace[696194683] 'read index received' (duration: 91.675393ms)","trace[696194683] 'applied index is now lower than readState.Index' (duration: 36.476262ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:36.324996Z","caller":"traceutil/trace.go:171","msg":"trace[970828248] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7519; }","duration":"174.38831ms","start":"2026-04-17T00:42:36.150579Z","end":"2026-04-17T00:42:36.324967Z","steps":["trace[970828248] 'process raft request' (duration: 137.935238ms)","trace[970828248] 'compare' (duration: 36.381941ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:36.325095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.294968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:42:36.325141Z","caller":"traceutil/trace.go:171","msg":"trace[141298846] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:7519; }","duration":"128.393371ms","start":"2026-04-17T00:42:36.196731Z","end":"2026-04-17T00:42:36.325124Z","steps":["trace[141298846] 'agreement among raft nodes before linearized reading' (duration: 128.293268ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:37.50841Z","caller":"traceutil/trace.go:171","msg":"trace[1063409843] transaction","detail":"{read_only:false; response_revision:7523; number_of_response:1; }","duration":"137.607621ms","start":"2026-04-17T00:42:37.370778Z","end":"2026-04-17T00:42:37.508386Z","steps":["trace[1063409843] 'process raft request' (duration: 136.460624ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:37.508589Z","caller":"traceutil/trace.go:171","msg":"trace[113234261] transaction","detail":"{read_only:false; response_revision:7524; number_of_response:1; }","duration":"137.681163ms","start":"2026-04-17T00:42:37.370885Z","end":"2026-04-17T00:42:37.508566Z","steps":["trace[113234261] 'process raft request' (duration: 137.404946ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:37.508804Z","caller":"traceutil/trace.go:171","msg":"trace[820121005] transaction","detail":"{read_only:false; response_revision:7525; number_of_response:1; }","duration":"137.848456ms","start":"2026-04-17T00:42:37.370942Z","end":"2026-04-17T00:42:37.50879Z","steps":["trace[820121005] 'process raft request' (duration: 137.566829ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:37.50901Z","caller":"traceutil/trace.go:171","msg":"trace[1873696142] linearizableReadLoop","detail":"{readStateIndex:7880; appliedIndex:7876; }","duration":"114.38351ms","start":"2026-04-17T00:42:37.394615Z","end":"2026-04-17T00:42:37.508998Z","steps":["trace[1873696142] 'read index received' (duration: 112.594129ms)","trace[1873696142] 'applied index is now lower than readState.Index' (duration: 1.788711ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:37.509128Z","caller":"traceutil/trace.go:171","msg":"trace[599397831] transaction","detail":"{read_only:false; response_revision:7526; number_of_response:1; }","duration":"115.957506ms","start":"2026-04-17T00:42:37.39316Z","end":"2026-04-17T00:42:37.509117Z","steps":["trace[599397831] 'process raft request' (duration: 115.551127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:37.509146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.513453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:37.509179Z","caller":"traceutil/trace.go:171","msg":"trace[1840002483] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:7526; }","duration":"114.579195ms","start":"2026-04-17T00:42:37.39459Z","end":"2026-04-17T00:42:37.50917Z","steps":["trace[1840002483] 'agreement among raft nodes before linearized reading' (duration: 114.462922ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:38.062851Z","caller":"traceutil/trace.go:171","msg":"trace[516248909] linearizableReadLoop","detail":"{readStateIndex:7884; appliedIndex:7883; }","duration":"128.608085ms","start":"2026-04-17T00:42:37.934216Z","end":"2026-04-17T00:42:38.062824Z","steps":["trace[516248909] 'read index received' (duration: 107.37013ms)","trace[516248909] 'applied index is now lower than readState.Index' (duration: 21.236705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:38.062998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.759829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:42:38.063032Z","caller":"traceutil/trace.go:171","msg":"trace[524187137] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:7530; }","duration":"128.851181ms","start":"2026-04-17T00:42:37.934172Z","end":"2026-04-17T00:42:38.063023Z","steps":["trace[524187137] 'agreement among raft nodes before linearized reading' (duration: 128.741538ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:38.06302Z","caller":"traceutil/trace.go:171","msg":"trace[1174684737] transaction","detail":"{read_only:false; response_revision:7530; number_of_response:1; }","duration":"140.537078ms","start":"2026-04-17T00:42:37.922447Z","end":"2026-04-17T00:42:38.062984Z","steps":["trace[1174684737] 'process raft request' (duration: 119.239832ms)","trace[1174684737] 'compare' (duration: 20.899107ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:39.432912Z","caller":"traceutil/trace.go:171","msg":"trace[488246767] transaction","detail":"{read_only:false; response_revision:7533; number_of_response:1; }","duration":"148.571099ms","start":"2026-04-17T00:42:39.284292Z","end":"2026-04-17T00:42:39.432863Z","steps":["trace[488246767] 'process raft request' (duration: 148.368944ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:39.687705Z","caller":"traceutil/trace.go:171","msg":"trace[1551084866] linearizableReadLoop","detail":"{readStateIndex:7890; appliedIndex:7888; }","duration":"170.588562ms","start":"2026-04-17T00:42:39.517095Z","end":"2026-04-17T00:42:39.687683Z","steps":["trace[1551084866] 'read index received' (duration: 23.371013ms)","trace[1551084866] 'applied index is now lower than readState.Index' (duration: 147.216549ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:39.687724Z","caller":"traceutil/trace.go:171","msg":"trace[1025751909] transaction","detail":"{read_only:false; response_revision:7536; number_of_response:1; }","duration":"171.274678ms","start":"2026-04-17T00:42:39.516419Z","end":"2026-04-17T00:42:39.687693Z","steps":["trace[1025751909] 'process raft request' (duration: 136.006513ms)","trace[1025751909] 'compare' (duration: 35.098642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:42:39.687894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.331401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:42:39.687956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.838548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T00:42:39.688034Z","caller":"traceutil/trace.go:171","msg":"trace[1389438509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7537; }","duration":"170.963361ms","start":"2026-04-17T00:42:39.517054Z","end":"2026-04-17T00:42:39.688017Z","steps":["trace[1389438509] 'agreement among raft nodes before linearized reading' (duration: 170.706085ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:39.688873Z","caller":"traceutil/trace.go:171","msg":"trace[499184834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7537; }","duration":"104.426003ms","start":"2026-04-17T00:42:39.583498Z","end":"2026-04-17T00:42:39.687924Z","steps":["trace[499184834] 'agreement among raft nodes before linearized reading' (duration: 104.3269ms)"],"step_count":1}