{"level":"warn","ts":"2026-10-16T09:14:47.605787Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T09:14:47.606049Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.208:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.208:2380","--initial-cluster=instance=https://162.253.55.208:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.208:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.208:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-16T09:14:47.606174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T09:14:47.606197Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.208:2380"]} {"level":"info","ts":"2026-10-16T09:14:47.606228Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T09:14:47.607399Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.208:2379"]} {"level":"info","ts":"2026-10-16T09:14:47.607571Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.208:2380"],"listen-peer-urls":["https://162.253.55.208:2380"],"advertise-client-urls":["https://162.253.55.208:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.208:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.208:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-16T09:14:47.613624Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.624734ms"} {"level":"info","ts":"2026-10-16T09:14:47.631027Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5019b1666ea94ec4","cluster-id":"5aff54f2fc6d3e3d"} {"level":"info","ts":"2026-10-16T09:14:47.631188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T09:14:47.631221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became follower at term 0"} {"level":"info","ts":"2026-10-16T09:14:47.631237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5019b1666ea94ec4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T09:14:47.63125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became follower at term 1"} {"level":"info","ts":"2026-10-16T09:14:47.631319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 switched to configuration voters=(5771839450953371332)"} {"level":"warn","ts":"2026-10-16T09:14:47.648524Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T09:14:47.651553Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T09:14:47.653148Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-16T09:14:47.655771Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5019b1666ea94ec4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T09:14:47.655993Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5019b1666ea94ec4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T09:14:47.658816Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T09:14:47.660024Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T09:14:47.66004Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T09:14:47.660451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 switched to configuration voters=(5771839450953371332)"} {"level":"info","ts":"2026-10-16T09:14:47.660835Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5aff54f2fc6d3e3d","local-member-id":"5019b1666ea94ec4","added-peer-id":"5019b1666ea94ec4","added-peer-peer-urls":["https://162.253.55.208:2380"]} {"level":"info","ts":"2026-10-16T09:14:47.660891Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T09:14:47.66132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.208:2380"} {"level":"info","ts":"2026-10-16T09:14:47.661443Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.208:2380"} {"level":"info","ts":"2026-10-16T09:14:47.661482Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5019b1666ea94ec4","initial-advertise-peer-urls":["https://162.253.55.208:2380"],"listen-peer-urls":["https://162.253.55.208:2380"],"advertise-client-urls":["https://162.253.55.208:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.208:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T09:14:47.66154Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T09:14:47.832311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T09:14:47.83238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T09:14:47.832399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 received MsgPreVoteResp from 5019b1666ea94ec4 at term 1"} {"level":"info","ts":"2026-10-16T09:14:47.832413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became candidate at term 2"} {"level":"info","ts":"2026-10-16T09:14:47.83242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 received MsgVoteResp from 5019b1666ea94ec4 at term 2"} {"level":"info","ts":"2026-10-16T09:14:47.832431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became leader at term 2"} {"level":"info","ts":"2026-10-16T09:14:47.832441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5019b1666ea94ec4 elected leader 5019b1666ea94ec4 at term 2"} {"level":"info","ts":"2026-10-16T09:14:47.833601Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T09:14:47.834567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T09:14:47.834564Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5019b1666ea94ec4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.208:2379]}","request-path":"/0/members/5019b1666ea94ec4/attributes","cluster-id":"5aff54f2fc6d3e3d","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T09:14:47.834637Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T09:14:47.834874Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T09:14:47.834955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T09:14:47.835023Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5aff54f2fc6d3e3d","local-member-id":"5019b1666ea94ec4","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T09:14:47.835111Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T09:14:47.835143Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T09:14:47.836955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T09:14:47.838776Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.208:2379"} {"level":"info","ts":"2026-10-16T09:15:39.881757Z","caller":"traceutil/trace.go:171","msg":"trace[635401100] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"129.960785ms","start":"2026-10-16T09:15:39.751766Z","end":"2026-10-16T09:15:39.881726Z","steps":["trace[635401100] 'process raft request' (duration: 129.708268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:15:40.265762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.693103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T09:15:40.266049Z","caller":"traceutil/trace.go:171","msg":"trace[682609933] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"451.865257ms","start":"2026-10-16T09:15:39.814161Z","end":"2026-10-16T09:15:40.266026Z","steps":["trace[682609933] 'process raft request' (duration: 335.105095ms)","trace[682609933] 'compare' (duration: 115.20029ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:15:40.266147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:15:39.814125Z","time spent":"451.97203ms","remote":"127.0.0.1:59366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:15:40.497686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.313382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-16T09:15:40.497882Z","caller":"traceutil/trace.go:171","msg":"trace[1997592911] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"119.108998ms","start":"2026-10-16T09:15:40.378757Z","end":"2026-10-16T09:15:40.497866Z","steps":["trace[1997592911] 'read index received' (duration: 10.505997ms)","trace[1997592911] 'applied index is now lower than readState.Index' (duration: 108.58692ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:15:40.497969Z","caller":"traceutil/trace.go:171","msg":"trace[540352288] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"146.64235ms","start":"2026-10-16T09:15:40.351319Z","end":"2026-10-16T09:15:40.497962Z","steps":["trace[540352288] 'process raft request' (duration: 37.965568ms)","trace[540352288] 'compare' (duration: 108.012824ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:15:40.498401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.632162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:15:40.498455Z","caller":"traceutil/trace.go:171","msg":"trace[1032859765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"119.732435ms","start":"2026-10-16T09:15:40.378713Z","end":"2026-10-16T09:15:40.498446Z","steps":["trace[1032859765] 'agreement among raft nodes before linearized reading' (duration: 119.609271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:15:41.009564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5675838743791391854,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-16T09:15:41.374637Z","caller":"traceutil/trace.go:171","msg":"trace[131879485] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"468.725189ms","start":"2026-10-16T09:15:40.905872Z","end":"2026-10-16T09:15:41.374597Z","steps":["trace[131879485] 'process raft request' (duration: 468.422521ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:15:41.374562Z","caller":"traceutil/trace.go:171","msg":"trace[1880630917] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:678; }","duration":"865.920263ms","start":"2026-10-16T09:15:40.508595Z","end":"2026-10-16T09:15:41.374516Z","steps":["trace[1880630917] 'read index received' (duration: 805.954953ms)","trace[1880630917] 'applied index is now lower than readState.Index' (duration: 59.934829ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:15:41.374904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:15:40.905816Z","time spent":"468.923455ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:15:41.374987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.420414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:15:41.375051Z","caller":"traceutil/trace.go:171","msg":"trace[595786725] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:653; }","duration":"863.532828ms","start":"2026-10-16T09:15:40.511497Z","end":"2026-10-16T09:15:41.375029Z","steps":["trace[595786725] 'agreement among raft nodes before linearized reading' (duration: 863.417215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:15:41.375091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:15:40.511484Z","time spent":"863.59701ms","remote":"127.0.0.1:59518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-10-16T09:15:41.375329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.062625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-16T09:15:41.375474Z","caller":"traceutil/trace.go:171","msg":"trace[1157722301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"542.265261ms","start":"2026-10-16T09:15:40.833187Z","end":"2026-10-16T09:15:41.375452Z","steps":["trace[1157722301] 'agreement among raft nodes before linearized reading' (duration: 541.814878ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:15:41.37466Z","caller":"traceutil/trace.go:171","msg":"trace[2001925122] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"871.31932ms","start":"2026-10-16T09:15:40.503302Z","end":"2026-10-16T09:15:41.374621Z","steps":["trace[2001925122] 'process raft request' (duration: 811.189925ms)","trace[2001925122] 'compare' (duration: 59.250281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:15:41.375576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:15:40.833164Z","time spent":"542.399655ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-16T09:15:41.375632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:15:40.503238Z","time spent":"872.333088ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:15:41.375727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.54819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-16T09:15:41.375956Z","caller":"traceutil/trace.go:171","msg":"trace[1461766696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"867.328881ms","start":"2026-10-16T09:15:40.508516Z","end":"2026-10-16T09:15:41.375845Z","steps":["trace[1461766696] 'agreement among raft nodes before linearized reading' (duration: 866.200411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:15:41.376274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:15:40.508485Z","time spent":"867.745343ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T09:15:57.382109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.555195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:15:57.382197Z","caller":"traceutil/trace.go:171","msg":"trace[267315095] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:819; }","duration":"219.687618ms","start":"2026-10-16T09:15:57.162489Z","end":"2026-10-16T09:15:57.382177Z","steps":["trace[267315095] 'count revisions from in-memory index tree' (duration: 219.40028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:15:57.382237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.623037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:15:57.382332Z","caller":"traceutil/trace.go:171","msg":"trace[20277001] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:819; }","duration":"219.781611ms","start":"2026-10-16T09:15:57.162532Z","end":"2026-10-16T09:15:57.382314Z","steps":["trace[20277001] 'range keys from in-memory index tree' (duration: 219.529474ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:15:57.423753Z","caller":"traceutil/trace.go:171","msg":"trace[409495909] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"100.164989ms","start":"2026-10-16T09:15:57.323546Z","end":"2026-10-16T09:15:57.423711Z","steps":["trace[409495909] 'read index received' (duration: 31.880732ms)","trace[409495909] 'applied index is now lower than readState.Index' (duration: 68.282747ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:15:57.424338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.774497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:15:57.424389Z","caller":"traceutil/trace.go:171","msg":"trace[462303416] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:819; }","duration":"100.900299ms","start":"2026-10-16T09:15:57.323473Z","end":"2026-10-16T09:15:57.424373Z","steps":["trace[462303416] 'agreement among raft nodes before linearized reading' (duration: 100.804587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:15:57.424573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.990273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:15:57.424607Z","caller":"traceutil/trace.go:171","msg":"trace[1282840838] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:819; }","duration":"101.056094ms","start":"2026-10-16T09:15:57.323542Z","end":"2026-10-16T09:15:57.424598Z","steps":["trace[1282840838] 'agreement among raft nodes before linearized reading' (duration: 101.003602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:15:57.425497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.840118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:15:57.425542Z","caller":"traceutil/trace.go:171","msg":"trace[315411277] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:819; }","duration":"100.93139ms","start":"2026-10-16T09:15:57.324601Z","end":"2026-10-16T09:15:57.425532Z","steps":["trace[315411277] 'agreement among raft nodes before linearized reading' (duration: 100.863948ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:17:18.863315Z","caller":"traceutil/trace.go:171","msg":"trace[645967420] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"186.553978ms","start":"2026-10-16T09:17:18.676617Z","end":"2026-10-16T09:17:18.863171Z","steps":["trace[645967420] 'process raft request' (duration: 173.006177ms)","trace[645967420] 'compare' (duration: 12.692508ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:17:19.221684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.156977ms","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-10-16T09:17:19.221829Z","caller":"traceutil/trace.go:171","msg":"trace[536124508] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1924; }","duration":"102.323892ms","start":"2026-10-16T09:17:19.119486Z","end":"2026-10-16T09:17:19.22181Z","steps":["trace[536124508] 'range keys from in-memory index tree' (duration: 102.134367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:17:19.221802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.696123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-16T09:17:19.221964Z","caller":"traceutil/trace.go:171","msg":"trace[565086761] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1924; }","duration":"132.928849ms","start":"2026-10-16T09:17:19.089007Z","end":"2026-10-16T09:17:19.221936Z","steps":["trace[565086761] 'range keys from in-memory index tree' (duration: 132.452097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:19:38.500039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.248124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T09:19:38.500655Z","caller":"traceutil/trace.go:171","msg":"trace[1493992372] linearizableReadLoop","detail":"{readStateIndex:3038; appliedIndex:3037; }","duration":"130.182002ms","start":"2026-10-16T09:19:38.370446Z","end":"2026-10-16T09:19:38.500628Z","steps":["trace[1493992372] 'read index received' (duration: 90.373µs)","trace[1493992372] 'applied index is now lower than readState.Index' (duration: 130.089229ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:19:38.500769Z","caller":"traceutil/trace.go:171","msg":"trace[163817002] transaction","detail":"{read_only:false; response_revision:2920; number_of_response:1; }","duration":"192.669962ms","start":"2026-10-16T09:19:38.308084Z","end":"2026-10-16T09:19:38.500754Z","steps":["trace[163817002] 'process raft request' (duration: 46.563334ms)","trace[163817002] 'compare' (duration: 145.042268ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:19:38.501011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.350603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:19:38.50111Z","caller":"traceutil/trace.go:171","msg":"trace[344004650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2921; }","duration":"120.459526ms","start":"2026-10-16T09:19:38.380629Z","end":"2026-10-16T09:19:38.501088Z","steps":["trace[344004650] 'agreement among raft nodes before linearized reading' (duration: 120.303582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:19:38.501353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.913262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-16T09:19:38.5014Z","caller":"traceutil/trace.go:171","msg":"trace[1162672828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2921; }","duration":"131.016625ms","start":"2026-10-16T09:19:38.370371Z","end":"2026-10-16T09:19:38.501387Z","steps":["trace[1162672828] 'agreement among raft nodes before linearized reading' (duration: 130.84243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:19:38.836819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.216481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T09:19:38.836986Z","caller":"traceutil/trace.go:171","msg":"trace[1238266816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2922; }","duration":"146.440257ms","start":"2026-10-16T09:19:38.690515Z","end":"2026-10-16T09:19:38.836956Z","steps":["trace[1238266816] 'range keys from in-memory index tree' (duration: 145.994824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:19:38.837022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.590083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T09:19:38.837111Z","caller":"traceutil/trace.go:171","msg":"trace[1729506913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2922; }","duration":"145.767039ms","start":"2026-10-16T09:19:38.691325Z","end":"2026-10-16T09:19:38.837092Z","steps":["trace[1729506913] 'range keys from in-memory index tree' (duration: 145.353307ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:22:51.373562Z","caller":"traceutil/trace.go:171","msg":"trace[1024671821] transaction","detail":"{read_only:false; response_revision:4137; number_of_response:1; }","duration":"134.433061ms","start":"2026-10-16T09:22:51.239097Z","end":"2026-10-16T09:22:51.37353Z","steps":["trace[1024671821] 'process raft request' (duration: 134.367609ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:22:51.373712Z","caller":"traceutil/trace.go:171","msg":"trace[1190622192] transaction","detail":"{read_only:false; response_revision:4136; number_of_response:1; }","duration":"143.962031ms","start":"2026-10-16T09:22:51.229717Z","end":"2026-10-16T09:22:51.373679Z","steps":["trace[1190622192] 'process raft request' (duration: 143.580631ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:22.046854Z","caller":"traceutil/trace.go:171","msg":"trace[1251811972] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"130.030871ms","start":"2026-10-16T09:24:21.916762Z","end":"2026-10-16T09:24:22.046792Z","steps":["trace[1251811972] 'process raft request' (duration: 129.746703ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:22.047133Z","caller":"traceutil/trace.go:171","msg":"trace[206045818] linearizableReadLoop","detail":"{readStateIndex:5421; appliedIndex:5420; }","duration":"132.435807ms","start":"2026-10-16T09:24:21.914654Z","end":"2026-10-16T09:24:22.04709Z","steps":["trace[206045818] 'read index received' (duration: 95.220701ms)","trace[206045818] 'applied index is now lower than readState.Index' (duration: 37.211686ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:24:22.047628Z","caller":"traceutil/trace.go:171","msg":"trace[569594987] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"150.426998ms","start":"2026-10-16T09:24:21.896084Z","end":"2026-10-16T09:24:22.046511Z","steps":["trace[569594987] 'process raft request' (duration: 113.802328ms)","trace[569594987] 'compare' (duration: 36.432125ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:24:22.048038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.365212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-10-16T09:24:22.04809Z","caller":"traceutil/trace.go:171","msg":"trace[806135267] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5216; }","duration":"133.453045ms","start":"2026-10-16T09:24:21.914616Z","end":"2026-10-16T09:24:22.048069Z","steps":["trace[806135267] 'agreement among raft nodes before linearized reading' (duration: 132.601701ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:22.242334Z","caller":"traceutil/trace.go:171","msg":"trace[301392814] linearizableReadLoop","detail":"{readStateIndex:5424; appliedIndex:5423; }","duration":"100.519706ms","start":"2026-10-16T09:24:22.141791Z","end":"2026-10-16T09:24:22.242311Z","steps":["trace[301392814] 'read index received' (duration: 100.460854ms)","trace[301392814] 'applied index is now lower than readState.Index' (duration: 57.332µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:24:22.242539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.726911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-16T09:24:22.242773Z","caller":"traceutil/trace.go:171","msg":"trace[632133134] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"128.357876ms","start":"2026-10-16T09:24:22.114182Z","end":"2026-10-16T09:24:22.24254Z","steps":["trace[632133134] 'process raft request' (duration: 127.966335ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:22.242862Z","caller":"traceutil/trace.go:171","msg":"trace[158419864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5218; }","duration":"100.805093ms","start":"2026-10-16T09:24:22.141757Z","end":"2026-10-16T09:24:22.242562Z","steps":["trace[158419864] 'agreement among raft nodes before linearized reading' (duration: 100.641539ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:23.255215Z","caller":"traceutil/trace.go:171","msg":"trace[1428345851] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"122.906076ms","start":"2026-10-16T09:24:23.132272Z","end":"2026-10-16T09:24:23.255178Z","steps":["trace[1428345851] 'process raft request' (duration: 122.68461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:24:26.809127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.244714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T09:24:26.809255Z","caller":"traceutil/trace.go:171","msg":"trace[1128074434] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5583; }","duration":"235.766979ms","start":"2026-10-16T09:24:26.573471Z","end":"2026-10-16T09:24:26.809238Z","steps":["trace[1128074434] 'read index received' (duration: 39.312µs)","trace[1128074434] 'applied index is now lower than readState.Index' (duration: 235.725437ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:24:26.809349Z","caller":"traceutil/trace.go:171","msg":"trace[1771038221] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"306.713556ms","start":"2026-10-16T09:24:26.502621Z","end":"2026-10-16T09:24:26.809334Z","steps":["trace[1771038221] 'process raft request' (duration: 15.172984ms)","trace[1771038221] 'compare' (duration: 290.494413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:24:26.809434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:24:26.502606Z","time spent":"306.795018ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:24:26.809624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.132712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-99c0e150-2888-4414-8df8-45f33f09b7a6\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-10-16T09:24:26.809913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.409677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-16T09:24:26.809985Z","caller":"traceutil/trace.go:171","msg":"trace[732537409] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-99c0e150-2888-4414-8df8-45f33f09b7a6; range_end:; response_count:1; response_revision:5375; }","duration":"231.519742ms","start":"2026-10-16T09:24:26.578446Z","end":"2026-10-16T09:24:26.809965Z","steps":["trace[732537409] 'agreement among raft nodes before linearized reading' (duration: 231.015538ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:26.810027Z","caller":"traceutil/trace.go:171","msg":"trace[330637226] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5375; }","duration":"236.574171ms","start":"2026-10-16T09:24:26.573428Z","end":"2026-10-16T09:24:26.810002Z","steps":["trace[330637226] 'agreement among raft nodes before linearized reading' (duration: 236.227442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:24:26.810034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.636046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-99c0e150-2888-4414-8df8-45f33f09b7a6\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-10-16T09:24:26.810018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.103131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18def84257204094\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-10-16T09:24:26.810101Z","caller":"traceutil/trace.go:171","msg":"trace[1023448098] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-99c0e150-2888-4414-8df8-45f33f09b7a6; range_end:; response_count:1; response_revision:5375; }","duration":"168.743439ms","start":"2026-10-16T09:24:26.641345Z","end":"2026-10-16T09:24:26.810088Z","steps":["trace[1023448098] 'agreement among raft nodes before linearized reading' (duration: 168.540003ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:26.810125Z","caller":"traceutil/trace.go:171","msg":"trace[81752759] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18def84257204094; range_end:; response_count:1; response_revision:5375; }","duration":"116.258575ms","start":"2026-10-16T09:24:26.693844Z","end":"2026-10-16T09:24:26.810102Z","steps":["trace[81752759] 'agreement among raft nodes before linearized reading' (duration: 116.032289ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:40.906126Z","caller":"traceutil/trace.go:171","msg":"trace[779893753] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"108.143004ms","start":"2026-10-16T09:24:40.797956Z","end":"2026-10-16T09:24:40.906099Z","steps":["trace[779893753] 'process raft request' (duration: 107.976639ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:42.6497Z","caller":"traceutil/trace.go:171","msg":"trace[1214457062] transaction","detail":"{read_only:false; response_revision:5644; number_of_response:1; }","duration":"120.512382ms","start":"2026-10-16T09:24:42.52915Z","end":"2026-10-16T09:24:42.649663Z","steps":["trace[1214457062] 'process raft request' (duration: 118.944309ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:48.330455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2972} {"level":"info","ts":"2026-10-16T09:24:48.414594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2972,"took":"81.67637ms","hash":868400536,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":31887360,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T09:24:48.414658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":868400536,"revision":2972,"compact-revision":-1} {"level":"warn","ts":"2026-10-16T09:24:49.366512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.767659ms","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-10-16T09:24:49.366651Z","caller":"traceutil/trace.go:171","msg":"trace[940818789] transaction","detail":"{read_only:false; response_revision:5788; number_of_response:1; }","duration":"260.862994ms","start":"2026-10-16T09:24:49.105778Z","end":"2026-10-16T09:24:49.366641Z","steps":["trace[940818789] 'process raft request' (duration: 195.443428ms)","trace[940818789] 'compare' (duration: 65.212301ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:24:49.366667Z","caller":"traceutil/trace.go:171","msg":"trace[835165309] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5787; }","duration":"246.938074ms","start":"2026-10-16T09:24:49.119717Z","end":"2026-10-16T09:24:49.366655Z","steps":["trace[835165309] 'range keys from in-memory index tree' (duration: 246.754269ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:49.366527Z","caller":"traceutil/trace.go:171","msg":"trace[1212292156] linearizableReadLoop","detail":"{readStateIndex:6012; appliedIndex:6011; }","duration":"181.513757ms","start":"2026-10-16T09:24:49.184994Z","end":"2026-10-16T09:24:49.366508Z","steps":["trace[1212292156] 'read index received' (duration: 116.141822ms)","trace[1212292156] 'applied index is now lower than readState.Index' (duration: 65.371305ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:24:49.366544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.024995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4q6tj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-16T09:24:49.366756Z","caller":"traceutil/trace.go:171","msg":"trace[1334085992] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4q6tj; range_end:; response_count:1; response_revision:5787; }","duration":"277.278822ms","start":"2026-10-16T09:24:49.08946Z","end":"2026-10-16T09:24:49.366739Z","steps":["trace[1334085992] 'range keys from in-memory index tree' (duration: 276.861691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:24:49.366618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.62628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f10380d1-4130-451c-b442-cb5dcda23801\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-16T09:24:49.367481Z","caller":"traceutil/trace.go:171","msg":"trace[300060199] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f10380d1-4130-451c-b442-cb5dcda23801; range_end:; response_count:1; response_revision:5788; }","duration":"181.914458ms","start":"2026-10-16T09:24:49.184937Z","end":"2026-10-16T09:24:49.366852Z","steps":["trace[300060199] 'agreement among raft nodes before linearized reading' (duration: 181.592839ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:49.889924Z","caller":"traceutil/trace.go:171","msg":"trace[2099606091] transaction","detail":"{read_only:false; response_revision:5789; number_of_response:1; }","duration":"132.369685ms","start":"2026-10-16T09:24:49.757509Z","end":"2026-10-16T09:24:49.889879Z","steps":["trace[2099606091] 'process raft request' (duration: 132.18271ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:25:02.715176Z","caller":"traceutil/trace.go:171","msg":"trace[859969907] transaction","detail":"{read_only:false; response_revision:5956; number_of_response:1; }","duration":"106.941811ms","start":"2026-10-16T09:25:02.608208Z","end":"2026-10-16T09:25:02.71515Z","steps":["trace[859969907] 'process raft request' (duration: 85.724681ms)","trace[859969907] 'compare' (duration: 20.806328ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:25:44.243965Z","caller":"traceutil/trace.go:171","msg":"trace[1465249482] transaction","detail":"{read_only:false; response_revision:6316; number_of_response:1; }","duration":"102.034772ms","start":"2026-10-16T09:25:44.141892Z","end":"2026-10-16T09:25:44.243927Z","steps":["trace[1465249482] 'process raft request' (duration: 86.072235ms)","trace[1465249482] 'compare' (duration: 14.772734ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:29:48.337642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5770} {"level":"info","ts":"2026-10-16T09:29:48.42262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5770,"took":"83.301781ms","hash":510845932,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":26615808,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T09:29:48.422696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":510845932,"revision":5770,"compact-revision":2972} {"level":"info","ts":"2026-10-16T09:31:14.178293Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000234b.wal"} {"level":"info","ts":"2026-10-16T09:31:18.379461Z","caller":"traceutil/trace.go:171","msg":"trace[376923971] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"385.662492ms","start":"2026-10-16T09:31:17.993759Z","end":"2026-10-16T09:31:18.37941Z","steps":["trace[376923971] 'process raft request' (duration: 378.786434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:18.379536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.021564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-16T09:31:18.379628Z","caller":"traceutil/trace.go:171","msg":"trace[1365356511] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8739; }","duration":"515.204839ms","start":"2026-10-16T09:31:17.864398Z","end":"2026-10-16T09:31:18.379603Z","steps":["trace[1365356511] 'range keys from in-memory index tree' (duration: 514.794908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:18.379674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:17.864373Z","time spent":"515.290542ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-16T09:31:18.379692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:17.993706Z","time spent":"385.872418ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:31:29.928089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.983648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.928177Z","caller":"traceutil/trace.go:171","msg":"trace[2007661788] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8799; }","duration":"261.120062ms","start":"2026-10-16T09:31:29.667039Z","end":"2026-10-16T09:31:29.928159Z","steps":["trace[2007661788] 'range keys from in-memory index tree' (duration: 260.926007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.92844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.42573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.928466Z","caller":"traceutil/trace.go:171","msg":"trace[445140848] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8799; }","duration":"261.479972ms","start":"2026-10-16T09:31:29.666978Z","end":"2026-10-16T09:31:29.928458Z","steps":["trace[445140848] 'range keys from in-memory index tree' (duration: 261.320617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.928555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.402339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.928598Z","caller":"traceutil/trace.go:171","msg":"trace[1884137548] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8799; }","duration":"261.42107ms","start":"2026-10-16T09:31:29.667145Z","end":"2026-10-16T09:31:29.928566Z","steps":["trace[1884137548] 'count revisions from in-memory index tree' (duration: 261.328597ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:29.928997Z","caller":"traceutil/trace.go:171","msg":"trace[1597183024] linearizableReadLoop","detail":"{readStateIndex:9119; appliedIndex:9118; }","duration":"242.989345ms","start":"2026-10-16T09:31:29.685997Z","end":"2026-10-16T09:31:29.928987Z","steps":["trace[1597183024] 'read index received' (duration: 233.404173ms)","trace[1597183024] 'applied index is now lower than readState.Index' (duration: 9.584312ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:31:29.929054Z","caller":"traceutil/trace.go:171","msg":"trace[505077175] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"258.688585ms","start":"2026-10-16T09:31:29.670358Z","end":"2026-10-16T09:31:29.929047Z","steps":["trace[505077175] 'process raft request' (duration: 249.208585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.929215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.18409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.929325Z","caller":"traceutil/trace.go:171","msg":"trace[1562832987] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8800; }","duration":"243.342595ms","start":"2026-10-16T09:31:29.685958Z","end":"2026-10-16T09:31:29.929301Z","steps":["trace[1562832987] 'agreement among raft nodes before linearized reading' (duration: 243.17013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.948664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.613792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.948756Z","caller":"traceutil/trace.go:171","msg":"trace[1548322041] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8801; }","duration":"262.760936ms","start":"2026-10-16T09:31:29.685958Z","end":"2026-10-16T09:31:29.948719Z","steps":["trace[1548322041] 'agreement among raft nodes before linearized reading' (duration: 262.590712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.948966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.794623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949002Z","caller":"traceutil/trace.go:171","msg":"trace[160394963] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8801; }","duration":"126.887825ms","start":"2026-10-16T09:31:29.822102Z","end":"2026-10-16T09:31:29.94899Z","steps":["trace[160394963] 'agreement among raft nodes before linearized reading' (duration: 126.834443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.894582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T09:31:29.949023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.407319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T09:31:29.949126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.093497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949146Z","caller":"traceutil/trace.go:171","msg":"trace[1226983409] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8801; }","duration":"220.15627ms","start":"2026-10-16T09:31:29.728983Z","end":"2026-10-16T09:31:29.949139Z","steps":["trace[1226983409] 'agreement among raft nodes before linearized reading' (duration: 220.128329ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:29.949164Z","caller":"traceutil/trace.go:171","msg":"trace[1964206806] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8801; }","duration":"219.642185ms","start":"2026-10-16T09:31:29.729495Z","end":"2026-10-16T09:31:29.949137Z","steps":["trace[1964206806] 'agreement among raft nodes before linearized reading' (duration: 219.373327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.09159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949242Z","caller":"traceutil/trace.go:171","msg":"trace[1448746986] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8801; }","duration":"127.163772ms","start":"2026-10-16T09:31:29.822068Z","end":"2026-10-16T09:31:29.949232Z","steps":["trace[1448746986] 'agreement among raft nodes before linearized reading' (duration: 127.108071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.195648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949288Z","caller":"traceutil/trace.go:171","msg":"trace[1644471755] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8801; }","duration":"263.264ms","start":"2026-10-16T09:31:29.686019Z","end":"2026-10-16T09:31:29.949283Z","steps":["trace[1644471755] 'agreement among raft nodes before linearized reading' (duration: 263.232909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.087853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T09:31:29.948664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.800612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949378Z","caller":"traceutil/trace.go:171","msg":"trace[1953894417] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8801; }","duration":"150.586154ms","start":"2026-10-16T09:31:29.798784Z","end":"2026-10-16T09:31:29.94937Z","steps":["trace[1953894417] 'agreement among raft nodes before linearized reading' (duration: 149.812043ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:29.949393Z","caller":"traceutil/trace.go:171","msg":"trace[671202063] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8801; }","duration":"241.240496ms","start":"2026-10-16T09:31:29.708128Z","end":"2026-10-16T09:31:29.949368Z","steps":["trace[671202063] 'agreement among raft nodes before linearized reading' (duration: 241.02375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.4015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949433Z","caller":"traceutil/trace.go:171","msg":"trace[206431064] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8801; }","duration":"127.443341ms","start":"2026-10-16T09:31:29.82198Z","end":"2026-10-16T09:31:29.949423Z","steps":["trace[206431064] 'agreement among raft nodes before linearized reading' (duration: 127.377779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.096401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949612Z","caller":"traceutil/trace.go:171","msg":"trace[188414454] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8801; }","duration":"150.154353ms","start":"2026-10-16T09:31:29.799446Z","end":"2026-10-16T09:31:29.9496Z","steps":["trace[188414454] 'agreement among raft nodes before linearized reading' (duration: 150.091911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.323637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T09:31:29.949768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.229756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949801Z","caller":"traceutil/trace.go:171","msg":"trace[649151263] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8801; }","duration":"241.287958ms","start":"2026-10-16T09:31:29.708503Z","end":"2026-10-16T09:31:29.949791Z","steps":["trace[649151263] 'agreement among raft nodes before linearized reading' (duration: 241.221296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.267863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T09:31:29.949821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.460065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949841Z","caller":"traceutil/trace.go:171","msg":"trace[1542984733] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8801; }","duration":"170.354436ms","start":"2026-10-16T09:31:29.779474Z","end":"2026-10-16T09:31:29.949828Z","steps":["trace[1542984733] 'agreement among raft nodes before linearized reading' (duration: 170.317275ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:29.949854Z","caller":"traceutil/trace.go:171","msg":"trace[1698135997] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8801; }","duration":"198.534717ms","start":"2026-10-16T09:31:29.75131Z","end":"2026-10-16T09:31:29.949845Z","steps":["trace[1698135997] 'agreement among raft nodes before linearized reading' (duration: 198.478706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.992345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T09:31:29.94991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.606157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.949926Z","caller":"traceutil/trace.go:171","msg":"trace[2059755004] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8801; }","duration":"151.045907ms","start":"2026-10-16T09:31:29.798858Z","end":"2026-10-16T09:31:29.949904Z","steps":["trace[2059755004] 'agreement among raft nodes before linearized reading' (duration: 150.964105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.949986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.445218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.950007Z","caller":"traceutil/trace.go:171","msg":"trace[83823868] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8801; }","duration":"170.527021ms","start":"2026-10-16T09:31:29.779474Z","end":"2026-10-16T09:31:29.950001Z","steps":["trace[83823868] 'agreement among raft nodes before linearized reading' (duration: 170.49097ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:29.9491Z","caller":"traceutil/trace.go:171","msg":"trace[1244654255] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8801; }","duration":"219.970765ms","start":"2026-10-16T09:31:29.729122Z","end":"2026-10-16T09:31:29.949093Z","steps":["trace[1244654255] 'agreement among raft nodes before linearized reading' (duration: 219.930034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.950106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.197718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.950141Z","caller":"traceutil/trace.go:171","msg":"trace[431469495] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8801; }","duration":"198.256069ms","start":"2026-10-16T09:31:29.751875Z","end":"2026-10-16T09:31:29.950131Z","steps":["trace[431469495] 'agreement among raft nodes before linearized reading' (duration: 198.207278ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:29.949767Z","caller":"traceutil/trace.go:171","msg":"trace[955348232] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8801; }","duration":"169.451741ms","start":"2026-10-16T09:31:29.780278Z","end":"2026-10-16T09:31:29.949729Z","steps":["trace[955348232] 'agreement among raft nodes before linearized reading' (duration: 169.335508ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:29.949933Z","caller":"traceutil/trace.go:171","msg":"trace[1510545050] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8801; }","duration":"241.631547ms","start":"2026-10-16T09:31:29.708295Z","end":"2026-10-16T09:31:29.949927Z","steps":["trace[1510545050] 'agreement among raft nodes before linearized reading' (duration: 241.593836ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:29.950273Z","caller":"traceutil/trace.go:171","msg":"trace[1098656072] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"114.142546ms","start":"2026-10-16T09:31:29.836108Z","end":"2026-10-16T09:31:29.950251Z","steps":["trace[1098656072] 'process raft request' (duration: 112.256595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:29.94977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.355352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:29.950464Z","caller":"traceutil/trace.go:171","msg":"trace[1186592611] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8801; }","duration":"199.063071ms","start":"2026-10-16T09:31:29.751366Z","end":"2026-10-16T09:31:29.950429Z","steps":["trace[1186592611] 'agreement among raft nodes before linearized reading' (duration: 198.360642ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:30.612766Z","caller":"traceutil/trace.go:171","msg":"trace[847850102] transaction","detail":"{read_only:false; response_revision:8802; number_of_response:1; }","duration":"171.725433ms","start":"2026-10-16T09:31:30.440994Z","end":"2026-10-16T09:31:30.612719Z","steps":["trace[847850102] 'process raft request' (duration: 171.535237ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:31.110246Z","caller":"traceutil/trace.go:171","msg":"trace[1692197591] linearizableReadLoop","detail":"{readStateIndex:9124; appliedIndex:9123; }","duration":"150.353208ms","start":"2026-10-16T09:31:30.959855Z","end":"2026-10-16T09:31:31.110209Z","steps":["trace[1692197591] 'read index received' (duration: 150.244405ms)","trace[1692197591] 'applied index is now lower than readState.Index' (duration: 107.343µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:31:31.110405Z","caller":"traceutil/trace.go:171","msg":"trace[318065181] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"191.370251ms","start":"2026-10-16T09:31:30.919022Z","end":"2026-10-16T09:31:31.110392Z","steps":["trace[318065181] 'process raft request' (duration: 190.96829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:31.110612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.740208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T09:31:31.110689Z","caller":"traceutil/trace.go:171","msg":"trace[360708892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8805; }","duration":"150.881562ms","start":"2026-10-16T09:31:30.959788Z","end":"2026-10-16T09:31:31.11067Z","steps":["trace[360708892] 'agreement among raft nodes before linearized reading' (duration: 150.609865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:31.131105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.790478ms","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-10-16T09:31:31.131212Z","caller":"traceutil/trace.go:171","msg":"trace[1297837631] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8806; }","duration":"143.965803ms","start":"2026-10-16T09:31:30.987218Z","end":"2026-10-16T09:31:31.131184Z","steps":["trace[1297837631] 'agreement among raft nodes before linearized reading' (duration: 143.752227ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:31.34622Z","caller":"traceutil/trace.go:171","msg":"trace[639239530] linearizableReadLoop","detail":"{readStateIndex:9127; appliedIndex:9126; }","duration":"134.500493ms","start":"2026-10-16T09:31:31.211688Z","end":"2026-10-16T09:31:31.346188Z","steps":["trace[639239530] 'read index received' (duration: 41.312181ms)","trace[639239530] 'applied index is now lower than readState.Index' (duration: 93.186782ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:31:31.346291Z","caller":"traceutil/trace.go:171","msg":"trace[1410890969] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"210.379872ms","start":"2026-10-16T09:31:31.135888Z","end":"2026-10-16T09:31:31.346268Z","steps":["trace[1410890969] 'process raft request' (duration: 117.112627ms)","trace[1410890969] 'compare' (duration: 93.030188ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:31:31.346529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.821042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T09:31:31.34662Z","caller":"traceutil/trace.go:171","msg":"trace[1258943147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8808; }","duration":"134.970006ms","start":"2026-10-16T09:31:31.211631Z","end":"2026-10-16T09:31:31.346601Z","steps":["trace[1258943147] 'agreement among raft nodes before linearized reading' (duration: 134.679248ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:59.112399Z","caller":"traceutil/trace.go:171","msg":"trace[1941285177] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"391.200299ms","start":"2026-10-16T09:31:58.72117Z","end":"2026-10-16T09:31:59.11237Z","steps":["trace[1941285177] 'process raft request' (duration: 391.032365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:59.112563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:58.721131Z","time spent":"391.346853ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T09:31:59.112911Z","caller":"traceutil/trace.go:171","msg":"trace[784664381] linearizableReadLoop","detail":"{readStateIndex:9338; appliedIndex:9338; }","duration":"226.718553ms","start":"2026-10-16T09:31:58.886181Z","end":"2026-10-16T09:31:59.1129Z","steps":["trace[784664381] 'read index received' (duration: 226.712593ms)","trace[784664381] 'applied index is now lower than readState.Index' (duration: 4.48µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:31:59.113184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.9993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T09:31:59.113216Z","caller":"traceutil/trace.go:171","msg":"trace[1319368210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9010; }","duration":"227.073782ms","start":"2026-10-16T09:31:58.886133Z","end":"2026-10-16T09:31:59.113207Z","steps":["trace[1319368210] 'agreement among raft nodes before linearized reading' (duration: 226.99077ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:32:11.023413Z","caller":"traceutil/trace.go:171","msg":"trace[1455220109] transaction","detail":"{read_only:false; response_revision:9075; number_of_response:1; }","duration":"120.120261ms","start":"2026-10-16T09:32:10.903267Z","end":"2026-10-16T09:32:11.023388Z","steps":["trace[1455220109] 'process raft request' (duration: 119.950066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:32:11.293401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.512573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T09:32:11.293552Z","caller":"traceutil/trace.go:171","msg":"trace[2038204417] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:9075; }","duration":"189.747869ms","start":"2026-10-16T09:32:11.103776Z","end":"2026-10-16T09:32:11.293524Z","steps":["trace[2038204417] 'count revisions from in-memory index tree' (duration: 189.393479ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:32:11.345243Z","caller":"traceutil/trace.go:171","msg":"trace[1271328035] transaction","detail":"{read_only:false; response_revision:9076; number_of_response:1; }","duration":"141.106556ms","start":"2026-10-16T09:32:11.204089Z","end":"2026-10-16T09:32:11.345196Z","steps":["trace[1271328035] 'process raft request' (duration: 140.826008ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:33:13.846038Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5019b1666ea94ec4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T09:33:13.85279Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T09:33:13.85291Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T09:34:48.344733Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8033} {"level":"info","ts":"2026-10-16T09:34:48.436325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8033,"took":"89.053782ms","hash":2129604368,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":25026560,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-16T09:34:48.436419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2129604368,"revision":8033,"compact-revision":5770} {"level":"info","ts":"2026-10-16T09:38:59.860046Z","caller":"traceutil/trace.go:171","msg":"trace[80213896] linearizableReadLoop","detail":"{readStateIndex:12690; appliedIndex:12689; }","duration":"116.686378ms","start":"2026-10-16T09:38:59.743309Z","end":"2026-10-16T09:38:59.859996Z","steps":["trace[80213896] 'read index received' (duration: 116.436671ms)","trace[80213896] 'applied index is now lower than readState.Index' (duration: 248.857µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:38:59.860129Z","caller":"traceutil/trace.go:171","msg":"trace[1549558008] transaction","detail":"{read_only:false; response_revision:12260; number_of_response:1; }","duration":"119.752972ms","start":"2026-10-16T09:38:59.740352Z","end":"2026-10-16T09:38:59.860105Z","steps":["trace[1549558008] 'process raft request' (duration: 119.480794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:38:59.860249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.934015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T09:38:59.86028Z","caller":"traceutil/trace.go:171","msg":"trace[1242824909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12260; }","duration":"117.022627ms","start":"2026-10-16T09:38:59.743248Z","end":"2026-10-16T09:38:59.86027Z","steps":["trace[1242824909] 'agreement among raft nodes before linearized reading' (duration: 116.883133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:38:59.872149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.697989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-16T09:38:59.872253Z","caller":"traceutil/trace.go:171","msg":"trace[282240308] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12262; }","duration":"127.858134ms","start":"2026-10-16T09:38:59.744377Z","end":"2026-10-16T09:38:59.872235Z","steps":["trace[282240308] 'agreement among raft nodes before linearized reading' (duration: 127.510854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:38:59.872162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.883827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-16T09:38:59.872389Z","caller":"traceutil/trace.go:171","msg":"trace[885442685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12262; }","duration":"123.144324ms","start":"2026-10-16T09:38:59.749218Z","end":"2026-10-16T09:38:59.872362Z","steps":["trace[885442685] 'agreement among raft nodes before linearized reading' (duration: 122.751004ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:39:22.059047Z","caller":"traceutil/trace.go:171","msg":"trace[1192419748] transaction","detail":"{read_only:false; response_revision:12386; number_of_response:1; }","duration":"101.522262ms","start":"2026-10-16T09:39:21.957459Z","end":"2026-10-16T09:39:22.058981Z","steps":["trace[1192419748] 'process raft request' (duration: 29.625072ms)","trace[1192419748] 'compare' (duration: 71.565841ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:39:48.351495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10408} {"level":"info","ts":"2026-10-16T09:39:48.442113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10408,"took":"87.888688ms","hash":3328047683,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":25935872,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T09:39:48.442191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3328047683,"revision":10408,"compact-revision":8033} {"level":"info","ts":"2026-10-16T09:44:48.359863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12579} {"level":"info","ts":"2026-10-16T09:44:48.442445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12579,"took":"80.109788ms","hash":458766766,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":27926528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T09:44:48.442558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":458766766,"revision":12579,"compact-revision":10408} {"level":"warn","ts":"2026-10-16T09:45:29.270976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.299645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T09:45:29.271028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.175685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:45:29.27108Z","caller":"traceutil/trace.go:171","msg":"trace[1127952418] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:14866; }","duration":"106.47735ms","start":"2026-10-16T09:45:29.16458Z","end":"2026-10-16T09:45:29.271057Z","steps":["trace[1127952418] 'count revisions from in-memory index tree' (duration: 106.190823ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:45:29.271173Z","caller":"traceutil/trace.go:171","msg":"trace[1800674705] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14866; }","duration":"128.315379ms","start":"2026-10-16T09:45:29.142786Z","end":"2026-10-16T09:45:29.271102Z","steps":["trace[1800674705] 'range keys from in-memory index tree' (duration: 128.048102ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:45:32.602834Z","caller":"traceutil/trace.go:171","msg":"trace[1538656517] transaction","detail":"{read_only:false; response_revision:14892; number_of_response:1; }","duration":"169.088347ms","start":"2026-10-16T09:45:32.43372Z","end":"2026-10-16T09:45:32.602808Z","steps":["trace[1538656517] 'process raft request' (duration: 168.932913ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:46:11.108054Z","caller":"traceutil/trace.go:171","msg":"trace[1982537370] transaction","detail":"{read_only:false; response_revision:15103; number_of_response:1; }","duration":"350.818821ms","start":"2026-10-16T09:46:10.757196Z","end":"2026-10-16T09:46:11.108015Z","steps":["trace[1982537370] 'process raft request' (duration: 350.575065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:46:11.108432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:46:10.757168Z","time spent":"351.033907ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:46:21.541283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.490382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:46:21.54138Z","caller":"traceutil/trace.go:171","msg":"trace[52702557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15273; }","duration":"160.646706ms","start":"2026-10-16T09:46:21.380714Z","end":"2026-10-16T09:46:21.541361Z","steps":["trace[52702557] 'range keys from in-memory index tree' (duration: 160.393879ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:46:21.780131Z","caller":"traceutil/trace.go:171","msg":"trace[303421901] linearizableReadLoop","detail":"{readStateIndex:15809; appliedIndex:15808; }","duration":"195.065759ms","start":"2026-10-16T09:46:21.585039Z","end":"2026-10-16T09:46:21.780105Z","steps":["trace[303421901] 'read index received' (duration: 194.897205ms)","trace[303421901] 'applied index is now lower than readState.Index' (duration: 167.304µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:46:21.780216Z","caller":"traceutil/trace.go:171","msg":"trace[1805395510] transaction","detail":"{read_only:false; response_revision:15274; number_of_response:1; }","duration":"227.849609ms","start":"2026-10-16T09:46:21.552329Z","end":"2026-10-16T09:46:21.780179Z","steps":["trace[1805395510] 'process raft request' (duration: 227.622893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:46:21.78038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.332876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4q6tj\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-16T09:46:21.780383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.685285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T09:46:21.780432Z","caller":"traceutil/trace.go:171","msg":"trace[320388545] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4q6tj; range_end:; response_count:1; response_revision:15274; }","duration":"195.44307ms","start":"2026-10-16T09:46:21.584978Z","end":"2026-10-16T09:46:21.780421Z","steps":["trace[320388545] 'agreement among raft nodes before linearized reading' (duration: 195.287666ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:46:21.78044Z","caller":"traceutil/trace.go:171","msg":"trace[624881001] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15274; }","duration":"110.803209ms","start":"2026-10-16T09:46:21.669624Z","end":"2026-10-16T09:46:21.780428Z","steps":["trace[624881001] 'agreement among raft nodes before linearized reading' (duration: 110.696796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:46:21.780535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.70368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-v9cqx.18def9746783f680\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-16T09:46:21.780647Z","caller":"traceutil/trace.go:171","msg":"trace[1170522848] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-v9cqx.18def9746783f680; range_end:; response_count:1; response_revision:15274; }","duration":"163.876464ms","start":"2026-10-16T09:46:21.616738Z","end":"2026-10-16T09:46:21.780614Z","steps":["trace[1170522848] 'agreement among raft nodes before linearized reading' (duration: 163.590087ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:46:21.898208Z","caller":"traceutil/trace.go:171","msg":"trace[1580680762] transaction","detail":"{read_only:false; response_revision:15275; number_of_response:1; }","duration":"114.85197ms","start":"2026-10-16T09:46:21.783324Z","end":"2026-10-16T09:46:21.898176Z","steps":["trace[1580680762] 'process raft request' (duration: 80.773895ms)","trace[1580680762] 'compare' (duration: 33.89985ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:46:21.914849Z","caller":"traceutil/trace.go:171","msg":"trace[1245270733] linearizableReadLoop","detail":"{readStateIndex:15811; appliedIndex:15809; }","duration":"120.731291ms","start":"2026-10-16T09:46:21.794098Z","end":"2026-10-16T09:46:21.91483Z","steps":["trace[1245270733] 'read index received' (duration: 70.01364ms)","trace[1245270733] 'applied index is now lower than readState.Index' (duration: 50.716501ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:46:21.914951Z","caller":"traceutil/trace.go:171","msg":"trace[454531771] transaction","detail":"{read_only:false; response_revision:15276; number_of_response:1; }","duration":"131.28949ms","start":"2026-10-16T09:46:21.783652Z","end":"2026-10-16T09:46:21.914942Z","steps":["trace[454531771] 'process raft request' (duration: 131.043153ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:46:21.915082Z","caller":"traceutil/trace.go:171","msg":"trace[376588360] transaction","detail":"{read_only:false; response_revision:15277; number_of_response:1; }","duration":"116.260029ms","start":"2026-10-16T09:46:21.798813Z","end":"2026-10-16T09:46:21.915073Z","steps":["trace[376588360] 'process raft request' (duration: 115.981501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:46:21.915448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.323347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f7b01064-144f-449d-80ad-b9b979e2d618\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-10-16T09:46:21.915579Z","caller":"traceutil/trace.go:171","msg":"trace[1068263470] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f7b01064-144f-449d-80ad-b9b979e2d618; range_end:; response_count:1; response_revision:15277; }","duration":"121.502482ms","start":"2026-10-16T09:46:21.794052Z","end":"2026-10-16T09:46:21.915554Z","steps":["trace[1068263470] 'agreement among raft nodes before linearized reading' (duration: 121.152112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:46:21.917051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.092901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:630066"} {"level":"info","ts":"2026-10-16T09:46:21.917226Z","caller":"traceutil/trace.go:171","msg":"trace[1011854208] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:15277; }","duration":"121.330438ms","start":"2026-10-16T09:46:21.795864Z","end":"2026-10-16T09:46:21.917194Z","steps":["trace[1011854208] 'agreement among raft nodes before linearized reading' (duration: 119.93286ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:47:28.844215Z","caller":"traceutil/trace.go:171","msg":"trace[661015448] linearizableReadLoop","detail":"{readStateIndex:16750; appliedIndex:16749; }","duration":"100.410004ms","start":"2026-10-16T09:47:28.743784Z","end":"2026-10-16T09:47:28.844194Z","steps":["trace[661015448] 'read index received' (duration: 100.233939ms)","trace[661015448] 'applied index is now lower than readState.Index' (duration: 172.705µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:47:28.844312Z","caller":"traceutil/trace.go:171","msg":"trace[2063772779] transaction","detail":"{read_only:false; response_revision:16189; number_of_response:1; }","duration":"111.021955ms","start":"2026-10-16T09:47:28.733275Z","end":"2026-10-16T09:47:28.844297Z","steps":["trace[2063772779] 'process raft request' (duration: 110.757288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:47:28.844521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.721382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T09:47:28.844588Z","caller":"traceutil/trace.go:171","msg":"trace[914443914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16189; }","duration":"100.832306ms","start":"2026-10-16T09:47:28.743747Z","end":"2026-10-16T09:47:28.844579Z","steps":["trace[914443914] 'agreement among raft nodes before linearized reading' (duration: 100.546168ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:48:02.956093Z","caller":"traceutil/trace.go:171","msg":"trace[2076666368] linearizableReadLoop","detail":"{readStateIndex:16935; appliedIndex:16934; }","duration":"116.353601ms","start":"2026-10-16T09:48:02.839708Z","end":"2026-10-16T09:48:02.956062Z","steps":["trace[2076666368] 'read index received' (duration: 116.128245ms)","trace[2076666368] 'applied index is now lower than readState.Index' (duration: 224.266µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:48:02.956288Z","caller":"traceutil/trace.go:171","msg":"trace[2106669163] transaction","detail":"{read_only:false; response_revision:16367; number_of_response:1; }","duration":"126.346215ms","start":"2026-10-16T09:48:02.82992Z","end":"2026-10-16T09:48:02.956266Z","steps":["trace[2106669163] 'process raft request' (duration: 126.002535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:02.956322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.596748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-16T09:48:02.95636Z","caller":"traceutil/trace.go:171","msg":"trace[1486684400] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:16367; }","duration":"116.68361ms","start":"2026-10-16T09:48:02.839665Z","end":"2026-10-16T09:48:02.956349Z","steps":["trace[1486684400] 'agreement among raft nodes before linearized reading' (duration: 116.531216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.110401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.115063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:48:03.110491Z","caller":"traceutil/trace.go:171","msg":"trace[1611953351] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16367; }","duration":"136.280018ms","start":"2026-10-16T09:48:02.97419Z","end":"2026-10-16T09:48:03.11047Z","steps":["trace[1611953351] 'range keys from in-memory index tree' (duration: 136.01718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.110817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.942766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-16T09:48:03.11086Z","caller":"traceutil/trace.go:171","msg":"trace[993338692] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16367; }","duration":"133.000287ms","start":"2026-10-16T09:48:02.977845Z","end":"2026-10-16T09:48:03.110845Z","steps":["trace[993338692] 'range keys from in-memory index tree' (duration: 132.826513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.111067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.028256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-b4rtz\" ","response":"range_response_count:1 size:20637"} {"level":"info","ts":"2026-10-16T09:48:03.111102Z","caller":"traceutil/trace.go:171","msg":"trace[427268379] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-b4rtz; range_end:; response_count:1; response_revision:16367; }","duration":"130.083237ms","start":"2026-10-16T09:48:02.98101Z","end":"2026-10-16T09:48:03.111094Z","steps":["trace[427268379] 'range keys from in-memory index tree' (duration: 129.940944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.111402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.639715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-16T09:48:03.111442Z","caller":"traceutil/trace.go:171","msg":"trace[580416124] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16367; }","duration":"125.701937ms","start":"2026-10-16T09:48:02.985731Z","end":"2026-10-16T09:48:03.111433Z","steps":["trace[580416124] 'range keys from in-memory index tree' (duration: 125.550923ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:48:03.137196Z","caller":"traceutil/trace.go:171","msg":"trace[1347877613] linearizableReadLoop","detail":"{readStateIndex:16936; appliedIndex:16935; }","duration":"116.852175ms","start":"2026-10-16T09:48:03.020323Z","end":"2026-10-16T09:48:03.137176Z","steps":["trace[1347877613] 'read index received' (duration: 116.416783ms)","trace[1347877613] 'applied index is now lower than readState.Index' (duration: 434.242µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:48:03.137296Z","caller":"traceutil/trace.go:171","msg":"trace[335411621] transaction","detail":"{read_only:false; response_revision:16368; number_of_response:1; }","duration":"122.731546ms","start":"2026-10-16T09:48:03.014516Z","end":"2026-10-16T09:48:03.137248Z","steps":["trace[335411621] 'process raft request' (duration: 122.303064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.137434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.094462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-16T09:48:03.137865Z","caller":"traceutil/trace.go:171","msg":"trace[1834428420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16368; }","duration":"117.553194ms","start":"2026-10-16T09:48:03.020281Z","end":"2026-10-16T09:48:03.137834Z","steps":["trace[1834428420] 'agreement among raft nodes before linearized reading' (duration: 117.04939ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:48:03.368096Z","caller":"traceutil/trace.go:171","msg":"trace[731936997] transaction","detail":"{read_only:false; response_revision:16369; number_of_response:1; }","duration":"224.629541ms","start":"2026-10-16T09:48:03.143441Z","end":"2026-10-16T09:48:03.36807Z","steps":["trace[731936997] 'process raft request' (duration: 224.452436ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:48:03.376583Z","caller":"traceutil/trace.go:171","msg":"trace[1455538787] transaction","detail":"{read_only:false; response_revision:16370; number_of_response:1; }","duration":"231.914131ms","start":"2026-10-16T09:48:03.144626Z","end":"2026-10-16T09:48:03.37654Z","steps":["trace[1455538787] 'process raft request' (duration: 231.660174ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:48:03.37688Z","caller":"traceutil/trace.go:171","msg":"trace[609879316] transaction","detail":"{read_only:false; response_revision:16372; number_of_response:1; }","duration":"228.58808ms","start":"2026-10-16T09:48:03.148271Z","end":"2026-10-16T09:48:03.37686Z","steps":["trace[609879316] 'process raft request' (duration: 228.439396ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:48:03.376931Z","caller":"traceutil/trace.go:171","msg":"trace[112667304] linearizableReadLoop","detail":"{readStateIndex:16941; appliedIndex:16936; }","duration":"227.929881ms","start":"2026-10-16T09:48:03.148977Z","end":"2026-10-16T09:48:03.376907Z","steps":["trace[112667304] 'read index received' (duration: 218.969655ms)","trace[112667304] 'applied index is now lower than readState.Index' (duration: 8.959136ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:48:03.376972Z","caller":"traceutil/trace.go:171","msg":"trace[1299330621] transaction","detail":"{read_only:false; response_revision:16373; number_of_response:1; }","duration":"228.544838ms","start":"2026-10-16T09:48:03.148416Z","end":"2026-10-16T09:48:03.376961Z","steps":["trace[1299330621] 'process raft request' (duration: 228.402764ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:48:03.376876Z","caller":"traceutil/trace.go:171","msg":"trace[1100019828] transaction","detail":"{read_only:false; response_revision:16371; number_of_response:1; }","duration":"230.482901ms","start":"2026-10-16T09:48:03.146366Z","end":"2026-10-16T09:48:03.376849Z","steps":["trace[1100019828] 'process raft request' (duration: 230.114041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.377252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.123908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-16T09:48:03.377293Z","caller":"traceutil/trace.go:171","msg":"trace[19537443] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16373; }","duration":"138.21813ms","start":"2026-10-16T09:48:03.239065Z","end":"2026-10-16T09:48:03.377283Z","steps":["trace[19537443] 'agreement among raft nodes before linearized reading' (duration: 138.060436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.377881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.886077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769784"} {"level":"info","ts":"2026-10-16T09:48:03.377931Z","caller":"traceutil/trace.go:171","msg":"trace[1814743503] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16373; }","duration":"229.008011ms","start":"2026-10-16T09:48:03.148911Z","end":"2026-10-16T09:48:03.377919Z","steps":["trace[1814743503] 'agreement among raft nodes before linearized reading' (duration: 228.139217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.634517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.168633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:48:03.634628Z","caller":"traceutil/trace.go:171","msg":"trace[978758642] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:16373; }","duration":"121.343288ms","start":"2026-10-16T09:48:03.513257Z","end":"2026-10-16T09:48:03.634601Z","steps":["trace[978758642] 'count revisions from in-memory index tree' (duration: 121.081941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.634928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.742243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:48:03.634986Z","caller":"traceutil/trace.go:171","msg":"trace[1794581109] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16373; }","duration":"170.844105ms","start":"2026-10-16T09:48:03.464124Z","end":"2026-10-16T09:48:03.634968Z","steps":["trace[1794581109] 'range keys from in-memory index tree' (duration: 170.65844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.635335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.496003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-16T09:48:03.635419Z","caller":"traceutil/trace.go:171","msg":"trace[1609750921] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16373; }","duration":"167.615957ms","start":"2026-10-16T09:48:03.467784Z","end":"2026-10-16T09:48:03.6354Z","steps":["trace[1609750921] 'range keys from in-memory index tree' (duration: 167.259887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.635464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.251914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-16T09:48:03.635556Z","caller":"traceutil/trace.go:171","msg":"trace[877902450] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16373; }","duration":"168.426639ms","start":"2026-10-16T09:48:03.467115Z","end":"2026-10-16T09:48:03.635542Z","steps":["trace[877902450] 'range keys from in-memory index tree' (duration: 168.09154ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:48:03.635583Z","caller":"traceutil/trace.go:171","msg":"trace[1916282930] linearizableReadLoop","detail":"{readStateIndex:16942; appliedIndex:16941; }","duration":"100.083924ms","start":"2026-10-16T09:48:03.535479Z","end":"2026-10-16T09:48:03.635563Z","steps":["trace[1916282930] 'read index received' (duration: 29.850778ms)","trace[1916282930] 'applied index is now lower than readState.Index' (duration: 70.231526ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:48:03.635867Z","caller":"traceutil/trace.go:171","msg":"trace[1888555665] transaction","detail":"{read_only:false; response_revision:16374; number_of_response:1; }","duration":"122.139869ms","start":"2026-10-16T09:48:03.513711Z","end":"2026-10-16T09:48:03.635851Z","steps":["trace[1888555665] 'process raft request' (duration: 51.647776ms)","trace[1888555665] 'compare' (duration: 69.198588ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:48:03.636004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.164527ms","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-10-16T09:48:03.636049Z","caller":"traceutil/trace.go:171","msg":"trace[1312472417] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:16374; }","duration":"100.232208ms","start":"2026-10-16T09:48:03.535802Z","end":"2026-10-16T09:48:03.636034Z","steps":["trace[1312472417] 'agreement among raft nodes before linearized reading' (duration: 100.156106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:48:03.636504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.01747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9sjhv\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-10-16T09:48:03.636555Z","caller":"traceutil/trace.go:171","msg":"trace[547387247] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9sjhv; range_end:; response_count:1; response_revision:16374; }","duration":"101.108862ms","start":"2026-10-16T09:48:03.535434Z","end":"2026-10-16T09:48:03.636543Z","steps":["trace[547387247] 'agreement among raft nodes before linearized reading' (duration: 100.988429ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:49:48.365481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14584} {"level":"info","ts":"2026-10-16T09:49:48.450769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14584,"took":"82.640706ms","hash":2437730582,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":31752192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T09:49:48.450833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2437730582,"revision":14584,"compact-revision":12579} {"level":"warn","ts":"2026-10-16T09:53:39.855271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.410753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7913"} {"level":"info","ts":"2026-10-16T09:53:39.855372Z","caller":"traceutil/trace.go:171","msg":"trace[1795768524] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:18852; }","duration":"167.509356ms","start":"2026-10-16T09:53:39.687829Z","end":"2026-10-16T09:53:39.855338Z","steps":["trace[1795768524] 'range keys from in-memory index tree' (duration: 167.167207ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:53:39.855493Z","caller":"traceutil/trace.go:171","msg":"trace[1552255820] linearizableReadLoop","detail":"{readStateIndex:19505; appliedIndex:19504; }","duration":"157.842551ms","start":"2026-10-16T09:53:39.697625Z","end":"2026-10-16T09:53:39.855468Z","steps":["trace[1552255820] 'read index received' (duration: 155.596439ms)","trace[1552255820] 'applied index is now lower than readState.Index' (duration: 2.244692ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:53:39.855582Z","caller":"traceutil/trace.go:171","msg":"trace[195899301] transaction","detail":"{read_only:false; response_revision:18853; number_of_response:1; }","duration":"161.233714ms","start":"2026-10-16T09:53:39.69432Z","end":"2026-10-16T09:53:39.855553Z","steps":["trace[195899301] 'process raft request' (duration: 158.870929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:39.855676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.038527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T09:53:39.855716Z","caller":"traceutil/trace.go:171","msg":"trace[1029666592] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:18853; }","duration":"158.136059ms","start":"2026-10-16T09:53:39.697569Z","end":"2026-10-16T09:53:39.855705Z","steps":["trace[1029666592] 'agreement among raft nodes before linearized reading' (duration: 158.034516ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:53:43.216282Z","caller":"traceutil/trace.go:171","msg":"trace[1571010345] linearizableReadLoop","detail":"{readStateIndex:19520; appliedIndex:19519; }","duration":"243.122991ms","start":"2026-10-16T09:53:42.973136Z","end":"2026-10-16T09:53:43.216259Z","steps":["trace[1571010345] 'read index received' (duration: 242.931916ms)","trace[1571010345] 'applied index is now lower than readState.Index' (duration: 189.535µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:53:43.216353Z","caller":"traceutil/trace.go:171","msg":"trace[1100902391] transaction","detail":"{read_only:false; response_revision:18867; number_of_response:1; }","duration":"253.706701ms","start":"2026-10-16T09:53:42.962628Z","end":"2026-10-16T09:53:43.216335Z","steps":["trace[1100902391] 'process raft request' (duration: 253.444044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:43.216508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.348557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T09:53:43.216543Z","caller":"traceutil/trace.go:171","msg":"trace[752142785] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:18867; }","duration":"243.421659ms","start":"2026-10-16T09:53:42.973111Z","end":"2026-10-16T09:53:43.216533Z","steps":["trace[752142785] 'agreement among raft nodes before linearized reading' (duration: 243.257325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:43.295969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.373971ms","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:6"} {"level":"info","ts":"2026-10-16T09:53:43.296027Z","caller":"traceutil/trace.go:171","msg":"trace[1516344911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:18868; }","duration":"230.479384ms","start":"2026-10-16T09:53:43.065535Z","end":"2026-10-16T09:53:43.296015Z","steps":["trace[1516344911] 'agreement among raft nodes before linearized reading' (duration: 230.350281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:43.296079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.242574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T09:53:43.296137Z","caller":"traceutil/trace.go:171","msg":"trace[878521800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18868; }","duration":"134.303825ms","start":"2026-10-16T09:53:43.16182Z","end":"2026-10-16T09:53:43.296124Z","steps":["trace[878521800] 'agreement among raft nodes before linearized reading' (duration: 134.125661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:43.296172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.876006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T09:53:43.29624Z","caller":"traceutil/trace.go:171","msg":"trace[325146553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18868; }","duration":"253.988179ms","start":"2026-10-16T09:53:43.042238Z","end":"2026-10-16T09:53:43.296226Z","steps":["trace[325146553] 'agreement among raft nodes before linearized reading' (duration: 253.877976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:43.296261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.915332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-16T09:53:43.296276Z","caller":"traceutil/trace.go:171","msg":"trace[2023284093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18868; }","duration":"204.931293ms","start":"2026-10-16T09:53:43.09134Z","end":"2026-10-16T09:53:43.296271Z","steps":["trace[2023284093] 'agreement among raft nodes before linearized reading' (duration: 204.883582ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:53:43.296334Z","caller":"traceutil/trace.go:171","msg":"trace[801532878] transaction","detail":"{read_only:false; response_revision:18868; number_of_response:1; }","duration":"205.523619ms","start":"2026-10-16T09:53:43.090805Z","end":"2026-10-16T09:53:43.296329Z","steps":["trace[801532878] 'process raft request' (duration: 204.930913ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:53:45.993006Z","caller":"traceutil/trace.go:171","msg":"trace[120973618] transaction","detail":"{read_only:false; response_revision:18900; number_of_response:1; }","duration":"119.403427ms","start":"2026-10-16T09:53:45.873546Z","end":"2026-10-16T09:53:45.99295Z","steps":["trace[120973618] 'process raft request' (duration: 88.276282ms)","trace[120973618] 'compare' (duration: 30.984021ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:53:46.218981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.337285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T09:53:46.219147Z","caller":"traceutil/trace.go:171","msg":"trace[2140288636] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18900; }","duration":"115.699225ms","start":"2026-10-16T09:53:46.103429Z","end":"2026-10-16T09:53:46.219128Z","steps":["trace[2140288636] 'range keys from in-memory index tree' (duration: 115.242772ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:54:23.029394Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5019b1666ea94ec4","local-member-applied-index":20009,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T09:54:23.03768Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20009} {"level":"info","ts":"2026-10-16T09:54:23.037777Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15009} {"level":"info","ts":"2026-10-16T09:54:48.37484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17044} {"level":"info","ts":"2026-10-16T09:54:48.483367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17044,"took":"105.371421ms","hash":648735122,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":34582528,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-16T09:54:48.483437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":648735122,"revision":17044,"compact-revision":14584} {"level":"info","ts":"2026-10-16T09:59:43.259151Z","caller":"traceutil/trace.go:171","msg":"trace[604765054] linearizableReadLoop","detail":"{readStateIndex:22394; appliedIndex:22393; }","duration":"194.37547ms","start":"2026-10-16T09:59:43.064751Z","end":"2026-10-16T09:59:43.259127Z","steps":["trace[604765054] 'read index received' (duration: 194.072961ms)","trace[604765054] 'applied index is now lower than readState.Index' (duration: 301.349µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:59:43.259263Z","caller":"traceutil/trace.go:171","msg":"trace[1325476113] transaction","detail":"{read_only:false; response_revision:21649; number_of_response:1; }","duration":"216.103578ms","start":"2026-10-16T09:59:43.043106Z","end":"2026-10-16T09:59:43.259209Z","steps":["trace[1325476113] 'process raft request' (duration: 215.838491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:59:43.259654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.853543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T09:59:43.259709Z","caller":"traceutil/trace.go:171","msg":"trace[1453239828] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:21649; }","duration":"194.954225ms","start":"2026-10-16T09:59:43.064742Z","end":"2026-10-16T09:59:43.259697Z","steps":["trace[1453239828] 'agreement among raft nodes before linearized reading' (duration: 194.517683ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:59:48.382643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19653} {"level":"info","ts":"2026-10-16T09:59:48.504672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19653,"took":"117.936836ms","hash":2211162989,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":30138368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T09:59:48.504831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2211162989,"revision":19653,"compact-revision":17044} {"level":"info","ts":"2026-10-16T10:00:03.706192Z","caller":"traceutil/trace.go:171","msg":"trace[2036809475] linearizableReadLoop","detail":"{readStateIndex:22546; appliedIndex:22545; }","duration":"290.169406ms","start":"2026-10-16T10:00:03.415997Z","end":"2026-10-16T10:00:03.706167Z","steps":["trace[2036809475] 'read index received' (duration: 289.992651ms)","trace[2036809475] 'applied index is now lower than readState.Index' (duration: 175.865µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:00:03.706286Z","caller":"traceutil/trace.go:171","msg":"trace[2118705407] transaction","detail":"{read_only:false; response_revision:21796; number_of_response:1; }","duration":"371.172095ms","start":"2026-10-16T10:00:03.335053Z","end":"2026-10-16T10:00:03.706225Z","steps":["trace[2118705407] 'process raft request' (duration: 370.953309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:03.70647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.335035Z","time spent":"371.340329ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T10:00:03.706482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.465053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T10:00:03.706645Z","caller":"traceutil/trace.go:171","msg":"trace[560112363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21796; }","duration":"290.636478ms","start":"2026-10-16T10:00:03.415981Z","end":"2026-10-16T10:00:03.706617Z","steps":["trace[560112363] 'agreement among raft nodes before linearized reading' (duration: 290.314759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.282639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.089365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T10:00:04.282906Z","caller":"traceutil/trace.go:171","msg":"trace[1692819511] transaction","detail":"{read_only:false; response_revision:21798; number_of_response:1; }","duration":"571.503509ms","start":"2026-10-16T10:00:03.711343Z","end":"2026-10-16T10:00:04.282847Z","steps":["trace[1692819511] 'process raft request' (duration: 571.399216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.282976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.711321Z","time spent":"571.619441ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T10:00:04.283141Z","caller":"traceutil/trace.go:171","msg":"trace[711208663] transaction","detail":"{read_only:false; response_revision:21797; number_of_response:1; }","duration":"800.763617ms","start":"2026-10-16T10:00:03.482362Z","end":"2026-10-16T10:00:04.283125Z","steps":["trace[711208663] 'process raft request' (duration: 527.102206ms)","trace[711208663] 'compare' (duration: 272.958712ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:00:04.283079Z","caller":"traceutil/trace.go:171","msg":"trace[635243600] linearizableReadLoop","detail":"{readStateIndex:22547; appliedIndex:22546; }","duration":"576.693111ms","start":"2026-10-16T10:00:03.706289Z","end":"2026-10-16T10:00:04.282982Z","steps":["trace[635243600] 'read index received' (duration: 303.190884ms)","trace[635243600] 'applied index is now lower than readState.Index' (duration: 273.497417ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:00:04.283332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.48234Z","time spent":"800.961383ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T10:00:04.283464Z","caller":"traceutil/trace.go:171","msg":"trace[1211085094] transaction","detail":"{read_only:false; response_revision:21799; number_of_response:1; }","duration":"534.292534ms","start":"2026-10-16T10:00:03.749146Z","end":"2026-10-16T10:00:04.283438Z","steps":["trace[1211085094] 'process raft request' (duration: 533.667157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.283572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.652859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T10:00:04.283621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.77929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"warn","ts":"2026-10-16T10:00:04.283658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.749122Z","time spent":"534.441678ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T10:00:04.283699Z","caller":"traceutil/trace.go:171","msg":"trace[815524802] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21799; }","duration":"674.880793ms","start":"2026-10-16T10:00:03.608797Z","end":"2026-10-16T10:00:04.283677Z","steps":["trace[815524802] 'agreement among raft nodes before linearized reading' (duration: 674.306548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.283761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.608784Z","time spent":"674.964715ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-16T10:00:04.2837Z","caller":"traceutil/trace.go:171","msg":"trace[1768554251] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:21799; }","duration":"665.896136ms","start":"2026-10-16T10:00:03.617773Z","end":"2026-10-16T10:00:04.283669Z","steps":["trace[1768554251] 'agreement among raft nodes before linearized reading' (duration: 665.352501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.283845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.617752Z","time spent":"666.077211ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-10-16T10:00:04.283853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.978887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-16T10:00:04.283893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.532222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T10:00:04.283952Z","caller":"traceutil/trace.go:171","msg":"trace[484828230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21799; }","duration":"852.614765ms","start":"2026-10-16T10:00:03.431322Z","end":"2026-10-16T10:00:04.283937Z","steps":["trace[484828230] 'agreement among raft nodes before linearized reading' (duration: 851.879104ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:00:04.283902Z","caller":"traceutil/trace.go:171","msg":"trace[1799939387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21799; }","duration":"723.067789ms","start":"2026-10-16T10:00:03.560822Z","end":"2026-10-16T10:00:04.28389Z","steps":["trace[1799939387] 'agreement among raft nodes before linearized reading' (duration: 722.284908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.283998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.431309Z","time spent":"852.677937ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-16T10:00:04.284042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.560803Z","time spent":"723.201293ms","remote":"127.0.0.1:59534","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-10-16T10:00:04.284149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.074747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-16T10:00:04.284686Z","caller":"traceutil/trace.go:171","msg":"trace[1465680792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21799; }","duration":"724.610491ms","start":"2026-10-16T10:00:03.560014Z","end":"2026-10-16T10:00:04.284625Z","steps":["trace[1465680792] 'agreement among raft nodes before linearized reading' (duration: 723.1144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.284895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.559994Z","time spent":"724.881329ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-16T10:00:04.285227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.006847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-16T10:00:04.285346Z","caller":"traceutil/trace.go:171","msg":"trace[1800795403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21799; }","duration":"719.139461ms","start":"2026-10-16T10:00:03.566172Z","end":"2026-10-16T10:00:04.285311Z","steps":["trace[1800795403] 'agreement among raft nodes before linearized reading' (duration: 716.936911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.285389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:03.566162Z","time spent":"719.215703ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-16T10:00:04.5765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.986887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T10:00:04.576637Z","caller":"traceutil/trace.go:171","msg":"trace[113056231] transaction","detail":"{read_only:false; response_revision:21800; number_of_response:1; }","duration":"287.826221ms","start":"2026-10-16T10:00:04.288785Z","end":"2026-10-16T10:00:04.576611Z","steps":["trace[113056231] 'process raft request' (duration: 185.632909ms)","trace[113056231] 'compare' (duration: 101.854373ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:00:04.948878Z","caller":"traceutil/trace.go:171","msg":"trace[1246757653] transaction","detail":"{read_only:false; response_revision:21801; number_of_response:1; }","duration":"659.13503ms","start":"2026-10-16T10:00:04.289712Z","end":"2026-10-16T10:00:04.948847Z","steps":["trace[1246757653] 'process raft request' (duration: 621.999168ms)","trace[1246757653] 'compare' (duration: 36.572366ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:00:04.949029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:04.289689Z","time spent":"659.270704ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T10:00:04.949413Z","caller":"traceutil/trace.go:171","msg":"trace[803823462] transaction","detail":"{read_only:false; response_revision:21804; number_of_response:1; }","duration":"232.588471ms","start":"2026-10-16T10:00:04.716808Z","end":"2026-10-16T10:00:04.949396Z","steps":["trace[803823462] 'process raft request' (duration: 232.431987ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:00:04.949473Z","caller":"traceutil/trace.go:171","msg":"trace[1353537291] transaction","detail":"{read_only:false; response_revision:21805; number_of_response:1; }","duration":"204.986562ms","start":"2026-10-16T10:00:04.744472Z","end":"2026-10-16T10:00:04.949458Z","steps":["trace[1353537291] 'process raft request' (duration: 204.874449ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:00:04.949485Z","caller":"traceutil/trace.go:171","msg":"trace[474655560] transaction","detail":"{read_only:false; response_revision:21802; number_of_response:1; }","duration":"659.016907ms","start":"2026-10-16T10:00:04.290455Z","end":"2026-10-16T10:00:04.949472Z","steps":["trace[474655560] 'process raft request' (duration: 658.290647ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:00:04.949404Z","caller":"traceutil/trace.go:171","msg":"trace[1752666922] transaction","detail":"{read_only:false; response_revision:21803; number_of_response:1; }","duration":"658.03074ms","start":"2026-10-16T10:00:04.291339Z","end":"2026-10-16T10:00:04.94937Z","steps":["trace[1752666922] 'process raft request' (duration: 657.784273ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:00:04.949625Z","caller":"traceutil/trace.go:171","msg":"trace[95366144] linearizableReadLoop","detail":"{readStateIndex:22553; appliedIndex:22549; }","duration":"569.123983ms","start":"2026-10-16T10:00:04.380481Z","end":"2026-10-16T10:00:04.949605Z","steps":["trace[95366144] 'read index received' (duration: 93.948945ms)","trace[95366144] 'applied index is now lower than readState.Index' (duration: 475.173678ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:00:04.949616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:04.290445Z","time spent":"659.076328ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T10:00:04.949671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:04.291323Z","time spent":"658.300297ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T10:00:04.949773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.294668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T10:00:04.949814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.157323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T10:00:04.949823Z","caller":"traceutil/trace.go:171","msg":"trace[1007652703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21805; }","duration":"569.37908ms","start":"2026-10-16T10:00:04.380427Z","end":"2026-10-16T10:00:04.949806Z","steps":["trace[1007652703] 'agreement among raft nodes before linearized reading' (duration: 569.298138ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:00:04.949858Z","caller":"traceutil/trace.go:171","msg":"trace[1395349936] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:21805; }","duration":"545.248636ms","start":"2026-10-16T10:00:04.404595Z","end":"2026-10-16T10:00:04.949843Z","steps":["trace[1395349936] 'agreement among raft nodes before linearized reading' (duration: 545.171864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:00:04.949862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:04.380407Z","time spent":"569.445472ms","remote":"127.0.0.1:59254","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-10-16T10:00:04.949897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:00:04.40456Z","time spent":"545.323877ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-10-16T10:04:12.860048Z","caller":"traceutil/trace.go:171","msg":"trace[688226276] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"153.580471ms","start":"2026-10-16T10:04:12.706434Z","end":"2026-10-16T10:04:12.860014Z","steps":["trace[688226276] 'process raft request' (duration: 153.423056ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:04:48.388651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21676} {"level":"info","ts":"2026-10-16T10:04:48.523857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21676,"took":"131.304707ms","hash":2584038363,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":43110400,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-16T10:04:48.523932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2584038363,"revision":21676,"compact-revision":19653} {"level":"info","ts":"2026-10-16T10:05:52.643171Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066c8.wal"} {"level":"info","ts":"2026-10-16T10:07:45.402283Z","caller":"traceutil/trace.go:171","msg":"trace[1809635201] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"117.777144ms","start":"2026-10-16T10:07:45.284479Z","end":"2026-10-16T10:07:45.402256Z","steps":["trace[1809635201] 'process raft request' (duration: 117.591939ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:07:50.835828Z","caller":"traceutil/trace.go:171","msg":"trace[472013251] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"102.38547ms","start":"2026-10-16T10:07:50.733413Z","end":"2026-10-16T10:07:50.835798Z","steps":["trace[472013251] 'process raft request' (duration: 102.192685ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:07:50.999343Z","caller":"traceutil/trace.go:171","msg":"trace[1222763189] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"206.323673ms","start":"2026-10-16T10:07:50.792987Z","end":"2026-10-16T10:07:50.999311Z","steps":["trace[1222763189] 'process raft request' (duration: 186.950749ms)","trace[1222763189] 'compare' (duration: 18.885021ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:07:50.999663Z","caller":"traceutil/trace.go:171","msg":"trace[823871321] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"188.210264ms","start":"2026-10-16T10:07:50.811426Z","end":"2026-10-16T10:07:50.999636Z","steps":["trace[823871321] 'process raft request' (duration: 187.827064ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:07:51.000253Z","caller":"traceutil/trace.go:171","msg":"trace[1861920422] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"170.658861ms","start":"2026-10-16T10:07:50.829582Z","end":"2026-10-16T10:07:51.000241Z","steps":["trace[1861920422] 'process raft request' (duration: 169.90649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:07:51.289865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.485829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T10:07:51.289957Z","caller":"traceutil/trace.go:171","msg":"trace[1323637464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26505; }","duration":"173.614162ms","start":"2026-10-16T10:07:51.116313Z","end":"2026-10-16T10:07:51.289927Z","steps":["trace[1323637464] 'range keys from in-memory index tree' (duration: 173.306953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:07:51.290034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.130594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T10:07:51.290158Z","caller":"traceutil/trace.go:171","msg":"trace[744641802] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"167.141624ms","start":"2026-10-16T10:07:51.122992Z","end":"2026-10-16T10:07:51.290134Z","steps":["trace[744641802] 'process raft request' (duration: 36.814984ms)","trace[744641802] 'compare' (duration: 129.867217ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:08:51.85045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.44542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T10:08:51.850529Z","caller":"traceutil/trace.go:171","msg":"trace[938388505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27055; }","duration":"237.566634ms","start":"2026-10-16T10:08:51.61295Z","end":"2026-10-16T10:08:51.850516Z","steps":["trace[938388505] 'range keys from in-memory index tree' (duration: 237.303357ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:51.850874Z","caller":"traceutil/trace.go:171","msg":"trace[2072268628] transaction","detail":"{read_only:false; response_revision:27056; number_of_response:1; }","duration":"146.971999ms","start":"2026-10-16T10:08:51.703874Z","end":"2026-10-16T10:08:51.850846Z","steps":["trace[2072268628] 'process raft request' (duration: 125.457797ms)","trace[2072268628] 'compare' (duration: 21.360308ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:08:52.007719Z","caller":"traceutil/trace.go:171","msg":"trace[783901619] transaction","detail":"{read_only:false; response_revision:27057; number_of_response:1; }","duration":"188.092331ms","start":"2026-10-16T10:08:51.819593Z","end":"2026-10-16T10:08:52.007686Z","steps":["trace[783901619] 'process raft request' (duration: 187.876785ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:52.044959Z","caller":"traceutil/trace.go:171","msg":"trace[71805216] linearizableReadLoop","detail":"{readStateIndex:27950; appliedIndex:27948; }","duration":"111.411248ms","start":"2026-10-16T10:08:51.933521Z","end":"2026-10-16T10:08:52.044932Z","steps":["trace[71805216] 'read index received' (duration: 73.901455ms)","trace[71805216] 'applied index is now lower than readState.Index' (duration: 37.508793ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:08:52.045036Z","caller":"traceutil/trace.go:171","msg":"trace[284309052] transaction","detail":"{read_only:false; response_revision:27058; number_of_response:1; }","duration":"189.950473ms","start":"2026-10-16T10:08:51.855059Z","end":"2026-10-16T10:08:52.04501Z","steps":["trace[284309052] 'process raft request' (duration: 189.735407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:52.045198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.654876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T10:08:52.045234Z","caller":"traceutil/trace.go:171","msg":"trace[230436289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27058; }","duration":"111.744458ms","start":"2026-10-16T10:08:51.933478Z","end":"2026-10-16T10:08:52.045222Z","steps":["trace[230436289] 'agreement among raft nodes before linearized reading' (duration: 111.576364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:52.09845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.712862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-16T10:08:52.098538Z","caller":"traceutil/trace.go:171","msg":"trace[80053779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27059; }","duration":"162.833755ms","start":"2026-10-16T10:08:51.935685Z","end":"2026-10-16T10:08:52.098519Z","steps":["trace[80053779] 'agreement among raft nodes before linearized reading' (duration: 162.609839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:52.099328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.778401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T10:08:52.09939Z","caller":"traceutil/trace.go:171","msg":"trace[587267299] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:27059; }","duration":"106.888754ms","start":"2026-10-16T10:08:51.992484Z","end":"2026-10-16T10:08:52.099373Z","steps":["trace[587267299] 'agreement among raft nodes before linearized reading' (duration: 106.793981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:52.099689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.955185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-16T10:08:52.099739Z","caller":"traceutil/trace.go:171","msg":"trace[1316222499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27059; }","duration":"128.034147ms","start":"2026-10-16T10:08:51.971692Z","end":"2026-10-16T10:08:52.099726Z","steps":["trace[1316222499] 'agreement among raft nodes before linearized reading' (duration: 127.902163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:52.099968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.934947ms","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:6"} {"level":"info","ts":"2026-10-16T10:08:52.100014Z","caller":"traceutil/trace.go:171","msg":"trace[140884371] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:27059; }","duration":"131.019769ms","start":"2026-10-16T10:08:51.968977Z","end":"2026-10-16T10:08:52.099996Z","steps":["trace[140884371] 'agreement among raft nodes before linearized reading' (duration: 130.952947ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:52.258963Z","caller":"traceutil/trace.go:171","msg":"trace[1269806370] transaction","detail":"{read_only:false; response_revision:27060; number_of_response:1; }","duration":"203.480745ms","start":"2026-10-16T10:08:52.055455Z","end":"2026-10-16T10:08:52.258936Z","steps":["trace[1269806370] 'process raft request' (duration: 193.434598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:52.431066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:08:52.102315Z","time spent":"328.743705ms","remote":"127.0.0.1:59278","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-16T10:08:52.43119Z","caller":"traceutil/trace.go:171","msg":"trace[1036231438] transaction","detail":"{read_only:false; response_revision:27061; number_of_response:1; }","duration":"325.66308ms","start":"2026-10-16T10:08:52.105501Z","end":"2026-10-16T10:08:52.431164Z","steps":["trace[1036231438] 'process raft request' (duration: 325.421704ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:52.431187Z","caller":"traceutil/trace.go:171","msg":"trace[203908127] transaction","detail":"{read_only:false; response_revision:27062; number_of_response:1; }","duration":"324.972391ms","start":"2026-10-16T10:08:52.106186Z","end":"2026-10-16T10:08:52.431158Z","steps":["trace[203908127] 'process raft request' (duration: 324.872708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:52.431323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:08:52.105479Z","time spent":"325.770814ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T10:08:52.431328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:08:52.106171Z","time spent":"325.091995ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T10:08:52.597136Z","caller":"traceutil/trace.go:171","msg":"trace[938279226] linearizableReadLoop","detail":"{readStateIndex:27958; appliedIndex:27957; }","duration":"163.631178ms","start":"2026-10-16T10:08:52.433456Z","end":"2026-10-16T10:08:52.597088Z","steps":["trace[938279226] 'read index received' (duration: 150.034613ms)","trace[938279226] 'applied index is now lower than readState.Index' (duration: 13.594625ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:08:52.597364Z","caller":"traceutil/trace.go:171","msg":"trace[587782061] transaction","detail":"{read_only:false; response_revision:27065; number_of_response:1; }","duration":"164.46548ms","start":"2026-10-16T10:08:52.432855Z","end":"2026-10-16T10:08:52.59732Z","steps":["trace[587782061] 'process raft request' (duration: 150.598658ms)","trace[587782061] 'compare' (duration: 13.202194ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:08:52.597338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.839143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T10:08:52.597504Z","caller":"traceutil/trace.go:171","msg":"trace[1056388453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27065; }","duration":"164.065919ms","start":"2026-10-16T10:08:52.433415Z","end":"2026-10-16T10:08:52.59748Z","steps":["trace[1056388453] 'agreement among raft nodes before linearized reading' (duration: 163.785562ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:52.772548Z","caller":"traceutil/trace.go:171","msg":"trace[1007802569] transaction","detail":"{read_only:false; response_revision:27066; number_of_response:1; }","duration":"108.330634ms","start":"2026-10-16T10:08:52.664148Z","end":"2026-10-16T10:08:52.772479Z","steps":["trace[1007802569] 'process raft request' (duration: 93.581118ms)","trace[1007802569] 'compare' (duration: 14.468888ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:09:48.419584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24705} {"level":"info","ts":"2026-10-16T10:09:48.577423Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24705,"took":"154.679201ms","hash":83306596,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":37666816,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-16T10:09:48.577519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":83306596,"revision":24705,"compact-revision":21676} {"level":"info","ts":"2026-10-16T10:12:19.564745Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5019b1666ea94ec4","local-member-applied-index":30010,"local-member-snapshot-index":20009,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T10:12:19.579047Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30010} {"level":"info","ts":"2026-10-16T10:12:19.579154Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25010} {"level":"info","ts":"2026-10-16T10:14:48.426512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27648} {"level":"info","ts":"2026-10-16T10:14:48.576372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27648,"took":"145.12298ms","hash":2259567298,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":34959360,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-16T10:14:48.576459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2259567298,"revision":27648,"compact-revision":24705} {"level":"info","ts":"2026-10-16T10:19:48.433086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30347} {"level":"info","ts":"2026-10-16T10:19:48.579697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30347,"took":"142.406215ms","hash":2003276827,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":30867456,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T10:19:48.579761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2003276827,"revision":30347,"compact-revision":27648} {"level":"info","ts":"2026-10-16T10:24:48.43952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32598} {"level":"info","ts":"2026-10-16T10:24:48.601648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32598,"took":"157.668907ms","hash":3676511570,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":30519296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T10:24:48.601717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3676511570,"revision":32598,"compact-revision":30347} {"level":"info","ts":"2026-10-16T10:29:48.446106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34831} {"level":"info","ts":"2026-10-16T10:29:48.589296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34831,"took":"136.407304ms","hash":613935196,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":31440896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T10:29:48.589364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":613935196,"revision":34831,"compact-revision":32598} {"level":"info","ts":"2026-10-16T10:33:26.089654Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5019b1666ea94ec4","local-member-applied-index":40011,"local-member-snapshot-index":30010,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T10:33:26.09548Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40011} {"level":"info","ts":"2026-10-16T10:33:26.095757Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35011} {"level":"info","ts":"2026-10-16T10:34:48.452887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37123} {"level":"info","ts":"2026-10-16T10:34:48.580236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37123,"took":"123.297593ms","hash":3085663749,"current-db-size-bytes":60788736,"current-db-size":"61 MB","current-db-size-in-use-bytes":33239040,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T10:34:48.58132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3085663749,"revision":37123,"compact-revision":34831} {"level":"info","ts":"2026-10-16T10:39:48.463552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39397} {"level":"info","ts":"2026-10-16T10:39:48.597772Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39397,"took":"129.913492ms","hash":2643344677,"current-db-size-bytes":63340544,"current-db-size":"63 MB","current-db-size-in-use-bytes":32145408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T10:39:48.59784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2643344677,"revision":39397,"compact-revision":37123} {"level":"info","ts":"2026-10-16T10:44:48.471751Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41634} {"level":"info","ts":"2026-10-16T10:44:48.603554Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41634,"took":"127.558586ms","hash":3042479476,"current-db-size-bytes":63340544,"current-db-size":"63 MB","current-db-size-in-use-bytes":30113792,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T10:44:48.60365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3042479476,"revision":41634,"compact-revision":39397}