{"level":"warn","ts":"2026-07-14T23:08:44.581866Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T23:08:44.582024Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.133:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.133:2380","--initial-cluster=instance=https://199.19.213.133:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.133:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.133: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-07-14T23:08:44.582135Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T23:08:44.582155Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.133:2380"]} {"level":"info","ts":"2026-07-14T23:08:44.582183Z","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-07-14T23:08:44.582873Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"]} {"level":"info","ts":"2026-07-14T23:08:44.582954Z","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://199.19.213.133:2380"],"listen-peer-urls":["https://199.19.213.133:2380"],"advertise-client-urls":["https://199.19.213.133:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.133: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-07-14T23:08:44.585857Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.63079ms"} {"level":"info","ts":"2026-07-14T23:08:44.591965Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21b354698260dd82","cluster-id":"b778216e1db811d5"} {"level":"info","ts":"2026-07-14T23:08:44.592053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T23:08:44.592077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became follower at term 0"} {"level":"info","ts":"2026-07-14T23:08:44.592098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21b354698260dd82 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T23:08:44.592109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became follower at term 1"} {"level":"info","ts":"2026-07-14T23:08:44.59216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=(2428377436218514818)"} {"level":"warn","ts":"2026-07-14T23:08:44.596563Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T23:08:44.602384Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T23:08:44.603721Z","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-07-14T23:08:44.605984Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21b354698260dd82","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T23:08:44.607442Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21b354698260dd82","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T23:08:44.607515Z","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-07-14T23:08:44.607653Z","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-07-14T23:08:44.607668Z","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-07-14T23:08:44.608738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=(2428377436218514818)"} {"level":"info","ts":"2026-07-14T23:08:44.608846Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b778216e1db811d5","local-member-id":"21b354698260dd82","added-peer-id":"21b354698260dd82","added-peer-peer-urls":["https://199.19.213.133:2380"]} {"level":"info","ts":"2026-07-14T23:08:44.6122Z","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-07-14T23:08:44.612359Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.133:2380"} {"level":"info","ts":"2026-07-14T23:08:44.612415Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.133:2380"} {"level":"info","ts":"2026-07-14T23:08:44.612481Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21b354698260dd82","initial-advertise-peer-urls":["https://199.19.213.133:2380"],"listen-peer-urls":["https://199.19.213.133:2380"],"advertise-client-urls":["https://199.19.213.133:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T23:08:44.612584Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T23:08:45.39287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T23:08:45.392936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T23:08:45.392986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 received MsgPreVoteResp from 21b354698260dd82 at term 1"} {"level":"info","ts":"2026-07-14T23:08:45.39304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became candidate at term 2"} {"level":"info","ts":"2026-07-14T23:08:45.393053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 received MsgVoteResp from 21b354698260dd82 at term 2"} {"level":"info","ts":"2026-07-14T23:08:45.393075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became leader at term 2"} {"level":"info","ts":"2026-07-14T23:08:45.393097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21b354698260dd82 elected leader 21b354698260dd82 at term 2"} {"level":"info","ts":"2026-07-14T23:08:45.394534Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:08:45.394506Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21b354698260dd82","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.133:2379]}","request-path":"/0/members/21b354698260dd82/attributes","cluster-id":"b778216e1db811d5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T23:08:45.394572Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:08:45.394611Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:08:45.394948Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T23:08:45.395068Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T23:08:45.396234Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b778216e1db811d5","local-member-id":"21b354698260dd82","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:08:45.396445Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:08:45.396496Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:08:45.399641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T23:08:45.400335Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.133:2379"} {"level":"info","ts":"2026-07-14T23:09:22.635122Z","caller":"traceutil/trace.go:171","msg":"trace[2098912806] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"108.38286ms","start":"2026-07-14T23:09:22.526715Z","end":"2026-07-14T23:09:22.635098Z","steps":["trace[2098912806] 'process raft request' (duration: 99.096423ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.782825Z","caller":"traceutil/trace.go:171","msg":"trace[396375808] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"116.843551ms","start":"2026-07-14T23:09:28.665934Z","end":"2026-07-14T23:09:28.782777Z","steps":["trace[396375808] 'process raft request' (duration: 116.172619ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:29.399334Z","caller":"traceutil/trace.go:171","msg":"trace[533608493] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"264.498907ms","start":"2026-07-14T23:09:29.1348Z","end":"2026-07-14T23:09:29.399298Z","steps":["trace[533608493] 'read index received' (duration: 264.393445ms)","trace[533608493] 'applied index is now lower than readState.Index' (duration: 104.052µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:29.399409Z","caller":"traceutil/trace.go:171","msg":"trace[1022307214] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"266.679108ms","start":"2026-07-14T23:09:29.13269Z","end":"2026-07-14T23:09:29.399369Z","steps":["trace[1022307214] 'process raft request' (duration: 266.399853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:29.399687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.845703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-14T23:09:29.399819Z","caller":"traceutil/trace.go:171","msg":"trace[19588550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"265.023856ms","start":"2026-07-14T23:09:29.134773Z","end":"2026-07-14T23:09:29.399797Z","steps":["trace[19588550] 'agreement among raft nodes before linearized reading' (duration: 264.68125ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:29.547489Z","caller":"traceutil/trace.go:171","msg":"trace[278372815] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"147.971993ms","start":"2026-07-14T23:09:29.399477Z","end":"2026-07-14T23:09:29.547449Z","steps":["trace[278372815] 'read index received' (duration: 146.746ms)","trace[278372815] 'applied index is now lower than readState.Index' (duration: 1.224473ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:29.547782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.215774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-14T23:09:29.547881Z","caller":"traceutil/trace.go:171","msg":"trace[1705117556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"412.350047ms","start":"2026-07-14T23:09:29.135505Z","end":"2026-07-14T23:09:29.547855Z","steps":["trace[1705117556] 'agreement among raft nodes before linearized reading' (duration: 412.087163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:29.547844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.590509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z5z75\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-14T23:09:29.547946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:29.135491Z","time spent":"412.445889ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T23:09:29.548028Z","caller":"traceutil/trace.go:171","msg":"trace[1058674310] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z5z75; range_end:; response_count:1; response_revision:599; }","duration":"334.834403ms","start":"2026-07-14T23:09:29.213166Z","end":"2026-07-14T23:09:29.548001Z","steps":["trace[1058674310] 'agreement among raft nodes before linearized reading' (duration: 334.358694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:29.548112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:29.213149Z","time spent":"334.949586ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-z5z75\" "} {"level":"info","ts":"2026-07-14T23:09:29.548166Z","caller":"traceutil/trace.go:171","msg":"trace[551576335] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"251.283945ms","start":"2026-07-14T23:09:29.296779Z","end":"2026-07-14T23:09:29.548063Z","steps":["trace[551576335] 'process raft request' (duration: 249.844559ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:29.551201Z","caller":"traceutil/trace.go:171","msg":"trace[1812909588] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"147.159427ms","start":"2026-07-14T23:09:29.404017Z","end":"2026-07-14T23:09:29.551177Z","steps":["trace[1812909588] 'process raft request' (duration: 147.017694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:44.436079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.888457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8v59c\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-14T23:09:44.436195Z","caller":"traceutil/trace.go:171","msg":"trace[1426761810] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8v59c; range_end:; response_count:1; response_revision:760; }","duration":"122.068451ms","start":"2026-07-14T23:09:44.314101Z","end":"2026-07-14T23:09:44.43617Z","steps":["trace[1426761810] 'range keys from in-memory index tree' (duration: 121.713864ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:44.582097Z","caller":"traceutil/trace.go:171","msg":"trace[383789684] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"136.78402ms","start":"2026-07-14T23:09:44.445292Z","end":"2026-07-14T23:09:44.582076Z","steps":["trace[383789684] 'process raft request' (duration: 136.641077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:58.247413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.121215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-14T23:10:58.247495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.088787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7128218b-5216-4769-819d-741ad1414a2e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-14T23:10:58.247559Z","caller":"traceutil/trace.go:171","msg":"trace[1036464248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1824; }","duration":"173.3613ms","start":"2026-07-14T23:10:58.074166Z","end":"2026-07-14T23:10:58.247527Z","steps":["trace[1036464248] 'range keys from in-memory index tree' (duration: 172.820609ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:58.247596Z","caller":"traceutil/trace.go:171","msg":"trace[549010890] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7128218b-5216-4769-819d-741ad1414a2e; range_end:; response_count:1; response_revision:1824; }","duration":"158.234709ms","start":"2026-07-14T23:10:58.08933Z","end":"2026-07-14T23:10:58.247565Z","steps":["trace[549010890] 'range keys from in-memory index tree' (duration: 157.795481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:58.247584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.211853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-14T23:10:58.247633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.974041ms","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-07-14T23:10:58.247905Z","caller":"traceutil/trace.go:171","msg":"trace[178213211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1824; }","duration":"303.288007ms","start":"2026-07-14T23:10:57.944599Z","end":"2026-07-14T23:10:58.247887Z","steps":["trace[178213211] 'range keys from in-memory index tree' (duration: 302.464022ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:58.247752Z","caller":"traceutil/trace.go:171","msg":"trace[1157473297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1824; }","duration":"177.449077ms","start":"2026-07-14T23:10:58.070274Z","end":"2026-07-14T23:10:58.247723Z","steps":["trace[1157473297] 'range keys from in-memory index tree' (duration: 176.881437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:58.247949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:57.944574Z","time spent":"303.359798ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T23:10:58.599083Z","caller":"traceutil/trace.go:171","msg":"trace[1044721266] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"121.332194ms","start":"2026-07-14T23:10:58.477712Z","end":"2026-07-14T23:10:58.599044Z","steps":["trace[1044721266] 'process raft request' (duration: 65.22064ms)","trace[1044721266] 'compare' (duration: 55.940931ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:58.618067Z","caller":"traceutil/trace.go:171","msg":"trace[116243300] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"117.131532ms","start":"2026-07-14T23:10:58.500896Z","end":"2026-07-14T23:10:58.618027Z","steps":["trace[116243300] 'process raft request' (duration: 116.886067ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:09.560537Z","caller":"traceutil/trace.go:171","msg":"trace[1968334097] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"125.098456ms","start":"2026-07-14T23:11:09.435404Z","end":"2026-07-14T23:11:09.560503Z","steps":["trace[1968334097] 'process raft request' (duration: 123.22535ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:41.615818Z","caller":"traceutil/trace.go:171","msg":"trace[902718620] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"120.934286ms","start":"2026-07-14T23:11:41.494843Z","end":"2026-07-14T23:11:41.615777Z","steps":["trace[902718620] 'process raft request' (duration: 120.669081ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:47.96387Z","caller":"traceutil/trace.go:171","msg":"trace[814706549] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"175.50991ms","start":"2026-07-14T23:11:47.788326Z","end":"2026-07-14T23:11:47.963835Z","steps":["trace[814706549] 'process raft request' (duration: 75.545237ms)","trace[814706549] 'compare' (duration: 99.729269ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:47.997303Z","caller":"traceutil/trace.go:171","msg":"trace[1428447608] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2223; }","duration":"145.67711ms","start":"2026-07-14T23:11:47.851595Z","end":"2026-07-14T23:11:47.997272Z","steps":["trace[1428447608] 'read index received' (duration: 12.273216ms)","trace[1428447608] 'applied index is now lower than readState.Index' (duration: 133.402464ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:47.997415Z","caller":"traceutil/trace.go:171","msg":"trace[1174990153] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"168.122339ms","start":"2026-07-14T23:11:47.829259Z","end":"2026-07-14T23:11:47.997382Z","steps":["trace[1174990153] 'process raft request' (duration: 167.826803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:47.997632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.002126ms","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-07-14T23:11:47.997736Z","caller":"traceutil/trace.go:171","msg":"trace[1729681416] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"146.153469ms","start":"2026-07-14T23:11:47.851544Z","end":"2026-07-14T23:11:47.997697Z","steps":["trace[1729681416] 'agreement among raft nodes before linearized reading' (duration: 145.858283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:10.396917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.581489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-14T23:13:10.39719Z","caller":"traceutil/trace.go:171","msg":"trace[920314899] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2784; }","duration":"151.946507ms","start":"2026-07-14T23:13:10.245219Z","end":"2026-07-14T23:13:10.397165Z","steps":["trace[920314899] 'range keys from in-memory index tree' (duration: 151.281183ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:10.397115Z","caller":"traceutil/trace.go:171","msg":"trace[1827314092] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"131.636995ms","start":"2026-07-14T23:13:10.265466Z","end":"2026-07-14T23:13:10.397103Z","steps":["trace[1827314092] 'process raft request' (duration: 118.658465ms)","trace[1827314092] 'compare' (duration: 12.640643ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:13:10.397061Z","caller":"traceutil/trace.go:171","msg":"trace[1319999921] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"112.580968ms","start":"2026-07-14T23:13:10.284454Z","end":"2026-07-14T23:13:10.397035Z","steps":["trace[1319999921] 'process raft request' (duration: 112.493536ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:17:06.855588Z","caller":"traceutil/trace.go:171","msg":"trace[1995213829] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"163.308401ms","start":"2026-07-14T23:17:06.692249Z","end":"2026-07-14T23:17:06.855557Z","steps":["trace[1995213829] 'process raft request' (duration: 163.119017ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:17:06.990192Z","caller":"traceutil/trace.go:171","msg":"trace[209665152] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"297.665824ms","start":"2026-07-14T23:17:06.692461Z","end":"2026-07-14T23:17:06.990126Z","steps":["trace[209665152] 'process raft request' (duration: 284.637712ms)","trace[209665152] 'compare' (duration: 12.729956ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:17:19.990497Z","caller":"traceutil/trace.go:171","msg":"trace[496486276] linearizableReadLoop","detail":"{readStateIndex:5436; appliedIndex:5435; }","duration":"202.204623ms","start":"2026-07-14T23:17:19.788262Z","end":"2026-07-14T23:17:19.990467Z","steps":["trace[496486276] 'read index received' (duration: 201.628201ms)","trace[496486276] 'applied index is now lower than readState.Index' (duration: 574.562µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:17:19.990565Z","caller":"traceutil/trace.go:171","msg":"trace[1795567550] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"298.572813ms","start":"2026-07-14T23:17:19.69194Z","end":"2026-07-14T23:17:19.990513Z","steps":["trace[1795567550] 'process raft request' (duration: 298.109733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:17:19.990826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.941887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-95dgs\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-07-14T23:17:19.990885Z","caller":"traceutil/trace.go:171","msg":"trace[1553855390] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-95dgs; range_end:; response_count:1; response_revision:5237; }","duration":"175.074619ms","start":"2026-07-14T23:17:19.815795Z","end":"2026-07-14T23:17:19.990869Z","steps":["trace[1553855390] 'agreement among raft nodes before linearized reading' (duration: 174.877186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:17:19.990859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.565039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-14T23:17:19.990971Z","caller":"traceutil/trace.go:171","msg":"trace[1919234031] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5237; }","duration":"202.733303ms","start":"2026-07-14T23:17:19.788212Z","end":"2026-07-14T23:17:19.990945Z","steps":["trace[1919234031] 'agreement among raft nodes before linearized reading' (duration: 202.367166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:17:28.231768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.867716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:17:28.23208Z","caller":"traceutil/trace.go:171","msg":"trace[2063690454] linearizableReadLoop","detail":"{readStateIndex:5601; appliedIndex:5600; }","duration":"245.773807ms","start":"2026-07-14T23:17:27.986287Z","end":"2026-07-14T23:17:28.232061Z","steps":["trace[2063690454] 'read index received' (duration: 86.033934ms)","trace[2063690454] 'applied index is now lower than readState.Index' (duration: 159.737423ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:17:28.232055Z","caller":"traceutil/trace.go:171","msg":"trace[963103144] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"285.959847ms","start":"2026-07-14T23:17:27.946039Z","end":"2026-07-14T23:17:28.231999Z","steps":["trace[963103144] 'process raft request' (duration: 126.322006ms)","trace[963103144] 'compare' (duration: 158.474718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:17:28.232269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.947521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9abbb7bc-9c9c-4cb3-8507-7e0ded9c316f\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-14T23:17:28.232346Z","caller":"traceutil/trace.go:171","msg":"trace[980463089] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9abbb7bc-9c9c-4cb3-8507-7e0ded9c316f; range_end:; response_count:1; response_revision:5401; }","duration":"246.088194ms","start":"2026-07-14T23:17:27.98624Z","end":"2026-07-14T23:17:28.232328Z","steps":["trace[980463089] 'agreement among raft nodes before linearized reading' (duration: 245.92224ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:17:28.23246Z","caller":"traceutil/trace.go:171","msg":"trace[1824061555] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"137.542017ms","start":"2026-07-14T23:17:28.094898Z","end":"2026-07-14T23:17:28.23244Z","steps":["trace[1824061555] 'process raft request' (duration: 137.322923ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:17:28.232453Z","caller":"traceutil/trace.go:171","msg":"trace[486324710] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"137.913844ms","start":"2026-07-14T23:17:28.094493Z","end":"2026-07-14T23:17:28.232407Z","steps":["trace[486324710] 'process raft request' (duration: 137.506556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:17:28.23259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.037931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-14T23:17:28.232673Z","caller":"traceutil/trace.go:171","msg":"trace[1943284054] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5403; }","duration":"155.165925ms","start":"2026-07-14T23:17:28.077485Z","end":"2026-07-14T23:17:28.232651Z","steps":["trace[1943284054] 'agreement among raft nodes before linearized reading' (duration: 154.794518ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:17:28.482761Z","caller":"traceutil/trace.go:171","msg":"trace[1044692496] linearizableReadLoop","detail":"{readStateIndex:5604; appliedIndex:5603; }","duration":"103.488737ms","start":"2026-07-14T23:17:28.379233Z","end":"2026-07-14T23:17:28.482722Z","steps":["trace[1044692496] 'read index received' (duration: 3.949049ms)","trace[1044692496] 'applied index is now lower than readState.Index' (duration: 99.537778ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:17:28.483Z","caller":"traceutil/trace.go:171","msg":"trace[1289599163] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"159.345606ms","start":"2026-07-14T23:17:28.323499Z","end":"2026-07-14T23:17:28.482844Z","steps":["trace[1289599163] 'process raft request' (duration: 59.779718ms)","trace[1289599163] 'compare' (duration: 99.263893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:17:28.483131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.855614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T23:17:28.483182Z","caller":"traceutil/trace.go:171","msg":"trace[655829805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5404; }","duration":"103.990267ms","start":"2026-07-14T23:17:28.379177Z","end":"2026-07-14T23:17:28.483168Z","steps":["trace[655829805] 'agreement among raft nodes before linearized reading' (duration: 103.748312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:17:28.483166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.825533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:17:28.483259Z","caller":"traceutil/trace.go:171","msg":"trace[1243517324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5404; }","duration":"103.968876ms","start":"2026-07-14T23:17:28.37927Z","end":"2026-07-14T23:17:28.483239Z","steps":["trace[1243517324] 'agreement among raft nodes before linearized reading' (duration: 103.697741ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:18:45.42544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-07-14T23:18:45.494237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"66.759203ms","hash":817645863,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":33968128,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-14T23:18:45.494295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":817645863,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-07-14T23:23:45.433397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6101} {"level":"info","ts":"2026-07-14T23:23:45.539711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6101,"took":"103.685205ms","hash":4237397677,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":23375872,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-14T23:23:45.539813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4237397677,"revision":6101,"compact-revision":2980} {"level":"warn","ts":"2026-07-14T23:24:18.401925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.070991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:24:18.402115Z","caller":"traceutil/trace.go:171","msg":"trace[1624374771] linearizableReadLoop","detail":"{readStateIndex:8826; appliedIndex:8825; }","duration":"404.589035ms","start":"2026-07-14T23:24:17.9975Z","end":"2026-07-14T23:24:18.402089Z","steps":["trace[1624374771] 'read index received' (duration: 195.183287ms)","trace[1624374771] 'applied index is now lower than readState.Index' (duration: 209.403708ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:24:18.402248Z","caller":"traceutil/trace.go:171","msg":"trace[1975918707] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"463.0116ms","start":"2026-07-14T23:24:17.939218Z","end":"2026-07-14T23:24:18.402229Z","steps":["trace[1975918707] 'process raft request' (duration: 253.48497ms)","trace[1975918707] 'compare' (duration: 208.115603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:24:18.402347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:24:17.939201Z","time spent":"463.092942ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:24:18.402517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.863883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:24:18.402597Z","caller":"traceutil/trace.go:171","msg":"trace[1574729793] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8521; }","duration":"290.985506ms","start":"2026-07-14T23:24:18.111593Z","end":"2026-07-14T23:24:18.402578Z","steps":["trace[1574729793] 'agreement among raft nodes before linearized reading' (duration: 290.839443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:24:18.402852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.366039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5rsj7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-14T23:24:18.402878Z","caller":"traceutil/trace.go:171","msg":"trace[250029676] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5rsj7; range_end:; response_count:1; response_revision:8521; }","duration":"405.44034ms","start":"2026-07-14T23:24:17.997429Z","end":"2026-07-14T23:24:18.40287Z","steps":["trace[250029676] 'agreement among raft nodes before linearized reading' (duration: 405.329428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:24:18.402892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:24:17.997403Z","time spent":"405.484202ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-5rsj7\" "} {"level":"info","ts":"2026-07-14T23:24:22.076179Z","caller":"traceutil/trace.go:171","msg":"trace[1743045853] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"146.107539ms","start":"2026-07-14T23:24:21.930043Z","end":"2026-07-14T23:24:22.076151Z","steps":["trace[1743045853] 'process raft request' (duration: 145.920335ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:24:22.07712Z","caller":"traceutil/trace.go:171","msg":"trace[815715673] linearizableReadLoop","detail":"{readStateIndex:8850; appliedIndex:8848; }","duration":"135.889013ms","start":"2026-07-14T23:24:21.941201Z","end":"2026-07-14T23:24:22.07709Z","steps":["trace[815715673] 'read index received' (duration: 134.851314ms)","trace[815715673] 'applied index is now lower than readState.Index' (duration: 1.036419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:24:22.077366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.13347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:24:22.077407Z","caller":"traceutil/trace.go:171","msg":"trace[940632652] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"136.642779ms","start":"2026-07-14T23:24:21.940732Z","end":"2026-07-14T23:24:22.077375Z","steps":["trace[940632652] 'process raft request' (duration: 136.157139ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:24:22.077459Z","caller":"traceutil/trace.go:171","msg":"trace[388065055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8544; }","duration":"136.276742ms","start":"2026-07-14T23:24:21.941166Z","end":"2026-07-14T23:24:22.077442Z","steps":["trace[388065055] 'agreement among raft nodes before linearized reading' (duration: 136.111619ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:24:30.868767Z","caller":"traceutil/trace.go:171","msg":"trace[879508646] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"118.969282ms","start":"2026-07-14T23:24:30.749775Z","end":"2026-07-14T23:24:30.868744Z","steps":["trace[879508646] 'process raft request' (duration: 118.811609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:24:31.06903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.581122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:24:31.069141Z","caller":"traceutil/trace.go:171","msg":"trace[733485871] linearizableReadLoop","detail":"{readStateIndex:8942; appliedIndex:8940; }","duration":"230.050712ms","start":"2026-07-14T23:24:30.839072Z","end":"2026-07-14T23:24:31.069123Z","steps":["trace[733485871] 'read index received' (duration: 29.539035ms)","trace[733485871] 'applied index is now lower than readState.Index' (duration: 200.510627ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:24:31.069169Z","caller":"traceutil/trace.go:171","msg":"trace[1586657289] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"319.082162ms","start":"2026-07-14T23:24:30.750054Z","end":"2026-07-14T23:24:31.069136Z","steps":["trace[1586657289] 'process raft request' (duration: 176.272575ms)","trace[1586657289] 'compare' (duration: 142.256146ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:24:31.069334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:24:30.750027Z","time spent":"319.228655ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:24:31.06935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.280916ms","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-07-14T23:24:31.069383Z","caller":"traceutil/trace.go:171","msg":"trace[1666834498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8631; }","duration":"230.365668ms","start":"2026-07-14T23:24:30.839008Z","end":"2026-07-14T23:24:31.069373Z","steps":["trace[1666834498] 'agreement among raft nodes before linearized reading' (duration: 230.202065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:24:31.069673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.080674ms","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-07-14T23:24:31.069797Z","caller":"traceutil/trace.go:171","msg":"trace[611223121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8631; }","duration":"207.277758ms","start":"2026-07-14T23:24:30.862496Z","end":"2026-07-14T23:24:31.069773Z","steps":["trace[611223121] 'agreement among raft nodes before linearized reading' (duration: 206.979671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:24:31.069847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.290974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T23:24:31.069886Z","caller":"traceutil/trace.go:171","msg":"trace[878777218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8631; }","duration":"201.386834ms","start":"2026-07-14T23:24:30.868489Z","end":"2026-07-14T23:24:31.069876Z","steps":["trace[878777218] 'agreement among raft nodes before linearized reading' (duration: 201.201601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:24:31.070013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.599049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T23:24:31.070042Z","caller":"traceutil/trace.go:171","msg":"trace[661657592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8631; }","duration":"201.65835ms","start":"2026-07-14T23:24:30.868373Z","end":"2026-07-14T23:24:31.070031Z","steps":["trace[661657592] 'agreement among raft nodes before linearized reading' (duration: 201.577069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:24:31.070064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.353194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:24:31.070091Z","caller":"traceutil/trace.go:171","msg":"trace[1913315695] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:8631; }","duration":"191.402605ms","start":"2026-07-14T23:24:30.878679Z","end":"2026-07-14T23:24:31.070082Z","steps":["trace[1913315695] 'agreement among raft nodes before linearized reading' (duration: 191.344754ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:24:32.125176Z","caller":"traceutil/trace.go:171","msg":"trace[360916485] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"167.61094ms","start":"2026-07-14T23:24:31.957547Z","end":"2026-07-14T23:24:32.125158Z","steps":["trace[360916485] 'process raft request' (duration: 167.475987ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:25:12.796961Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002475.wal"} {"level":"info","ts":"2026-07-14T23:26:25.722469Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"21b354698260dd82","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:26:25.728658Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-14T23:26:25.728764Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-14T23:28:45.440509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8292} {"level":"info","ts":"2026-07-14T23:28:45.523455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8292,"took":"80.933381ms","hash":2604136954,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":26050560,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-14T23:28:45.523509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2604136954,"revision":8292,"compact-revision":6101} {"level":"info","ts":"2026-07-14T23:33:45.44785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10783} {"level":"info","ts":"2026-07-14T23:33:45.52569Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10783,"took":"75.057387ms","hash":4056420281,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":27410432,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-14T23:33:45.525749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4056420281,"revision":10783,"compact-revision":8292} {"level":"info","ts":"2026-07-14T23:36:05.225899Z","caller":"traceutil/trace.go:171","msg":"trace[253631754] transaction","detail":"{read_only:false; response_revision:13851; number_of_response:1; }","duration":"103.553947ms","start":"2026-07-14T23:36:05.122316Z","end":"2026-07-14T23:36:05.22587Z","steps":["trace[253631754] 'process raft request' (duration: 78.573133ms)","trace[253631754] 'compare' (duration: 24.839082ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:36:05.232786Z","caller":"traceutil/trace.go:171","msg":"trace[1012477053] transaction","detail":"{read_only:false; response_revision:13852; number_of_response:1; }","duration":"110.033748ms","start":"2026-07-14T23:36:05.122718Z","end":"2026-07-14T23:36:05.232752Z","steps":["trace[1012477053] 'process raft request' (duration: 109.787514ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:36:05.232942Z","caller":"traceutil/trace.go:171","msg":"trace[934350553] transaction","detail":"{read_only:false; response_revision:13855; number_of_response:1; }","duration":"102.034697ms","start":"2026-07-14T23:36:05.130887Z","end":"2026-07-14T23:36:05.232921Z","steps":["trace[934350553] 'process raft request' (duration: 101.912116ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:36:05.233002Z","caller":"traceutil/trace.go:171","msg":"trace[437707978] transaction","detail":"{read_only:false; response_revision:13853; number_of_response:1; }","duration":"110.16685ms","start":"2026-07-14T23:36:05.122823Z","end":"2026-07-14T23:36:05.23299Z","steps":["trace[437707978] 'process raft request' (duration: 109.872455ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:36:05.232898Z","caller":"traceutil/trace.go:171","msg":"trace[1644438176] transaction","detail":"{read_only:false; response_revision:13854; number_of_response:1; }","duration":"103.770751ms","start":"2026-07-14T23:36:05.129083Z","end":"2026-07-14T23:36:05.232854Z","steps":["trace[1644438176] 'process raft request' (duration: 103.656379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:36:12.24493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.61882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5rsj7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-14T23:36:12.245095Z","caller":"traceutil/trace.go:171","msg":"trace[1380674289] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5rsj7; range_end:; response_count:1; response_revision:13904; }","duration":"106.853303ms","start":"2026-07-14T23:36:12.138213Z","end":"2026-07-14T23:36:12.245067Z","steps":["trace[1380674289] 'range keys from in-memory index tree' (duration: 106.342596ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:36:13.105859Z","caller":"traceutil/trace.go:171","msg":"trace[384462601] linearizableReadLoop","detail":"{readStateIndex:14387; appliedIndex:14386; }","duration":"162.47164ms","start":"2026-07-14T23:36:12.943358Z","end":"2026-07-14T23:36:13.105829Z","steps":["trace[384462601] 'read index received' (duration: 162.010944ms)","trace[384462601] 'applied index is now lower than readState.Index' (duration: 459.246µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:36:13.10596Z","caller":"traceutil/trace.go:171","msg":"trace[1185768326] transaction","detail":"{read_only:false; response_revision:13907; number_of_response:1; }","duration":"199.255517ms","start":"2026-07-14T23:36:12.906659Z","end":"2026-07-14T23:36:13.105915Z","steps":["trace[1185768326] 'process raft request' (duration: 198.75313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:36:13.106078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.695954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:36:13.106176Z","caller":"traceutil/trace.go:171","msg":"trace[1317712177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13907; }","duration":"162.862625ms","start":"2026-07-14T23:36:12.943292Z","end":"2026-07-14T23:36:13.106154Z","steps":["trace[1317712177] 'agreement among raft nodes before linearized reading' (duration: 162.660502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:36:13.350066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.900949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:36:13.350192Z","caller":"traceutil/trace.go:171","msg":"trace[342215641] transaction","detail":"{read_only:false; response_revision:13908; number_of_response:1; }","duration":"193.67095ms","start":"2026-07-14T23:36:13.156499Z","end":"2026-07-14T23:36:13.35017Z","steps":["trace[342215641] 'process raft request' (duration: 45.524208ms)","trace[342215641] 'compare' (duration: 147.730947ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:36:36.244515Z","caller":"traceutil/trace.go:171","msg":"trace[2103824357] transaction","detail":"{read_only:false; response_revision:14222; number_of_response:1; }","duration":"247.534584ms","start":"2026-07-14T23:36:35.996954Z","end":"2026-07-14T23:36:36.244488Z","steps":["trace[2103824357] 'process raft request' (duration: 247.351821ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:36:36.248208Z","caller":"traceutil/trace.go:171","msg":"trace[1134788965] transaction","detail":"{read_only:false; response_revision:14223; number_of_response:1; }","duration":"209.56565ms","start":"2026-07-14T23:36:36.038615Z","end":"2026-07-14T23:36:36.248181Z","steps":["trace[1134788965] 'process raft request' (duration: 208.903601ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:36:36.248373Z","caller":"traceutil/trace.go:171","msg":"trace[1606668334] transaction","detail":"{read_only:false; response_revision:14224; number_of_response:1; }","duration":"207.623763ms","start":"2026-07-14T23:36:36.040719Z","end":"2026-07-14T23:36:36.248343Z","steps":["trace[1606668334] 'process raft request' (duration: 206.951764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.342173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.727019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:37:56.342372Z","caller":"traceutil/trace.go:171","msg":"trace[1787678088] linearizableReadLoop","detail":"{readStateIndex:15518; appliedIndex:15516; }","duration":"295.29275ms","start":"2026-07-14T23:37:56.047061Z","end":"2026-07-14T23:37:56.342353Z","steps":["trace[1787678088] 'read index received' (duration: 120.266916ms)","trace[1787678088] 'applied index is now lower than readState.Index' (duration: 175.024334ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:37:56.342473Z","caller":"traceutil/trace.go:171","msg":"trace[2090010607] transaction","detail":"{read_only:false; response_revision:15002; number_of_response:1; }","duration":"377.309022ms","start":"2026-07-14T23:37:55.965152Z","end":"2026-07-14T23:37:56.342461Z","steps":["trace[2090010607] 'process raft request' (duration: 202.194096ms)","trace[2090010607] 'compare' (duration: 174.601687ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:37:56.342532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:37:55.965122Z","time spent":"377.377183ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:37:56.342805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.690857ms","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-07-14T23:37:56.342902Z","caller":"traceutil/trace.go:171","msg":"trace[217352370] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15003; }","duration":"295.863092ms","start":"2026-07-14T23:37:56.047017Z","end":"2026-07-14T23:37:56.34288Z","steps":["trace[217352370] 'agreement among raft nodes before linearized reading' (duration: 295.702518ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:56.342982Z","caller":"traceutil/trace.go:171","msg":"trace[2001630364] transaction","detail":"{read_only:false; response_revision:15003; number_of_response:1; }","duration":"377.563048ms","start":"2026-07-14T23:37:55.9654Z","end":"2026-07-14T23:37:56.342963Z","steps":["trace[2001630364] 'process raft request' (duration: 376.897154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.34287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.793339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-07-14T23:37:56.343023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.967524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:37:56.343065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:37:55.965384Z","time spent":"377.632899ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:37:56.343068Z","caller":"traceutil/trace.go:171","msg":"trace[375161241] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15003; }","duration":"296.044065ms","start":"2026-07-14T23:37:56.047008Z","end":"2026-07-14T23:37:56.343052Z","steps":["trace[375161241] 'agreement among raft nodes before linearized reading' (duration: 295.973284ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:56.343055Z","caller":"traceutil/trace.go:171","msg":"trace[1167962586] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15003; }","duration":"296.017285ms","start":"2026-07-14T23:37:56.047002Z","end":"2026-07-14T23:37:56.343019Z","steps":["trace[1167962586] 'agreement among raft nodes before linearized reading' (duration: 295.681718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.343109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.712919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-m58vb\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-07-14T23:37:56.343138Z","caller":"traceutil/trace.go:171","msg":"trace[287867101] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-m58vb; range_end:; response_count:1; response_revision:15003; }","duration":"235.7688ms","start":"2026-07-14T23:37:56.107359Z","end":"2026-07-14T23:37:56.343128Z","steps":["trace[287867101] 'agreement among raft nodes before linearized reading' (duration: 235.674228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.34313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.982302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-14T23:37:56.343146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.597514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-07-14T23:37:56.343189Z","caller":"traceutil/trace.go:171","msg":"trace[1799879468] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15003; }","duration":"274.058384ms","start":"2026-07-14T23:37:56.069117Z","end":"2026-07-14T23:37:56.343175Z","steps":["trace[1799879468] 'agreement among raft nodes before linearized reading' (duration: 273.8577ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:56.343214Z","caller":"traceutil/trace.go:171","msg":"trace[1658352106] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15003; }","duration":"274.732066ms","start":"2026-07-14T23:37:56.068462Z","end":"2026-07-14T23:37:56.343194Z","steps":["trace[1658352106] 'agreement among raft nodes before linearized reading' (duration: 274.522492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.343307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.38216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-14T23:37:56.343399Z","caller":"traceutil/trace.go:171","msg":"trace[1434889464] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15003; }","duration":"274.566084ms","start":"2026-07-14T23:37:56.068809Z","end":"2026-07-14T23:37:56.343375Z","steps":["trace[1434889464] 'agreement among raft nodes before linearized reading' (duration: 274.234207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.697149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.611146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-c8n4z\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-07-14T23:37:56.697234Z","caller":"traceutil/trace.go:171","msg":"trace[1121762345] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-c8n4z; range_end:; response_count:1; response_revision:15003; }","duration":"248.709988ms","start":"2026-07-14T23:37:56.448505Z","end":"2026-07-14T23:37:56.697215Z","steps":["trace[1121762345] 'range keys from in-memory index tree' (duration: 248.410862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.69755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.320073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-14T23:37:56.697589Z","caller":"traceutil/trace.go:171","msg":"trace[427432516] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15003; }","duration":"244.387864ms","start":"2026-07-14T23:37:56.453193Z","end":"2026-07-14T23:37:56.69758Z","steps":["trace[427432516] 'range keys from in-memory index tree' (duration: 244.206451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.697761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.209091ms","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-07-14T23:37:56.697795Z","caller":"traceutil/trace.go:171","msg":"trace[200117197] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15003; }","duration":"244.259682ms","start":"2026-07-14T23:37:56.453527Z","end":"2026-07-14T23:37:56.697787Z","steps":["trace[200117197] 'range keys from in-memory index tree' (duration: 244.13462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.69818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.445415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-14T23:37:56.698351Z","caller":"traceutil/trace.go:171","msg":"trace[832459248] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15003; }","duration":"244.66022ms","start":"2026-07-14T23:37:56.453666Z","end":"2026-07-14T23:37:56.698327Z","steps":["trace[832459248] 'range keys from in-memory index tree' (duration: 244.224431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.699089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.820752ms","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-07-14T23:37:56.699209Z","caller":"traceutil/trace.go:171","msg":"trace[1974729946] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15003; }","duration":"126.961654ms","start":"2026-07-14T23:37:56.572233Z","end":"2026-07-14T23:37:56.699195Z","steps":["trace[1974729946] 'range keys from in-memory index tree' (duration: 126.73256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:56.699453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.201969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-14T23:37:56.69993Z","caller":"traceutil/trace.go:171","msg":"trace[1947990347] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15003; }","duration":"127.732249ms","start":"2026-07-14T23:37:56.572184Z","end":"2026-07-14T23:37:56.699917Z","steps":["trace[1947990347] 'range keys from in-memory index tree' (duration: 127.024476ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:56.699635Z","caller":"traceutil/trace.go:171","msg":"trace[1114180834] linearizableReadLoop","detail":"{readStateIndex:15519; appliedIndex:15518; }","duration":"125.101578ms","start":"2026-07-14T23:37:56.574517Z","end":"2026-07-14T23:37:56.699619Z","steps":["trace[1114180834] 'read index received' (duration: 115.886862ms)","trace[1114180834] 'applied index is now lower than readState.Index' (duration: 9.212986ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:37:56.69978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.268962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-14T23:37:56.700195Z","caller":"traceutil/trace.go:171","msg":"trace[1846780676] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15004; }","duration":"125.6996ms","start":"2026-07-14T23:37:56.57444Z","end":"2026-07-14T23:37:56.70014Z","steps":["trace[1846780676] 'agreement among raft nodes before linearized reading' (duration: 125.226951ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:56.699792Z","caller":"traceutil/trace.go:171","msg":"trace[329991714] transaction","detail":"{read_only:false; response_revision:15004; number_of_response:1; }","duration":"126.25257ms","start":"2026-07-14T23:37:56.573503Z","end":"2026-07-14T23:37:56.699756Z","steps":["trace[329991714] 'process raft request' (duration: 116.89077ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:56.712103Z","caller":"traceutil/trace.go:171","msg":"trace[462143068] transaction","detail":"{read_only:false; response_revision:15005; number_of_response:1; }","duration":"137.054877ms","start":"2026-07-14T23:37:56.575028Z","end":"2026-07-14T23:37:56.712083Z","steps":["trace[462143068] 'process raft request' (duration: 136.761632ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:38:45.455914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12854} {"level":"info","ts":"2026-07-14T23:38:45.532764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12854,"took":"72.785055ms","hash":3968630770,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":30179328,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-14T23:38:45.532901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3968630770,"revision":12854,"compact-revision":10783} {"level":"info","ts":"2026-07-14T23:43:45.465977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15316} {"level":"info","ts":"2026-07-14T23:43:45.570811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15316,"took":"100.428801ms","hash":2641964089,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-14T23:43:45.570889Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2641964089,"revision":15316,"compact-revision":12854} {"level":"info","ts":"2026-07-14T23:44:02.085783Z","caller":"traceutil/trace.go:171","msg":"trace[277261553] transaction","detail":"{read_only:false; response_revision:17680; number_of_response:1; }","duration":"134.287699ms","start":"2026-07-14T23:44:01.951473Z","end":"2026-07-14T23:44:02.085761Z","steps":["trace[277261553] 'process raft request' (duration: 134.140466ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:44:02.714854Z","caller":"traceutil/trace.go:171","msg":"trace[1114385428] transaction","detail":"{read_only:false; response_revision:17686; number_of_response:1; }","duration":"198.640482ms","start":"2026-07-14T23:44:02.516172Z","end":"2026-07-14T23:44:02.714813Z","steps":["trace[1114385428] 'process raft request' (duration: 198.327166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:03.118089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.897535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-m58vb\" ","response":"range_response_count:1 size:9800"} {"level":"warn","ts":"2026-07-14T23:44:03.118148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.892443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:44:03.118183Z","caller":"traceutil/trace.go:171","msg":"trace[1502722017] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-m58vb; range_end:; response_count:1; response_revision:17686; }","duration":"322.059558ms","start":"2026-07-14T23:44:02.796103Z","end":"2026-07-14T23:44:03.118162Z","steps":["trace[1502722017] 'range keys from in-memory index tree' (duration: 321.683152ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:44:03.118209Z","caller":"traceutil/trace.go:171","msg":"trace[1863000794] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:17686; }","duration":"269.036696ms","start":"2026-07-14T23:44:02.849155Z","end":"2026-07-14T23:44:03.118192Z","steps":["trace[1863000794] 'range keys from in-memory index tree' (duration: 268.781791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:03.118221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:02.796075Z","time spent":"322.13864ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-m58vb\" "} {"level":"info","ts":"2026-07-14T23:44:03.260961Z","caller":"traceutil/trace.go:171","msg":"trace[871371215] linearizableReadLoop","detail":"{readStateIndex:18292; appliedIndex:18291; }","duration":"135.209046ms","start":"2026-07-14T23:44:03.125724Z","end":"2026-07-14T23:44:03.260933Z","steps":["trace[871371215] 'read index received' (duration: 134.937851ms)","trace[871371215] 'applied index is now lower than readState.Index' (duration: 269.545µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:44:03.260996Z","caller":"traceutil/trace.go:171","msg":"trace[860668559] transaction","detail":"{read_only:false; response_revision:17687; number_of_response:1; }","duration":"136.241095ms","start":"2026-07-14T23:44:03.124713Z","end":"2026-07-14T23:44:03.260955Z","steps":["trace[860668559] 'process raft request' (duration: 136.032492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:03.26116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.40625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:44:03.261203Z","caller":"traceutil/trace.go:171","msg":"trace[269349467] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:17687; }","duration":"135.507922ms","start":"2026-07-14T23:44:03.125678Z","end":"2026-07-14T23:44:03.261186Z","steps":["trace[269349467] 'agreement among raft nodes before linearized reading' (duration: 135.381789ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:25.045751Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"21b354698260dd82","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:47:25.051663Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-14T23:47:25.051903Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-14T23:48:45.473086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17555} {"level":"info","ts":"2026-07-14T23:48:45.571005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17555,"took":"93.672572ms","hash":2686541042,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":34439168,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-14T23:48:45.571105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2686541042,"revision":17555,"compact-revision":15316} {"level":"info","ts":"2026-07-14T23:49:36.393609Z","caller":"traceutil/trace.go:171","msg":"trace[705473409] transaction","detail":"{read_only:false; response_revision:20301; number_of_response:1; }","duration":"124.680543ms","start":"2026-07-14T23:49:36.268891Z","end":"2026-07-14T23:49:36.393572Z","steps":["trace[705473409] 'process raft request' (duration: 124.488549ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:49:36.419044Z","caller":"traceutil/trace.go:171","msg":"trace[5633679] transaction","detail":"{read_only:false; response_revision:20302; number_of_response:1; }","duration":"146.138166ms","start":"2026-07-14T23:49:36.272878Z","end":"2026-07-14T23:49:36.419016Z","steps":["trace[5633679] 'process raft request' (duration: 145.963733ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:49:47.024659Z","caller":"traceutil/trace.go:171","msg":"trace[53114870] transaction","detail":"{read_only:false; response_revision:20354; number_of_response:1; }","duration":"134.641755ms","start":"2026-07-14T23:49:46.889959Z","end":"2026-07-14T23:49:47.024601Z","steps":["trace[53114870] 'process raft request' (duration: 134.36788ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:49:52.981882Z","caller":"traceutil/trace.go:171","msg":"trace[2028497649] transaction","detail":"{read_only:false; response_revision:20387; number_of_response:1; }","duration":"413.601422ms","start":"2026-07-14T23:49:52.56824Z","end":"2026-07-14T23:49:52.981842Z","steps":["trace[2028497649] 'process raft request' (duration: 413.393229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:49:52.982103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:49:52.568212Z","time spent":"413.771386ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:49:52.985806Z","caller":"traceutil/trace.go:171","msg":"trace[1288655624] transaction","detail":"{read_only:false; response_revision:20388; number_of_response:1; }","duration":"165.482241ms","start":"2026-07-14T23:49:52.820291Z","end":"2026-07-14T23:49:52.985773Z","steps":["trace[1288655624] 'process raft request' (duration: 164.948061ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:49:53.175368Z","caller":"traceutil/trace.go:171","msg":"trace[1420561725] transaction","detail":"{read_only:false; response_revision:20389; number_of_response:1; }","duration":"123.787435ms","start":"2026-07-14T23:49:53.051514Z","end":"2026-07-14T23:49:53.175301Z","steps":["trace[1420561725] 'process raft request' (duration: 74.597982ms)","trace[1420561725] 'compare' (duration: 48.963989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:49:53.415925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.352129ms","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-07-14T23:49:53.416014Z","caller":"traceutil/trace.go:171","msg":"trace[249897697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20389; }","duration":"128.505162ms","start":"2026-07-14T23:49:53.287491Z","end":"2026-07-14T23:49:53.415996Z","steps":["trace[249897697] 'range keys from in-memory index tree' (duration: 128.228906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:49:53.416027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.699561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:49:53.416189Z","caller":"traceutil/trace.go:171","msg":"trace[1491560917] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:20389; }","duration":"131.972556ms","start":"2026-07-14T23:49:53.284186Z","end":"2026-07-14T23:49:53.416159Z","steps":["trace[1491560917] 'count revisions from in-memory index tree' (duration: 131.496098ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:45.478243Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20021} {"level":"info","ts":"2026-07-14T23:53:45.60176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20021,"took":"117.808239ms","hash":2846120103,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":41308160,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-07-14T23:53:45.601848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2846120103,"revision":20021,"compact-revision":17555} {"level":"info","ts":"2026-07-14T23:53:48.753622Z","caller":"traceutil/trace.go:171","msg":"trace[928005781] transaction","detail":"{read_only:false; response_revision:22843; number_of_response:1; }","duration":"121.422125ms","start":"2026-07-14T23:53:48.632174Z","end":"2026-07-14T23:53:48.753596Z","steps":["trace[928005781] 'process raft request' (duration: 121.252862ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:48.759814Z","caller":"traceutil/trace.go:171","msg":"trace[1467891797] transaction","detail":"{read_only:false; response_revision:22844; number_of_response:1; }","duration":"125.544178ms","start":"2026-07-14T23:53:48.634255Z","end":"2026-07-14T23:53:48.759799Z","steps":["trace[1467891797] 'process raft request' (duration: 125.143141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:49.082251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.057647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18174"} {"level":"info","ts":"2026-07-14T23:53:49.082333Z","caller":"traceutil/trace.go:171","msg":"trace[550385668] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:22844; }","duration":"230.167208ms","start":"2026-07-14T23:53:48.852147Z","end":"2026-07-14T23:53:49.082315Z","steps":["trace[550385668] 'range keys from in-memory index tree' (duration: 229.904055ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:49.087137Z","caller":"traceutil/trace.go:171","msg":"trace[1697639376] linearizableReadLoop","detail":"{readStateIndex:23616; appliedIndex:23615; }","duration":"212.361079ms","start":"2026-07-14T23:53:48.874726Z","end":"2026-07-14T23:53:49.087087Z","steps":["trace[1697639376] 'read index received' (duration: 211.999142ms)","trace[1697639376] 'applied index is now lower than readState.Index' (duration: 360.107µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:49.087317Z","caller":"traceutil/trace.go:171","msg":"trace[1082324012] transaction","detail":"{read_only:false; response_revision:22845; number_of_response:1; }","duration":"235.005246ms","start":"2026-07-14T23:53:48.852294Z","end":"2026-07-14T23:53:49.087299Z","steps":["trace[1082324012] 'process raft request' (duration: 234.564509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:49.087352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.584053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:49.087405Z","caller":"traceutil/trace.go:171","msg":"trace[1703626120] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:22845; }","duration":"212.684835ms","start":"2026-07-14T23:53:48.874702Z","end":"2026-07-14T23:53:49.087387Z","steps":["trace[1703626120] 'agreement among raft nodes before linearized reading' (duration: 212.559533ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:55:28.641324Z","caller":"traceutil/trace.go:171","msg":"trace[405263369] transaction","detail":"{read_only:false; response_revision:23914; number_of_response:1; }","duration":"105.11384ms","start":"2026-07-14T23:55:28.536161Z","end":"2026-07-14T23:55:28.641275Z","steps":["trace[405263369] 'process raft request' (duration: 51.969725ms)","trace[405263369] 'compare' (duration: 52.575254ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:35.407901Z","caller":"traceutil/trace.go:171","msg":"trace[664273627] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"115.382453ms","start":"2026-07-14T23:57:35.292486Z","end":"2026-07-14T23:57:35.407869Z","steps":["trace[664273627] 'process raft request' (duration: 115.078697ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:35.712141Z","caller":"traceutil/trace.go:171","msg":"trace[876587763] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"175.166568ms","start":"2026-07-14T23:57:35.536928Z","end":"2026-07-14T23:57:35.712094Z","steps":["trace[876587763] 'process raft request' (duration: 155.712315ms)","trace[876587763] 'compare' (duration: 18.499276ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:35.980262Z","caller":"traceutil/trace.go:171","msg":"trace[898241029] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"312.373977ms","start":"2026-07-14T23:57:35.667856Z","end":"2026-07-14T23:57:35.98023Z","steps":["trace[898241029] 'process raft request' (duration: 287.810149ms)","trace[898241029] 'compare' (duration: 24.184882ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:35.980319Z","caller":"traceutil/trace.go:171","msg":"trace[1959472555] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"311.144344ms","start":"2026-07-14T23:57:35.669137Z","end":"2026-07-14T23:57:35.980281Z","steps":["trace[1959472555] 'process raft request' (duration: 311.010552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:35.980423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:35.667824Z","time spent":"312.50969ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:35.980512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:35.669125Z","time spent":"311.294187ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:57:36.438543Z","caller":"traceutil/trace.go:171","msg":"trace[1922766963] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"134.801554ms","start":"2026-07-14T23:57:36.303699Z","end":"2026-07-14T23:57:36.4385Z","steps":["trace[1922766963] 'process raft request' (duration: 133.349248ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:36.993945Z","caller":"traceutil/trace.go:171","msg":"trace[2146639834] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"109.795989ms","start":"2026-07-14T23:57:36.884116Z","end":"2026-07-14T23:57:36.993912Z","steps":["trace[2146639834] 'process raft request' (duration: 109.530493ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:36.994049Z","caller":"traceutil/trace.go:171","msg":"trace[1400653009] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"109.772759ms","start":"2026-07-14T23:57:36.884253Z","end":"2026-07-14T23:57:36.994026Z","steps":["trace[1400653009] 'process raft request' (duration: 109.449382ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:36.994296Z","caller":"traceutil/trace.go:171","msg":"trace[486392564] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"123.756849ms","start":"2026-07-14T23:57:36.870526Z","end":"2026-07-14T23:57:36.994283Z","steps":["trace[486392564] 'process raft request' (duration: 122.896793ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:37.155398Z","caller":"traceutil/trace.go:171","msg":"trace[879906068] linearizableReadLoop","detail":"{readStateIndex:25953; appliedIndex:25951; }","duration":"161.617715ms","start":"2026-07-14T23:57:36.993747Z","end":"2026-07-14T23:57:37.155364Z","steps":["trace[879906068] 'read index received' (duration: 161.284369ms)","trace[879906068] 'applied index is now lower than readState.Index' (duration: 332.136µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:37.155498Z","caller":"traceutil/trace.go:171","msg":"trace[589826523] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"169.763136ms","start":"2026-07-14T23:57:36.985724Z","end":"2026-07-14T23:57:37.155487Z","steps":["trace[589826523] 'process raft request' (duration: 169.581033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:37.155703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.219746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18c24d579b1555ae\" ","response":"range_response_count:1 size:722"} {"level":"warn","ts":"2026-07-14T23:57:37.155742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.102506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:37.155773Z","caller":"traceutil/trace.go:171","msg":"trace[444937675] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18c24d579b1555ae; range_end:; response_count:1; response_revision:25123; }","duration":"199.345849ms","start":"2026-07-14T23:57:36.95641Z","end":"2026-07-14T23:57:37.155756Z","steps":["trace[444937675] 'agreement among raft nodes before linearized reading' (duration: 199.109915ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:37.155781Z","caller":"traceutil/trace.go:171","msg":"trace[458334578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25123; }","duration":"160.195838ms","start":"2026-07-14T23:57:36.995569Z","end":"2026-07-14T23:57:37.155764Z","steps":["trace[458334578] 'agreement among raft nodes before linearized reading' (duration: 160.127697ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:37.155433Z","caller":"traceutil/trace.go:171","msg":"trace[243258874] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"169.760236ms","start":"2026-07-14T23:57:36.985644Z","end":"2026-07-14T23:57:37.155405Z","steps":["trace[243258874] 'process raft request' (duration: 169.462111ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:37.330995Z","caller":"traceutil/trace.go:171","msg":"trace[1363574095] linearizableReadLoop","detail":"{readStateIndex:25954; appliedIndex:25953; }","duration":"111.172114ms","start":"2026-07-14T23:57:37.219795Z","end":"2026-07-14T23:57:37.330968Z","steps":["trace[1363574095] 'read index received' (duration: 87.92714ms)","trace[1363574095] 'applied index is now lower than readState.Index' (duration: 23.239654ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:37.331067Z","caller":"traceutil/trace.go:171","msg":"trace[1091937509] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"164.451398ms","start":"2026-07-14T23:57:37.166554Z","end":"2026-07-14T23:57:37.331006Z","steps":["trace[1091937509] 'process raft request' (duration: 141.258616ms)","trace[1091937509] 'compare' (duration: 22.620502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:37.331398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.576122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-14T23:57:37.331447Z","caller":"traceutil/trace.go:171","msg":"trace[364814760] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25124; }","duration":"111.694173ms","start":"2026-07-14T23:57:37.219741Z","end":"2026-07-14T23:57:37.331435Z","steps":["trace[364814760] 'agreement among raft nodes before linearized reading' (duration: 111.378628ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:37.605756Z","caller":"traceutil/trace.go:171","msg":"trace[83381929] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"268.465538ms","start":"2026-07-14T23:57:37.337257Z","end":"2026-07-14T23:57:37.605722Z","steps":["trace[83381929] 'process raft request' (duration: 268.215263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:37.877777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.223525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:57:37.87805Z","caller":"traceutil/trace.go:171","msg":"trace[315877697] linearizableReadLoop","detail":"{readStateIndex:25957; appliedIndex:25954; }","duration":"412.094437ms","start":"2026-07-14T23:57:37.465934Z","end":"2026-07-14T23:57:37.878028Z","steps":["trace[315877697] 'read index received' (duration: 139.595444ms)","trace[315877697] 'applied index is now lower than readState.Index' (duration: 272.497703ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:37.878167Z","caller":"traceutil/trace.go:171","msg":"trace[573555889] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"463.562707ms","start":"2026-07-14T23:57:37.414592Z","end":"2026-07-14T23:57:37.878155Z","steps":["trace[573555889] 'process raft request' (duration: 463.309902ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:37.878174Z","caller":"traceutil/trace.go:171","msg":"trace[1343566690] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"540.481472ms","start":"2026-07-14T23:57:37.337655Z","end":"2026-07-14T23:57:37.878136Z","steps":["trace[1343566690] 'process raft request' (duration: 406.782318ms)","trace[1343566690] 'compare' (duration: 133.074273ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:37.878247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:37.414576Z","time spent":"463.626877ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:37.878372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:37.337637Z","time spent":"540.623254ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:37.878471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.512565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:37.878554Z","caller":"traceutil/trace.go:171","msg":"trace[1850916438] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25127; }","duration":"412.618396ms","start":"2026-07-14T23:57:37.465914Z","end":"2026-07-14T23:57:37.878532Z","steps":["trace[1850916438] 'agreement among raft nodes before linearized reading' (duration: 412.472554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:37.878606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:37.465905Z","time spent":"412.686828ms","remote":"127.0.0.1:35758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:57:37.878643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.329426ms","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-07-14T23:57:37.878696Z","caller":"traceutil/trace.go:171","msg":"trace[1794114619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25128; }","duration":"371.441198ms","start":"2026-07-14T23:57:37.507243Z","end":"2026-07-14T23:57:37.878685Z","steps":["trace[1794114619] 'agreement among raft nodes before linearized reading' (duration: 371.240895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:37.878723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:37.50723Z","time spent":"371.487489ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T23:57:37.878898Z","caller":"traceutil/trace.go:171","msg":"trace[987345179] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"208.114232ms","start":"2026-07-14T23:57:37.670769Z","end":"2026-07-14T23:57:37.878884Z","steps":["trace[987345179] 'process raft request' (duration: 207.222036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:37.878936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.445051ms","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-07-14T23:57:37.878956Z","caller":"traceutil/trace.go:171","msg":"trace[339935250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25128; }","duration":"154.513672ms","start":"2026-07-14T23:57:37.724437Z","end":"2026-07-14T23:57:37.87895Z","steps":["trace[339935250] 'agreement among raft nodes before linearized reading' (duration: 154.445521ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:38.201448Z","caller":"traceutil/trace.go:171","msg":"trace[92461938] linearizableReadLoop","detail":"{readStateIndex:25960; appliedIndex:25959; }","duration":"258.829778ms","start":"2026-07-14T23:57:37.942589Z","end":"2026-07-14T23:57:38.201419Z","steps":["trace[92461938] 'read index received' (duration: 258.647674ms)","trace[92461938] 'applied index is now lower than readState.Index' (duration: 180.364µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:38.201595Z","caller":"traceutil/trace.go:171","msg":"trace[1918640288] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"316.408992ms","start":"2026-07-14T23:57:37.885168Z","end":"2026-07-14T23:57:38.201577Z","steps":["trace[1918640288] 'process raft request' (duration: 315.991494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:38.201743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:37.885132Z","time spent":"316.500524ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:38.201885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.294407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:38.201996Z","caller":"traceutil/trace.go:171","msg":"trace[1473213654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25130; }","duration":"259.46945ms","start":"2026-07-14T23:57:37.9425Z","end":"2026-07-14T23:57:38.201969Z","steps":["trace[1473213654] 'agreement among raft nodes before linearized reading' (duration: 259.264167ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:38.365237Z","caller":"traceutil/trace.go:171","msg":"trace[27257440] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"378.329837ms","start":"2026-07-14T23:57:37.986863Z","end":"2026-07-14T23:57:38.365193Z","steps":["trace[27257440] 'process raft request' (duration: 323.545284ms)","trace[27257440] 'compare' (duration: 54.498798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:38.365476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:37.986835Z","time spent":"378.509991ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:57:38.365597Z","caller":"traceutil/trace.go:171","msg":"trace[329361592] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"378.689484ms","start":"2026-07-14T23:57:37.986863Z","end":"2026-07-14T23:57:38.365553Z","steps":["trace[329361592] 'process raft request' (duration: 378.273366ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:38.365652Z","caller":"traceutil/trace.go:171","msg":"trace[1164094122] linearizableReadLoop","detail":"{readStateIndex:25963; appliedIndex:25960; }","duration":"164.081281ms","start":"2026-07-14T23:57:38.201534Z","end":"2026-07-14T23:57:38.365616Z","steps":["trace[1164094122] 'read index received' (duration: 108.780779ms)","trace[1164094122] 'applied index is now lower than readState.Index' (duration: 55.299112ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:38.365757Z","caller":"traceutil/trace.go:171","msg":"trace[74326138] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"186.837685ms","start":"2026-07-14T23:57:38.178905Z","end":"2026-07-14T23:57:38.365743Z","steps":["trace[74326138] 'process raft request' (duration: 186.53774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:38.36581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:37.986835Z","time spent":"378.855667ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:38.365918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.465476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"warn","ts":"2026-07-14T23:57:38.365917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.39887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:38.365963Z","caller":"traceutil/trace.go:171","msg":"trace[303811270] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25133; }","duration":"173.555898ms","start":"2026-07-14T23:57:38.192392Z","end":"2026-07-14T23:57:38.365948Z","steps":["trace[303811270] 'agreement among raft nodes before linearized reading' (duration: 173.382815ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:38.365965Z","caller":"traceutil/trace.go:171","msg":"trace[2072581660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25133; }","duration":"162.497091ms","start":"2026-07-14T23:57:38.203458Z","end":"2026-07-14T23:57:38.365955Z","steps":["trace[2072581660] 'agreement among raft nodes before linearized reading' (duration: 162.413119ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:38.936856Z","caller":"traceutil/trace.go:171","msg":"trace[188633769] linearizableReadLoop","detail":"{readStateIndex:25967; appliedIndex:25963; }","duration":"366.583568ms","start":"2026-07-14T23:57:38.570249Z","end":"2026-07-14T23:57:38.936833Z","steps":["trace[188633769] 'read index received' (duration: 90.668222ms)","trace[188633769] 'applied index is now lower than readState.Index' (duration: 275.914026ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:38.936917Z","caller":"traceutil/trace.go:171","msg":"trace[504947008] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"403.12458ms","start":"2026-07-14T23:57:38.533752Z","end":"2026-07-14T23:57:38.936876Z","steps":["trace[504947008] 'process raft request' (duration: 402.978187ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:38.936979Z","caller":"traceutil/trace.go:171","msg":"trace[1897481257] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"403.08088ms","start":"2026-07-14T23:57:38.533885Z","end":"2026-07-14T23:57:38.936966Z","steps":["trace[1897481257] 'process raft request' (duration: 402.903576ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:38.937107Z","caller":"traceutil/trace.go:171","msg":"trace[405167415] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"403.322544ms","start":"2026-07-14T23:57:38.533747Z","end":"2026-07-14T23:57:38.93707Z","steps":["trace[405167415] 'process raft request' (duration: 402.623471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:38.937147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:38.53385Z","time spent":"403.208461ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:38.937155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:38.533719Z","time spent":"403.274393ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:38.937311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:38.533719Z","time spent":"403.461947ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:38.93731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.020897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:38.937428Z","caller":"traceutil/trace.go:171","msg":"trace[891220997] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25136; }","duration":"367.19872ms","start":"2026-07-14T23:57:38.570204Z","end":"2026-07-14T23:57:38.937402Z","steps":["trace[891220997] 'agreement among raft nodes before linearized reading' (duration: 366.949425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:38.937493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:38.57019Z","time spent":"367.289451ms","remote":"127.0.0.1:48848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:57:38.976884Z","caller":"traceutil/trace.go:171","msg":"trace[358303890] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"314.611438ms","start":"2026-07-14T23:57:38.662233Z","end":"2026-07-14T23:57:38.976845Z","steps":["trace[358303890] 'process raft request' (duration: 313.145902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:38.97693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.976998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:38.976983Z","caller":"traceutil/trace.go:171","msg":"trace[2081802341] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25137; }","duration":"335.089141ms","start":"2026-07-14T23:57:38.641876Z","end":"2026-07-14T23:57:38.976965Z","steps":["trace[2081802341] 'agreement among raft nodes before linearized reading' (duration: 334.997359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:38.976911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.439962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-14T23:57:38.977028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:38.641843Z","time spent":"335.177892ms","remote":"127.0.0.1:46742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:57:38.97697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.166513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:38.977047Z","caller":"traceutil/trace.go:171","msg":"trace[715129461] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25137; }","duration":"339.627025ms","start":"2026-07-14T23:57:38.637409Z","end":"2026-07-14T23:57:38.977036Z","steps":["trace[715129461] 'agreement among raft nodes before linearized reading' (duration: 339.415131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:38.977074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:38.637386Z","time spent":"339.680526ms","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":14,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:57:38.977097Z","caller":"traceutil/trace.go:171","msg":"trace[605621108] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25137; }","duration":"282.326676ms","start":"2026-07-14T23:57:38.694732Z","end":"2026-07-14T23:57:38.977058Z","steps":["trace[605621108] 'agreement among raft nodes before linearized reading' (duration: 282.124653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:38.977201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:38.662205Z","time spent":"314.774322ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:57:39.160014Z","caller":"traceutil/trace.go:171","msg":"trace[623422047] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"161.188687ms","start":"2026-07-14T23:57:38.998781Z","end":"2026-07-14T23:57:39.15997Z","steps":["trace[623422047] 'process raft request' (duration: 140.053372ms)","trace[623422047] 'compare' (duration: 20.819869ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:39.160187Z","caller":"traceutil/trace.go:171","msg":"trace[796160555] linearizableReadLoop","detail":"{readStateIndex:25970; appliedIndex:25968; }","duration":"103.438409ms","start":"2026-07-14T23:57:39.056721Z","end":"2026-07-14T23:57:39.160159Z","steps":["trace[796160555] 'read index received' (duration: 82.134942ms)","trace[796160555] 'applied index is now lower than readState.Index' (duration: 21.302377ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:39.160822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.087602ms","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-07-14T23:57:39.160757Z","caller":"traceutil/trace.go:171","msg":"trace[1878025617] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"159.77623ms","start":"2026-07-14T23:57:39.000936Z","end":"2026-07-14T23:57:39.160712Z","steps":["trace[1878025617] 'process raft request' (duration: 158.941205ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:39.160891Z","caller":"traceutil/trace.go:171","msg":"trace[223091867] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25139; }","duration":"104.237504ms","start":"2026-07-14T23:57:39.056638Z","end":"2026-07-14T23:57:39.160875Z","steps":["trace[223091867] 'agreement among raft nodes before linearized reading' (duration: 103.628373ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:39.476225Z","caller":"traceutil/trace.go:171","msg":"trace[338152201] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25970; }","duration":"310.95015ms","start":"2026-07-14T23:57:39.165255Z","end":"2026-07-14T23:57:39.476205Z","steps":["trace[338152201] 'read index received' (duration: 30.846515ms)","trace[338152201] 'applied index is now lower than readState.Index' (duration: 280.102145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:39.476408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.132333ms","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-07-14T23:57:39.476437Z","caller":"traceutil/trace.go:171","msg":"trace[304600912] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25141; }","duration":"311.194805ms","start":"2026-07-14T23:57:39.165233Z","end":"2026-07-14T23:57:39.476428Z","steps":["trace[304600912] 'agreement among raft nodes before linearized reading' (duration: 311.045512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:39.47664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:39.165225Z","time spent":"311.408179ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-14T23:57:39.476426Z","caller":"traceutil/trace.go:171","msg":"trace[1928721774] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"312.140963ms","start":"2026-07-14T23:57:39.164237Z","end":"2026-07-14T23:57:39.476378Z","steps":["trace[1928721774] 'process raft request' (duration: 311.795557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:39.476828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:39.164215Z","time spent":"312.487639ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:39.791742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.378312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:57:39.792075Z","caller":"traceutil/trace.go:171","msg":"trace[1115344354] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"172.744842ms","start":"2026-07-14T23:57:39.619301Z","end":"2026-07-14T23:57:39.792046Z","steps":["trace[1115344354] 'process raft request' (duration: 49.927812ms)","trace[1115344354] 'compare' (duration: 122.166268ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:40.082098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.703677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:57:40.082212Z","caller":"traceutil/trace.go:171","msg":"trace[1845829202] linearizableReadLoop","detail":"{readStateIndex:25974; appliedIndex:25973; }","duration":"152.23911ms","start":"2026-07-14T23:57:39.929955Z","end":"2026-07-14T23:57:40.082194Z","steps":["trace[1845829202] 'read index received' (duration: 20.044204ms)","trace[1845829202] 'applied index is now lower than readState.Index' (duration: 132.193856ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:40.082364Z","caller":"traceutil/trace.go:171","msg":"trace[2112888194] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"197.418562ms","start":"2026-07-14T23:57:39.884886Z","end":"2026-07-14T23:57:40.082305Z","steps":["trace[2112888194] 'process raft request' (duration: 65.142914ms)","trace[2112888194] 'compare' (duration: 131.546295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:40.082432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.489104ms","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-07-14T23:57:40.082464Z","caller":"traceutil/trace.go:171","msg":"trace[501060800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25143; }","duration":"152.563536ms","start":"2026-07-14T23:57:39.929891Z","end":"2026-07-14T23:57:40.082454Z","steps":["trace[501060800] 'agreement among raft nodes before linearized reading' (duration: 152.389122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:40.082692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.977443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:40.082775Z","caller":"traceutil/trace.go:171","msg":"trace[279367453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25143; }","duration":"139.146486ms","start":"2026-07-14T23:57:39.94361Z","end":"2026-07-14T23:57:40.082756Z","steps":["trace[279367453] 'agreement among raft nodes before linearized reading' (duration: 138.918352ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:40.245342Z","caller":"traceutil/trace.go:171","msg":"trace[1273205610] linearizableReadLoop","detail":"{readStateIndex:25976; appliedIndex:25975; }","duration":"125.809777ms","start":"2026-07-14T23:57:40.119501Z","end":"2026-07-14T23:57:40.245311Z","steps":["trace[1273205610] 'read index received' (duration: 120.260324ms)","trace[1273205610] 'applied index is now lower than readState.Index' (duration: 5.548203ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:40.245484Z","caller":"traceutil/trace.go:171","msg":"trace[1118171359] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"146.89124ms","start":"2026-07-14T23:57:40.09858Z","end":"2026-07-14T23:57:40.245471Z","steps":["trace[1118171359] 'process raft request' (duration: 141.280356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:40.245766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.235005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-07-14T23:57:40.245815Z","caller":"traceutil/trace.go:171","msg":"trace[292921091] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:25145; }","duration":"126.307596ms","start":"2026-07-14T23:57:40.119492Z","end":"2026-07-14T23:57:40.2458Z","steps":["trace[292921091] 'agreement among raft nodes before linearized reading' (duration: 126.121203ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:40.648485Z","caller":"traceutil/trace.go:171","msg":"trace[116089026] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"276.551999ms","start":"2026-07-14T23:57:40.371905Z","end":"2026-07-14T23:57:40.648457Z","steps":["trace[116089026] 'process raft request' (duration: 276.345995ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:40.686952Z","caller":"traceutil/trace.go:171","msg":"trace[1173775608] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"314.089228ms","start":"2026-07-14T23:57:40.372835Z","end":"2026-07-14T23:57:40.686924Z","steps":["trace[1173775608] 'process raft request' (duration: 314.033877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:40.687116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:40.372829Z","time spent":"314.202341ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:57:40.687564Z","caller":"traceutil/trace.go:171","msg":"trace[1028590040] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"314.765981ms","start":"2026-07-14T23:57:40.372784Z","end":"2026-07-14T23:57:40.68755Z","steps":["trace[1028590040] 'process raft request' (duration: 300.678458ms)","trace[1028590040] 'compare' (duration: 13.278228ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:40.687634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:40.372776Z","time spent":"314.824193ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:41.212036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.473195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:41.21214Z","caller":"traceutil/trace.go:171","msg":"trace[1903100381] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:25152; }","duration":"121.620588ms","start":"2026-07-14T23:57:41.090495Z","end":"2026-07-14T23:57:41.212116Z","steps":["trace[1903100381] 'count revisions from in-memory index tree' (duration: 121.364284ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:50.878679Z","caller":"traceutil/trace.go:171","msg":"trace[1499791051] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"139.096955ms","start":"2026-07-14T23:57:50.739546Z","end":"2026-07-14T23:57:50.878643Z","steps":["trace[1499791051] 'process raft request' (duration: 136.592908ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:51.000418Z","caller":"traceutil/trace.go:171","msg":"trace[712395102] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"254.590198ms","start":"2026-07-14T23:57:50.745795Z","end":"2026-07-14T23:57:51.000385Z","steps":["trace[712395102] 'process raft request' (duration: 253.682502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:54.549136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.94372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:57:54.549311Z","caller":"traceutil/trace.go:171","msg":"trace[1036511663] linearizableReadLoop","detail":"{readStateIndex:26080; appliedIndex:26079; }","duration":"192.193764ms","start":"2026-07-14T23:57:54.357081Z","end":"2026-07-14T23:57:54.549275Z","steps":["trace[1036511663] 'read index received' (duration: 42.81µs)","trace[1036511663] 'applied index is now lower than readState.Index' (duration: 192.148144ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:54.549438Z","caller":"traceutil/trace.go:171","msg":"trace[1150090369] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"305.010079ms","start":"2026-07-14T23:57:54.244386Z","end":"2026-07-14T23:57:54.549396Z","steps":["trace[1150090369] 'process raft request' (duration: 112.52937ms)","trace[1150090369] 'compare' (duration: 191.304038ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:54.54965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:54.244359Z","time spent":"305.169763ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:54.549804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.715834ms","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-07-14T23:57:54.54988Z","caller":"traceutil/trace.go:171","msg":"trace[2144854737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25246; }","duration":"192.837206ms","start":"2026-07-14T23:57:54.357023Z","end":"2026-07-14T23:57:54.54986Z","steps":["trace[2144854737] 'agreement among raft nodes before linearized reading' (duration: 192.441398ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:54.818364Z","caller":"traceutil/trace.go:171","msg":"trace[1210122012] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"263.391373ms","start":"2026-07-14T23:57:54.554934Z","end":"2026-07-14T23:57:54.818325Z","steps":["trace[1210122012] 'process raft request' (duration: 257.536764ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:54.88889Z","caller":"traceutil/trace.go:171","msg":"trace[496062366] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"180.372834ms","start":"2026-07-14T23:57:54.708483Z","end":"2026-07-14T23:57:54.888856Z","steps":["trace[496062366] 'process raft request' (duration: 180.085699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.183628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.803693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:57:55.183813Z","caller":"traceutil/trace.go:171","msg":"trace[222176755] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"167.209299ms","start":"2026-07-14T23:57:55.016587Z","end":"2026-07-14T23:57:55.183796Z","steps":["trace[222176755] 'process raft request' (duration: 167.148278ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:55.183797Z","caller":"traceutil/trace.go:171","msg":"trace[1322913676] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"286.534286ms","start":"2026-07-14T23:57:54.897208Z","end":"2026-07-14T23:57:55.183742Z","steps":["trace[1322913676] 'process raft request' (duration: 31.525749ms)","trace[1322913676] 'compare' (duration: 254.671581ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:55.183832Z","caller":"traceutil/trace.go:171","msg":"trace[215042163] linearizableReadLoop","detail":"{readStateIndex:26083; appliedIndex:26082; }","duration":"248.299072ms","start":"2026-07-14T23:57:54.935489Z","end":"2026-07-14T23:57:55.183788Z","steps":["trace[215042163] 'read index received' (duration: 69.431µs)","trace[215042163] 'applied index is now lower than readState.Index' (duration: 248.223981ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:55.184054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.529237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:57:55.184129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.441481ms","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-07-14T23:57:55.184157Z","caller":"traceutil/trace.go:171","msg":"trace[263816349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25250; }","duration":"154.496981ms","start":"2026-07-14T23:57:55.029652Z","end":"2026-07-14T23:57:55.184149Z","steps":["trace[263816349] 'agreement among raft nodes before linearized reading' (duration: 154.381599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.184173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.085139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:57:55.184172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.517862ms","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-07-14T23:57:55.184263Z","caller":"traceutil/trace.go:171","msg":"trace[1256037539] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25250; }","duration":"214.634674ms","start":"2026-07-14T23:57:54.969608Z","end":"2026-07-14T23:57:55.184243Z","steps":["trace[1256037539] 'agreement among raft nodes before linearized reading' (duration: 214.491581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.184354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.798068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-07-14T23:57:55.18439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.743047ms","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-07-14T23:57:55.184392Z","caller":"traceutil/trace.go:171","msg":"trace[1660856402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25250; }","duration":"139.87456ms","start":"2026-07-14T23:57:55.044505Z","end":"2026-07-14T23:57:55.18438Z","steps":["trace[1660856402] 'agreement among raft nodes before linearized reading' (duration: 139.697446ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:55.184162Z","caller":"traceutil/trace.go:171","msg":"trace[238100728] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25250; }","duration":"248.7135ms","start":"2026-07-14T23:57:54.935419Z","end":"2026-07-14T23:57:55.184133Z","steps":["trace[238100728] 'agreement among raft nodes before linearized reading' (duration: 248.508546ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:55.184429Z","caller":"traceutil/trace.go:171","msg":"trace[2058589494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25250; }","duration":"146.833978ms","start":"2026-07-14T23:57:55.037579Z","end":"2026-07-14T23:57:55.184413Z","steps":["trace[2058589494] 'agreement among raft nodes before linearized reading' (duration: 146.664355ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:55.184216Z","caller":"traceutil/trace.go:171","msg":"trace[23770500] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25250; }","duration":"218.16624ms","start":"2026-07-14T23:57:54.966033Z","end":"2026-07-14T23:57:55.184199Z","steps":["trace[23770500] 'agreement among raft nodes before linearized reading' (duration: 218.092519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.1841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.142008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:55.184642Z","caller":"traceutil/trace.go:171","msg":"trace[1423427687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25250; }","duration":"242.720018ms","start":"2026-07-14T23:57:54.941873Z","end":"2026-07-14T23:57:55.184593Z","steps":["trace[1423427687] 'agreement among raft nodes before linearized reading' (duration: 242.138198ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:55.345374Z","caller":"traceutil/trace.go:171","msg":"trace[1848433072] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"157.05753ms","start":"2026-07-14T23:57:55.18829Z","end":"2026-07-14T23:57:55.345348Z","steps":["trace[1848433072] 'process raft request' (duration: 130.414553ms)","trace[1848433072] 'compare' (duration: 26.400162ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:55.643099Z","caller":"traceutil/trace.go:171","msg":"trace[707536843] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"453.716573ms","start":"2026-07-14T23:57:55.189346Z","end":"2026-07-14T23:57:55.643062Z","steps":["trace[707536843] 'process raft request' (duration: 453.594911ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:55.643291Z","caller":"traceutil/trace.go:171","msg":"trace[1987231256] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"376.531725ms","start":"2026-07-14T23:57:55.266741Z","end":"2026-07-14T23:57:55.643273Z","steps":["trace[1987231256] 'process raft request' (duration: 376.431453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.643312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.189336Z","time spent":"453.863967ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:57:55.643111Z","caller":"traceutil/trace.go:171","msg":"trace[1112750191] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"453.848116ms","start":"2026-07-14T23:57:55.18921Z","end":"2026-07-14T23:57:55.643058Z","steps":["trace[1112750191] 'process raft request' (duration: 434.095938ms)","trace[1112750191] 'compare' (duration: 19.493683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:55.643427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.189178Z","time spent":"454.221432ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:57:55.643471Z","caller":"traceutil/trace.go:171","msg":"trace[706811403] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"383.357001ms","start":"2026-07-14T23:57:55.260105Z","end":"2026-07-14T23:57:55.643462Z","steps":["trace[706811403] 'process raft request' (duration: 382.914953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.643508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.260089Z","time spent":"383.399922ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:55.643434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.266716Z","time spent":"376.629556ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:57:55.643749Z","caller":"traceutil/trace.go:171","msg":"trace[1087826108] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"360.962503ms","start":"2026-07-14T23:57:55.282776Z","end":"2026-07-14T23:57:55.643738Z","steps":["trace[1087826108] 'process raft request' (duration: 360.483035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.643922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.282746Z","time spent":"361.151027ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:57:55.643921Z","caller":"traceutil/trace.go:171","msg":"trace[1243510124] linearizableReadLoop","detail":"{readStateIndex:26087; appliedIndex:26084; }","duration":"452.217655ms","start":"2026-07-14T23:57:55.191676Z","end":"2026-07-14T23:57:55.643893Z","steps":["trace[1243510124] 'read index received' (duration: 127.05115ms)","trace[1243510124] 'applied index is now lower than readState.Index' (duration: 325.165095ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:55.643959Z","caller":"traceutil/trace.go:171","msg":"trace[1629588789] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"348.96286ms","start":"2026-07-14T23:57:55.294961Z","end":"2026-07-14T23:57:55.643924Z","steps":["trace[1629588789] 'process raft request' (duration: 348.750476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.6441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.294942Z","time spent":"349.081313ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:55.644229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.545131ms","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-07-14T23:57:55.644271Z","caller":"traceutil/trace.go:171","msg":"trace[1394075483] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25257; }","duration":"452.633723ms","start":"2026-07-14T23:57:55.191626Z","end":"2026-07-14T23:57:55.644259Z","steps":["trace[1394075483] 'agreement among raft nodes before linearized reading' (duration: 452.402029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:55.644313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.191614Z","time spent":"452.684244ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-14T23:57:56.13576Z","caller":"traceutil/trace.go:171","msg":"trace[69599814] linearizableReadLoop","detail":"{readStateIndex:26092; appliedIndex:26091; }","duration":"491.733183ms","start":"2026-07-14T23:57:55.643994Z","end":"2026-07-14T23:57:56.135727Z","steps":["trace[69599814] 'read index received' (duration: 448.14626ms)","trace[69599814] 'applied index is now lower than readState.Index' (duration: 43.585213ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:56.136263Z","caller":"traceutil/trace.go:171","msg":"trace[2023337382] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"550.096022ms","start":"2026-07-14T23:57:55.586104Z","end":"2026-07-14T23:57:56.1362Z","steps":["trace[2023337382] 'process raft request' (duration: 506.131832ms)","trace[2023337382] 'compare' (duration: 43.311527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:56.136271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.785163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:57:56.136292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.029794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:57:56.13634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.712446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-07-14T23:57:56.136394Z","caller":"traceutil/trace.go:171","msg":"trace[1030997083] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25258; }","duration":"202.952257ms","start":"2026-07-14T23:57:55.93341Z","end":"2026-07-14T23:57:56.136362Z","steps":["trace[1030997083] 'agreement among raft nodes before linearized reading' (duration: 202.724313ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:56.1364Z","caller":"traceutil/trace.go:171","msg":"trace[358199322] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25258; }","duration":"294.189128ms","start":"2026-07-14T23:57:55.842183Z","end":"2026-07-14T23:57:56.136372Z","steps":["trace[358199322] 'agreement among raft nodes before linearized reading' (duration: 293.972014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:56.136375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.403278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18231"} {"level":"warn","ts":"2026-07-14T23:57:56.136267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.330752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:56.136435Z","caller":"traceutil/trace.go:171","msg":"trace[1721507313] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25258; }","duration":"912.845538ms","start":"2026-07-14T23:57:55.22357Z","end":"2026-07-14T23:57:56.136415Z","steps":["trace[1721507313] 'agreement among raft nodes before linearized reading' (duration: 912.638194ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:56.136473Z","caller":"traceutil/trace.go:171","msg":"trace[1129461283] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25258; }","duration":"490.579011ms","start":"2026-07-14T23:57:55.645868Z","end":"2026-07-14T23:57:56.136447Z","steps":["trace[1129461283] 'agreement among raft nodes before linearized reading' (duration: 490.268046ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:56.136495Z","caller":"traceutil/trace.go:171","msg":"trace[463093847] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25258; }","duration":"263.586977ms","start":"2026-07-14T23:57:55.872877Z","end":"2026-07-14T23:57:56.136463Z","steps":["trace[463093847] 'agreement among raft nodes before linearized reading' (duration: 263.268051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:56.136537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.645835Z","time spent":"490.691783ms","remote":"127.0.0.1:37210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18254,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-14T23:57:56.136529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.586076Z","time spent":"550.295806ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:57:56.136484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:55.223558Z","time spent":"912.91911ms","remote":"127.0.0.1:37236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":562,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-07-14T23:57:56.359841Z","caller":"traceutil/trace.go:171","msg":"trace[864755318] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"208.540941ms","start":"2026-07-14T23:57:56.151246Z","end":"2026-07-14T23:57:56.359787Z","steps":["trace[864755318] 'process raft request' (duration: 149.303065ms)","trace[864755318] 'compare' (duration: 58.855258ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:56.359814Z","caller":"traceutil/trace.go:171","msg":"trace[1403633537] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"114.774281ms","start":"2026-07-14T23:57:56.244999Z","end":"2026-07-14T23:57:56.359773Z","steps":["trace[1403633537] 'process raft request' (duration: 114.685539ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:56.635793Z","caller":"traceutil/trace.go:171","msg":"trace[741152823] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"259.751376ms","start":"2026-07-14T23:57:56.376019Z","end":"2026-07-14T23:57:56.63577Z","steps":["trace[741152823] 'process raft request' (duration: 259.357658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:58.068961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.703146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e32021f-1d98-4818-a44d-48a76b161253\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-14T23:57:58.069099Z","caller":"traceutil/trace.go:171","msg":"trace[201539899] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e32021f-1d98-4818-a44d-48a76b161253; range_end:; response_count:1; response_revision:25329; }","duration":"176.914629ms","start":"2026-07-14T23:57:57.89216Z","end":"2026-07-14T23:57:58.069074Z","steps":["trace[201539899] 'range keys from in-memory index tree' (duration: 176.476242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:58.069254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.98369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:57:58.06934Z","caller":"traceutil/trace.go:171","msg":"trace[737689116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25330; }","duration":"126.131033ms","start":"2026-07-14T23:57:57.943188Z","end":"2026-07-14T23:57:58.069319Z","steps":["trace[737689116] 'agreement among raft nodes before linearized reading' (duration: 125.9525ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:58.069056Z","caller":"traceutil/trace.go:171","msg":"trace[1116890429] linearizableReadLoop","detail":"{readStateIndex:26164; appliedIndex:26163; }","duration":"125.741406ms","start":"2026-07-14T23:57:57.943244Z","end":"2026-07-14T23:57:58.068985Z","steps":["trace[1116890429] 'read index received' (duration: 43.498091ms)","trace[1116890429] 'applied index is now lower than readState.Index' (duration: 82.241485ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:57:58.069754Z","caller":"traceutil/trace.go:171","msg":"trace[2098617055] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"153.365661ms","start":"2026-07-14T23:57:57.915638Z","end":"2026-07-14T23:57:58.069003Z","steps":["trace[2098617055] 'process raft request' (duration: 71.178278ms)","trace[2098617055] 'compare' (duration: 81.784446ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:57:58.069839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.734277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:57:58.070041Z","caller":"traceutil/trace.go:171","msg":"trace[1229143294] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25330; }","duration":"109.989022ms","start":"2026-07-14T23:57:57.960028Z","end":"2026-07-14T23:57:58.070017Z","steps":["trace[1229143294] 'agreement among raft nodes before linearized reading' (duration: 109.705116ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:57:59.980344Z","caller":"traceutil/trace.go:171","msg":"trace[757019235] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"313.456257ms","start":"2026-07-14T23:57:59.666857Z","end":"2026-07-14T23:57:59.980313Z","steps":["trace[757019235] 'process raft request' (duration: 313.260294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:57:59.98055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:59.666826Z","time spent":"313.61551ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:58:00.03343Z","caller":"traceutil/trace.go:171","msg":"trace[242185785] linearizableReadLoop","detail":"{readStateIndex:26181; appliedIndex:26179; }","duration":"366.450365ms","start":"2026-07-14T23:57:59.666939Z","end":"2026-07-14T23:58:00.033389Z","steps":["trace[242185785] 'read index received' (duration: 313.777753ms)","trace[242185785] 'applied index is now lower than readState.Index' (duration: 52.671302ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:00.033533Z","caller":"traceutil/trace.go:171","msg":"trace[401025290] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"366.643069ms","start":"2026-07-14T23:57:59.666883Z","end":"2026-07-14T23:58:00.033526Z","steps":["trace[401025290] 'process raft request' (duration: 366.326573ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:00.033461Z","caller":"traceutil/trace.go:171","msg":"trace[514976995] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"366.546597ms","start":"2026-07-14T23:57:59.666884Z","end":"2026-07-14T23:58:00.033431Z","steps":["trace[514976995] 'process raft request' (duration: 364.404717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:00.033796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:59.666847Z","time spent":"366.718121ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:58:00.033824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.062679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-14T23:58:00.033886Z","caller":"traceutil/trace.go:171","msg":"trace[1465290347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25346; }","duration":"366.176211ms","start":"2026-07-14T23:57:59.66769Z","end":"2026-07-14T23:58:00.033866Z","steps":["trace[1465290347] 'agreement among raft nodes before linearized reading' (duration: 365.944377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:00.033939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.000025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-14T23:58:00.033946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:59.667672Z","time spent":"366.258442ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T23:58:00.033984Z","caller":"traceutil/trace.go:171","msg":"trace[186641584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25346; }","duration":"367.095417ms","start":"2026-07-14T23:57:59.666873Z","end":"2026-07-14T23:58:00.033968Z","steps":["trace[186641584] 'agreement among raft nodes before linearized reading' (duration: 366.922904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:00.034011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:59.666846Z","time spent":"367.160779ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T23:58:00.033791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:57:59.666856Z","time spent":"366.803272ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:58:04.582644Z","caller":"traceutil/trace.go:171","msg":"trace[362159088] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"193.53286ms","start":"2026-07-14T23:58:04.38906Z","end":"2026-07-14T23:58:04.582593Z","steps":["trace[362159088] 'process raft request' (duration: 193.171094ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:05.012046Z","caller":"traceutil/trace.go:171","msg":"trace[1171104595] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"118.452019ms","start":"2026-07-14T23:58:04.893562Z","end":"2026-07-14T23:58:05.012014Z","steps":["trace[1171104595] 'process raft request' (duration: 118.245646ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:05.029509Z","caller":"traceutil/trace.go:171","msg":"trace[522781113] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"104.817965ms","start":"2026-07-14T23:58:04.92466Z","end":"2026-07-14T23:58:05.029478Z","steps":["trace[522781113] 'process raft request' (duration: 104.511619ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:07.560707Z","caller":"traceutil/trace.go:171","msg":"trace[1892053648] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"147.192286ms","start":"2026-07-14T23:58:07.413482Z","end":"2026-07-14T23:58:07.560674Z","steps":["trace[1892053648] 'process raft request' (duration: 146.979522ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:09.159491Z","caller":"traceutil/trace.go:171","msg":"trace[1685786651] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"118.743106ms","start":"2026-07-14T23:58:09.040705Z","end":"2026-07-14T23:58:09.159448Z","steps":["trace[1685786651] 'process raft request' (duration: 96.094993ms)","trace[1685786651] 'compare' (duration: 21.921948ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:58:29.140637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.393223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"warn","ts":"2026-07-14T23:58:29.140691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.742086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:58:29.140752Z","caller":"traceutil/trace.go:171","msg":"trace[1179303673] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25599; }","duration":"174.550285ms","start":"2026-07-14T23:58:28.966175Z","end":"2026-07-14T23:58:29.140725Z","steps":["trace[1179303673] 'range keys from in-memory index tree' (duration: 174.217599ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:29.140782Z","caller":"traceutil/trace.go:171","msg":"trace[2044807317] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25599; }","duration":"117.889089ms","start":"2026-07-14T23:58:29.022867Z","end":"2026-07-14T23:58:29.140756Z","steps":["trace[2044807317] 'count revisions from in-memory index tree' (duration: 117.663185ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:29.423473Z","caller":"traceutil/trace.go:171","msg":"trace[782854875] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"110.173455ms","start":"2026-07-14T23:58:29.313261Z","end":"2026-07-14T23:58:29.423434Z","steps":["trace[782854875] 'process raft request' (duration: 74.289646ms)","trace[782854875] 'compare' (duration: 35.694915ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:29.437429Z","caller":"traceutil/trace.go:171","msg":"trace[990643507] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"123.210598ms","start":"2026-07-14T23:58:29.314179Z","end":"2026-07-14T23:58:29.437389Z","steps":["trace[990643507] 'process raft request' (duration: 123.019025ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:29.924337Z","caller":"traceutil/trace.go:171","msg":"trace[1167817281] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"239.669361ms","start":"2026-07-14T23:58:29.684638Z","end":"2026-07-14T23:58:29.924307Z","steps":["trace[1167817281] 'process raft request' (duration: 239.467838ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:32.874904Z","caller":"traceutil/trace.go:171","msg":"trace[237357178] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"142.371386ms","start":"2026-07-14T23:58:32.732504Z","end":"2026-07-14T23:58:32.874875Z","steps":["trace[237357178] 'process raft request' (duration: 142.144902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:33.087016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.837422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:58:33.087063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.266638ms","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-07-14T23:58:33.087099Z","caller":"traceutil/trace.go:171","msg":"trace[1759872981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25625; }","duration":"144.946254ms","start":"2026-07-14T23:58:32.94213Z","end":"2026-07-14T23:58:33.087077Z","steps":["trace[1759872981] 'range keys from in-memory index tree' (duration: 144.691049ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:33.087122Z","caller":"traceutil/trace.go:171","msg":"trace[1194939172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25625; }","duration":"176.38491ms","start":"2026-07-14T23:58:32.910724Z","end":"2026-07-14T23:58:33.087109Z","steps":["trace[1194939172] 'range keys from in-memory index tree' (duration: 175.976932ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:33.229427Z","caller":"traceutil/trace.go:171","msg":"trace[1244082644] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"137.266902ms","start":"2026-07-14T23:58:33.09212Z","end":"2026-07-14T23:58:33.229387Z","steps":["trace[1244082644] 'process raft request' (duration: 137.015157ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:33.230453Z","caller":"traceutil/trace.go:171","msg":"trace[737428558] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"108.538875ms","start":"2026-07-14T23:58:33.121891Z","end":"2026-07-14T23:58:33.23043Z","steps":["trace[737428558] 'process raft request' (duration: 108.145518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:33.454097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.028068ms","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-07-14T23:58:33.454195Z","caller":"traceutil/trace.go:171","msg":"trace[900366370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25628; }","duration":"165.136151ms","start":"2026-07-14T23:58:33.289039Z","end":"2026-07-14T23:58:33.454175Z","steps":["trace[900366370] 'range keys from in-memory index tree' (duration: 164.793274ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:33.454268Z","caller":"traceutil/trace.go:171","msg":"trace[554378032] linearizableReadLoop","detail":"{readStateIndex:26472; appliedIndex:26471; }","duration":"145.546295ms","start":"2026-07-14T23:58:33.308694Z","end":"2026-07-14T23:58:33.45424Z","steps":["trace[554378032] 'read index received' (duration: 143.12179ms)","trace[554378032] 'applied index is now lower than readState.Index' (duration: 2.422695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:58:33.454441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.702938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:58:33.454452Z","caller":"traceutil/trace.go:171","msg":"trace[2063107263] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"149.121591ms","start":"2026-07-14T23:58:33.305297Z","end":"2026-07-14T23:58:33.454419Z","steps":["trace[2063107263] 'process raft request' (duration: 146.498582ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:33.454487Z","caller":"traceutil/trace.go:171","msg":"trace[1760879131] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25629; }","duration":"145.83423ms","start":"2026-07-14T23:58:33.308639Z","end":"2026-07-14T23:58:33.454473Z","steps":["trace[1760879131] 'agreement among raft nodes before linearized reading' (duration: 145.713828ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:33.687245Z","caller":"traceutil/trace.go:171","msg":"trace[242486674] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"239.295574ms","start":"2026-07-14T23:58:33.447915Z","end":"2026-07-14T23:58:33.687211Z","steps":["trace[242486674] 'process raft request' (duration: 239.066069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:33.86262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.721795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:58:33.862844Z","caller":"traceutil/trace.go:171","msg":"trace[899002064] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"405.140578ms","start":"2026-07-14T23:58:33.457682Z","end":"2026-07-14T23:58:33.862822Z","steps":["trace[899002064] 'process raft request' (duration: 405.070497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:33.862927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:33.45764Z","time spent":"405.251251ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:58:33.862874Z","caller":"traceutil/trace.go:171","msg":"trace[452838422] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"410.984198ms","start":"2026-07-14T23:58:33.45185Z","end":"2026-07-14T23:58:33.862834Z","steps":["trace[452838422] 'process raft request' (duration: 305.940867ms)","trace[452838422] 'compare' (duration: 104.566622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:58:33.863581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:33.451702Z","time spent":"411.328643ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:58:34.137766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.505311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-14T23:58:34.137923Z","caller":"traceutil/trace.go:171","msg":"trace[1611478581] linearizableReadLoop","detail":"{readStateIndex:26476; appliedIndex:26475; }","duration":"196.684839ms","start":"2026-07-14T23:58:33.941212Z","end":"2026-07-14T23:58:34.137897Z","steps":["trace[1611478581] 'read index received' (duration: 46.531µs)","trace[1611478581] 'applied index is now lower than readState.Index' (duration: 196.636888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:58:34.138171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.954014ms","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-07-14T23:58:34.138216Z","caller":"traceutil/trace.go:171","msg":"trace[1609504726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25632; }","duration":"197.054356ms","start":"2026-07-14T23:58:33.941146Z","end":"2026-07-14T23:58:34.1382Z","steps":["trace[1609504726] 'agreement among raft nodes before linearized reading' (duration: 196.817831ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:34.279715Z","caller":"traceutil/trace.go:171","msg":"trace[388187661] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"125.791366ms","start":"2026-07-14T23:58:34.153897Z","end":"2026-07-14T23:58:34.279688Z","steps":["trace[388187661] 'process raft request' (duration: 113.444156ms)","trace[388187661] 'compare' (duration: 12.213188ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:34.340214Z","caller":"traceutil/trace.go:171","msg":"trace[1020299454] linearizableReadLoop","detail":"{readStateIndex:26480; appliedIndex:26477; }","duration":"128.748001ms","start":"2026-07-14T23:58:34.211449Z","end":"2026-07-14T23:58:34.340197Z","steps":["trace[1020299454] 'read index received' (duration: 55.920823ms)","trace[1020299454] 'applied index is now lower than readState.Index' (duration: 72.825978ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:34.340313Z","caller":"traceutil/trace.go:171","msg":"trace[142512051] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"148.5455ms","start":"2026-07-14T23:58:34.191757Z","end":"2026-07-14T23:58:34.340303Z","steps":["trace[142512051] 'process raft request' (duration: 148.389147ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:34.340283Z","caller":"traceutil/trace.go:171","msg":"trace[456776326] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"148.490149ms","start":"2026-07-14T23:58:34.19175Z","end":"2026-07-14T23:58:34.34024Z","steps":["trace[456776326] 'process raft request' (duration: 148.262515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:34.340652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.17421ms","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-07-14T23:58:34.340743Z","caller":"traceutil/trace.go:171","msg":"trace[688177343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25636; }","duration":"129.325893ms","start":"2026-07-14T23:58:34.211396Z","end":"2026-07-14T23:58:34.340722Z","steps":["trace[688177343] 'agreement among raft nodes before linearized reading' (duration: 129.010416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:34.368098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.246261ms","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-07-14T23:58:34.368206Z","caller":"traceutil/trace.go:171","msg":"trace[931349335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25637; }","duration":"151.388233ms","start":"2026-07-14T23:58:34.216792Z","end":"2026-07-14T23:58:34.36818Z","steps":["trace[931349335] 'agreement among raft nodes before linearized reading' (duration: 151.042237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:34.492849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.411855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:58:34.493491Z","caller":"traceutil/trace.go:171","msg":"trace[1462520683] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"147.41593ms","start":"2026-07-14T23:58:34.346024Z","end":"2026-07-14T23:58:34.49344Z","steps":["trace[1462520683] 'process raft request' (duration: 39.314183ms)","trace[1462520683] 'compare' (duration: 107.109209ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:34.49354Z","caller":"traceutil/trace.go:171","msg":"trace[1858583662] linearizableReadLoop","detail":"{readStateIndex:26482; appliedIndex:26481; }","duration":"125.717846ms","start":"2026-07-14T23:58:34.367793Z","end":"2026-07-14T23:58:34.493511Z","steps":["trace[1858583662] 'read index received' (duration: 17.556368ms)","trace[1858583662] 'applied index is now lower than readState.Index' (duration: 108.158908ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:34.493781Z","caller":"traceutil/trace.go:171","msg":"trace[414669989] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"120.570059ms","start":"2026-07-14T23:58:34.373198Z","end":"2026-07-14T23:58:34.493768Z","steps":["trace[414669989] 'process raft request' (duration: 120.480948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:34.493776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.790536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:58:34.493876Z","caller":"traceutil/trace.go:171","msg":"trace[589135266] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25638; }","duration":"140.955329ms","start":"2026-07-14T23:58:34.352894Z","end":"2026-07-14T23:58:34.493849Z","steps":["trace[589135266] 'agreement among raft nodes before linearized reading' (duration: 140.742905ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:35.362522Z","caller":"traceutil/trace.go:171","msg":"trace[564553653] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"124.163736ms","start":"2026-07-14T23:58:35.238326Z","end":"2026-07-14T23:58:35.36249Z","steps":["trace[564553653] 'process raft request' (duration: 123.941572ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:35.588149Z","caller":"traceutil/trace.go:171","msg":"trace[30378300] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"127.734833ms","start":"2026-07-14T23:58:35.460373Z","end":"2026-07-14T23:58:35.588108Z","steps":["trace[30378300] 'process raft request' (duration: 51.343248ms)","trace[30378300] 'compare' (duration: 76.198352ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:35.802088Z","caller":"traceutil/trace.go:171","msg":"trace[2034012474] transaction","detail":"{read_only:false; response_revision:25643; number_of_response:1; }","duration":"108.939552ms","start":"2026-07-14T23:58:35.693118Z","end":"2026-07-14T23:58:35.802058Z","steps":["trace[2034012474] 'process raft request' (duration: 98.878455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:45.728631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.24454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:58:45.728747Z","caller":"traceutil/trace.go:171","msg":"trace[2095172313] linearizableReadLoop","detail":"{readStateIndex:26561; appliedIndex:26560; }","duration":"236.408061ms","start":"2026-07-14T23:58:45.492318Z","end":"2026-07-14T23:58:45.728726Z","steps":["trace[2095172313] 'read index received' (duration: 135.954247ms)","trace[2095172313] 'applied index is now lower than readState.Index' (duration: 100.451984ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:45.728853Z","caller":"traceutil/trace.go:171","msg":"trace[2044833493] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"249.26981ms","start":"2026-07-14T23:58:45.479569Z","end":"2026-07-14T23:58:45.728839Z","steps":["trace[2044833493] 'process raft request' (duration: 148.726594ms)","trace[2044833493] 'compare' (duration: 99.861683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:58:45.729262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.93746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:58:45.729303Z","caller":"traceutil/trace.go:171","msg":"trace[2407033] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25715; }","duration":"237.019302ms","start":"2026-07-14T23:58:45.492268Z","end":"2026-07-14T23:58:45.729287Z","steps":["trace[2407033] 'agreement among raft nodes before linearized reading' (duration: 236.868689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:45.742339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.261857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:58:45.742382Z","caller":"traceutil/trace.go:171","msg":"trace[1398908479] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25716; }","duration":"236.309239ms","start":"2026-07-14T23:58:45.50606Z","end":"2026-07-14T23:58:45.742369Z","steps":["trace[1398908479] 'agreement among raft nodes before linearized reading' (duration: 236.222858ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:45.742388Z","caller":"traceutil/trace.go:171","msg":"trace[857215702] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"115.19592ms","start":"2026-07-14T23:58:45.627141Z","end":"2026-07-14T23:58:45.742337Z","steps":["trace[857215702] 'process raft request' (duration: 115.041137ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:46.105553Z","caller":"traceutil/trace.go:171","msg":"trace[1366013816] compact","detail":"{revision:22764; response_revision:25716; }","duration":"370.556632ms","start":"2026-07-14T23:58:45.734971Z","end":"2026-07-14T23:58:46.105527Z","steps":["trace[1366013816] 'process raft request' (duration: 288.543422ms)","trace[1366013816] 'check and update compact revision' (duration: 81.885758ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:46.105546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22764} {"level":"warn","ts":"2026-07-14T23:58:46.105637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:45.734927Z","time spent":"370.703445ms","remote":"127.0.0.1:37020","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-14T23:58:46.110441Z","caller":"traceutil/trace.go:171","msg":"trace[1900183735] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"275.240864ms","start":"2026-07-14T23:58:45.835172Z","end":"2026-07-14T23:58:46.110413Z","steps":["trace[1900183735] 'process raft request' (duration: 274.841767ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:46.110895Z","caller":"traceutil/trace.go:171","msg":"trace[1929526784] linearizableReadLoop","detail":"{readStateIndex:26565; appliedIndex:26562; }","duration":"126.154963ms","start":"2026-07-14T23:58:45.984731Z","end":"2026-07-14T23:58:46.110886Z","steps":["trace[1929526784] 'read index received' (duration: 38.789223ms)","trace[1929526784] 'applied index is now lower than readState.Index' (duration: 87.36474ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:46.111015Z","caller":"traceutil/trace.go:171","msg":"trace[1808397397] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"154.661645ms","start":"2026-07-14T23:58:45.956327Z","end":"2026-07-14T23:58:46.110988Z","steps":["trace[1808397397] 'process raft request' (duration: 154.062894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:46.111058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.318126ms","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-07-14T23:58:46.111084Z","caller":"traceutil/trace.go:171","msg":"trace[1494346346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25718; }","duration":"126.420419ms","start":"2026-07-14T23:58:45.984655Z","end":"2026-07-14T23:58:46.111075Z","steps":["trace[1494346346] 'agreement among raft nodes before linearized reading' (duration: 126.263336ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:46.251749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22764,"took":"140.842607ms","hash":3508433424,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":35893248,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-14T23:58:46.251813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3508433424,"revision":22764,"compact-revision":20021} {"level":"info","ts":"2026-07-14T23:58:46.505376Z","caller":"traceutil/trace.go:171","msg":"trace[314934884] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"121.347674ms","start":"2026-07-14T23:58:46.384Z","end":"2026-07-14T23:58:46.505348Z","steps":["trace[314934884] 'process raft request' (duration: 121.13269ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:46.51331Z","caller":"traceutil/trace.go:171","msg":"trace[1849997527] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"126.968249ms","start":"2026-07-14T23:58:46.386328Z","end":"2026-07-14T23:58:46.513297Z","steps":["trace[1849997527] 'process raft request' (duration: 126.803336ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:46.513574Z","caller":"traceutil/trace.go:171","msg":"trace[1763079953] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"104.01142ms","start":"2026-07-14T23:58:46.409422Z","end":"2026-07-14T23:58:46.513433Z","steps":["trace[1763079953] 'process raft request' (duration: 103.794996ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:46.693763Z","caller":"traceutil/trace.go:171","msg":"trace[1055161092] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"141.719643ms","start":"2026-07-14T23:58:46.552002Z","end":"2026-07-14T23:58:46.693721Z","steps":["trace[1055161092] 'process raft request' (duration: 45.647721ms)","trace[1055161092] 'compare' (duration: 95.852008ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:46.693911Z","caller":"traceutil/trace.go:171","msg":"trace[1434921350] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"139.370439ms","start":"2026-07-14T23:58:46.554515Z","end":"2026-07-14T23:58:46.693886Z","steps":["trace[1434921350] 'process raft request' (duration: 139.121004ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:52.418026Z","caller":"traceutil/trace.go:171","msg":"trace[774504285] linearizableReadLoop","detail":"{readStateIndex:26623; appliedIndex:26622; }","duration":"162.009432ms","start":"2026-07-14T23:58:52.255988Z","end":"2026-07-14T23:58:52.417997Z","steps":["trace[774504285] 'read index received' (duration: 161.866669ms)","trace[774504285] 'applied index is now lower than readState.Index' (duration: 141.253µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:52.418123Z","caller":"traceutil/trace.go:171","msg":"trace[165876713] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"226.143499ms","start":"2026-07-14T23:58:52.191943Z","end":"2026-07-14T23:58:52.418086Z","steps":["trace[165876713] 'process raft request' (duration: 225.848203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:52.418321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.307008ms","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-07-14T23:58:52.418372Z","caller":"traceutil/trace.go:171","msg":"trace[1494144137] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25775; }","duration":"162.435871ms","start":"2026-07-14T23:58:52.255924Z","end":"2026-07-14T23:58:52.41836Z","steps":["trace[1494144137] 'agreement among raft nodes before linearized reading' (duration: 162.216057ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:52.618793Z","caller":"traceutil/trace.go:171","msg":"trace[39558209] linearizableReadLoop","detail":"{readStateIndex:26624; appliedIndex:26623; }","duration":"200.654882ms","start":"2026-07-14T23:58:52.418104Z","end":"2026-07-14T23:58:52.618759Z","steps":["trace[39558209] 'read index received' (duration: 107.336292ms)","trace[39558209] 'applied index is now lower than readState.Index' (duration: 93.31621ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:52.618903Z","caller":"traceutil/trace.go:171","msg":"trace[1047816045] transaction","detail":"{read_only:false; response_revision:25776; number_of_response:1; }","duration":"244.602503ms","start":"2026-07-14T23:58:52.374233Z","end":"2026-07-14T23:58:52.618835Z","steps":["trace[1047816045] 'process raft request' (duration: 151.252452ms)","trace[1047816045] 'compare' (duration: 92.930203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:58:52.619208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.238658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-14T23:58:52.619305Z","caller":"traceutil/trace.go:171","msg":"trace[470017245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25776; }","duration":"361.396732ms","start":"2026-07-14T23:58:52.257883Z","end":"2026-07-14T23:58:52.619279Z","steps":["trace[470017245] 'agreement among raft nodes before linearized reading' (duration: 361.025375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:52.619377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.257874Z","time spent":"361.491173ms","remote":"127.0.0.1:37298","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-07-14T23:58:52.886396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.962104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:58:52.88673Z","caller":"traceutil/trace.go:171","msg":"trace[1485602962] linearizableReadLoop","detail":"{readStateIndex:26627; appliedIndex:26624; }","duration":"267.833907ms","start":"2026-07-14T23:58:52.618871Z","end":"2026-07-14T23:58:52.886705Z","steps":["trace[1485602962] 'read index received' (duration: 6.95695ms)","trace[1485602962] 'applied index is now lower than readState.Index' (duration: 260.875317ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:58:52.886868Z","caller":"traceutil/trace.go:171","msg":"trace[2127099909] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"262.342925ms","start":"2026-07-14T23:58:52.624508Z","end":"2026-07-14T23:58:52.886851Z","steps":["trace[2127099909] 'process raft request' (duration: 262.137601ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:52.886907Z","caller":"traceutil/trace.go:171","msg":"trace[1722838639] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"350.801433ms","start":"2026-07-14T23:58:52.536032Z","end":"2026-07-14T23:58:52.886833Z","steps":["trace[1722838639] 'process raft request' (duration: 350.538948ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:52.88722Z","caller":"traceutil/trace.go:171","msg":"trace[1476657902] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"352.563987ms","start":"2026-07-14T23:58:52.534617Z","end":"2026-07-14T23:58:52.887181Z","steps":["trace[1476657902] 'process raft request' (duration: 228.670236ms)","trace[1476657902] 'compare' (duration: 122.76977ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:58:52.887359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.535993Z","time spent":"351.195441ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:58:52.887428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.534596Z","time spent":"352.72754ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:58:52.887243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.613411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:58:52.887794Z","caller":"traceutil/trace.go:171","msg":"trace[1269138202] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25780; }","duration":"439.180971ms","start":"2026-07-14T23:58:52.448591Z","end":"2026-07-14T23:58:52.887772Z","steps":["trace[1269138202] 'agreement among raft nodes before linearized reading' (duration: 438.5397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:52.887858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.448579Z","time spent":"439.260153ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:58:52.887966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.032782ms","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":"warn","ts":"2026-07-14T23:58:52.888073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.363936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:58:52.888125Z","caller":"traceutil/trace.go:171","msg":"trace[1117466510] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25780; }","duration":"120.404927ms","start":"2026-07-14T23:58:52.767699Z","end":"2026-07-14T23:58:52.888104Z","steps":["trace[1117466510] 'agreement among raft nodes before linearized reading' (duration: 120.332665ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:52.888064Z","caller":"traceutil/trace.go:171","msg":"trace[1446304177] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25780; }","duration":"125.161204ms","start":"2026-07-14T23:58:52.762882Z","end":"2026-07-14T23:58:52.888043Z","steps":["trace[1446304177] 'agreement among raft nodes before linearized reading' (duration: 124.858979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:52.888159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.347478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-07-14T23:58:52.888219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.724402ms","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-07-14T23:58:52.888257Z","caller":"traceutil/trace.go:171","msg":"trace[1084779424] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25780; }","duration":"177.651484ms","start":"2026-07-14T23:58:52.710578Z","end":"2026-07-14T23:58:52.88823Z","steps":["trace[1084779424] 'agreement among raft nodes before linearized reading' (duration: 176.822939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:52.888256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.978526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-14T23:58:52.888261Z","caller":"traceutil/trace.go:171","msg":"trace[784860280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25780; }","duration":"165.822623ms","start":"2026-07-14T23:58:52.722428Z","end":"2026-07-14T23:58:52.88825Z","steps":["trace[784860280] 'agreement among raft nodes before linearized reading' (duration: 165.6694ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:52.888309Z","caller":"traceutil/trace.go:171","msg":"trace[124669028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25780; }","duration":"166.046567ms","start":"2026-07-14T23:58:52.72225Z","end":"2026-07-14T23:58:52.888297Z","steps":["trace[124669028] 'agreement among raft nodes before linearized reading' (duration: 165.855874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:52.887981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.171358ms","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-07-14T23:58:52.888372Z","caller":"traceutil/trace.go:171","msg":"trace[960068949] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25780; }","duration":"465.592146ms","start":"2026-07-14T23:58:52.422764Z","end":"2026-07-14T23:58:52.888356Z","steps":["trace[960068949] 'agreement among raft nodes before linearized reading' (duration: 464.453024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:52.888426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.422744Z","time spent":"465.667637ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-14T23:58:53.338702Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006806.wal"} {"level":"info","ts":"2026-07-14T23:58:53.339351Z","caller":"traceutil/trace.go:171","msg":"trace[1405397192] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"445.398918ms","start":"2026-07-14T23:58:52.893924Z","end":"2026-07-14T23:58:53.339323Z","steps":["trace[1405397192] 'process raft request' (duration: 445.059602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:53.33959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.893898Z","time spent":"445.533862ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:58:53.345662Z","caller":"traceutil/trace.go:171","msg":"trace[1725580269] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"450.580095ms","start":"2026-07-14T23:58:52.895062Z","end":"2026-07-14T23:58:53.345642Z","steps":["trace[1725580269] 'process raft request' (duration: 448.992715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:53.345784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.895033Z","time spent":"450.704948ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:58:53.346161Z","caller":"traceutil/trace.go:171","msg":"trace[104178555] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"450.994473ms","start":"2026-07-14T23:58:52.895152Z","end":"2026-07-14T23:58:53.346146Z","steps":["trace[104178555] 'process raft request' (duration: 450.447703ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:53.34625Z","caller":"traceutil/trace.go:171","msg":"trace[450891431] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"382.335963ms","start":"2026-07-14T23:58:52.963898Z","end":"2026-07-14T23:58:53.346234Z","steps":["trace[450891431] 'process raft request' (duration: 382.108059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:53.346404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.895132Z","time spent":"451.173136ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:58:53.346556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.963868Z","time spent":"382.591177ms","remote":"127.0.0.1:37298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:58:53.346623Z","caller":"traceutil/trace.go:171","msg":"trace[793343305] linearizableReadLoop","detail":"{readStateIndex:26631; appliedIndex:26628; }","duration":"403.545537ms","start":"2026-07-14T23:58:52.943051Z","end":"2026-07-14T23:58:53.346596Z","steps":["trace[793343305] 'read index received' (duration: 396.097688ms)","trace[793343305] 'applied index is now lower than readState.Index' (duration: 7.446479ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:58:53.346832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.775072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:58:53.346913Z","caller":"traceutil/trace.go:171","msg":"trace[24155531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25785; }","duration":"403.880524ms","start":"2026-07-14T23:58:52.94302Z","end":"2026-07-14T23:58:53.3469Z","steps":["trace[24155531] 'agreement among raft nodes before linearized reading' (duration: 403.731121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:53.346983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.943004Z","time spent":"403.969395ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T23:58:53.346898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.394823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:58:53.34708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.678344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:58:53.347126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.863268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:58:53.347143Z","caller":"traceutil/trace.go:171","msg":"trace[536280255] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25785; }","duration":"170.807676ms","start":"2026-07-14T23:58:53.176313Z","end":"2026-07-14T23:58:53.347121Z","steps":["trace[536280255] 'agreement among raft nodes before linearized reading' (duration: 170.687994ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:53.347183Z","caller":"traceutil/trace.go:171","msg":"trace[935500369] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25785; }","duration":"207.98933ms","start":"2026-07-14T23:58:53.13918Z","end":"2026-07-14T23:58:53.347169Z","steps":["trace[935500369] 'agreement among raft nodes before linearized reading' (duration: 207.858618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:53.347212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.145382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-07-14T23:58:53.347138Z","caller":"traceutil/trace.go:171","msg":"trace[504116459] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25785; }","duration":"389.700139ms","start":"2026-07-14T23:58:52.957415Z","end":"2026-07-14T23:58:53.347115Z","steps":["trace[504116459] 'agreement among raft nodes before linearized reading' (duration: 389.383683ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:58:53.347267Z","caller":"traceutil/trace.go:171","msg":"trace[215857545] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25785; }","duration":"157.219452ms","start":"2026-07-14T23:58:53.190029Z","end":"2026-07-14T23:58:53.347248Z","steps":["trace[215857545] 'agreement among raft nodes before linearized reading' (duration: 157.036679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:58:53.347316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:58:52.95738Z","time spent":"389.919843ms","remote":"127.0.0.1:37474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-14T23:58:53.347846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.58579ms","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-07-14T23:58:53.347914Z","caller":"traceutil/trace.go:171","msg":"trace[671871420] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25785; }","duration":"209.710072ms","start":"2026-07-14T23:58:53.13819Z","end":"2026-07-14T23:58:53.3479Z","steps":["trace[671871420] 'agreement among raft nodes before linearized reading' (duration: 208.839885ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:03:46.119945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25715} {"level":"info","ts":"2026-07-15T00:03:46.284051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25715,"took":"157.365893ms","hash":17078375,"current-db-size-bytes":58925056,"current-db-size":"59 MB","current-db-size-in-use-bytes":38383616,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-15T00:03:46.28416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":17078375,"revision":25715,"compact-revision":22764} {"level":"info","ts":"2026-07-15T00:04:35.664531Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"21b354698260dd82","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T00:04:35.670853Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T00:04:35.671026Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T00:08:46.131437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28705} {"level":"info","ts":"2026-07-15T00:08:46.28213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28705,"took":"145.368065ms","hash":1283959093,"current-db-size-bytes":58925056,"current-db-size":"59 MB","current-db-size-in-use-bytes":31363072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T00:08:46.282215Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1283959093,"revision":28705,"compact-revision":25715} {"level":"info","ts":"2026-07-15T00:13:46.139089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30961} {"level":"info","ts":"2026-07-15T00:13:46.283735Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30961,"took":"138.777642ms","hash":3150637383,"current-db-size-bytes":58925056,"current-db-size":"59 MB","current-db-size-in-use-bytes":31522816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T00:13:46.28383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3150637383,"revision":30961,"compact-revision":28705} {"level":"info","ts":"2026-07-15T00:18:46.146334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33221} {"level":"info","ts":"2026-07-15T00:18:46.298789Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33221,"took":"146.598666ms","hash":2179270150,"current-db-size-bytes":58925056,"current-db-size":"59 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T00:18:46.298868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2179270150,"revision":33221,"compact-revision":30961} {"level":"info","ts":"2026-07-15T00:18:49.258744Z","caller":"traceutil/trace.go:171","msg":"trace[2130208864] transaction","detail":"{read_only:false; response_revision:35534; number_of_response:1; }","duration":"108.090862ms","start":"2026-07-15T00:18:49.150612Z","end":"2026-07-15T00:18:49.258702Z","steps":["trace[2130208864] 'process raft request' (duration: 63.685185ms)","trace[2130208864] 'compare' (duration: 43.832394ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T00:23:46.153728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35514} {"level":"info","ts":"2026-07-15T00:23:46.304316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35514,"took":"144.289094ms","hash":3225033898,"current-db-size-bytes":59813888,"current-db-size":"60 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T00:23:46.304398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3225033898,"revision":35514,"compact-revision":33221} {"level":"info","ts":"2026-07-15T00:25:53.862818Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"21b354698260dd82","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T00:25:53.868495Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T00:25:53.868715Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-15T00:28:46.164261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37781} {"level":"info","ts":"2026-07-15T00:28:46.300509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37781,"took":"130.446637ms","hash":3760323848,"current-db-size-bytes":61509632,"current-db-size":"62 MB","current-db-size-in-use-bytes":32665600,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T00:28:46.300583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3760323848,"revision":37781,"compact-revision":35514}