{"level":"warn","ts":"2026-05-19T17:02:47.85226Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T17:02:47.852349Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-05-19T17:02:47.852416Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T17:02:47.852423Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-05-19T17:02:47.85244Z","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-05-19T17:02:47.853067Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-05-19T17:02:47.853139Z","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.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-05-19T17:02:47.855718Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.369266ms"} {"level":"info","ts":"2026-05-19T17:02:47.863865Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-05-19T17:02:47.864118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T17:02:47.864144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-05-19T17:02:47.864164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T17:02:47.864174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-05-19T17:02:47.864211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-05-19T17:02:47.869156Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T17:02:47.870427Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T17:02:47.87159Z","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-05-19T17:02:47.874054Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T17:02:47.874235Z","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-05-19T17:02:47.874333Z","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-05-19T17:02:47.874348Z","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-05-19T17:02:47.875046Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T17:02:47.876457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-05-19T17:02:47.877211Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-05-19T17:02:47.878854Z","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-05-19T17:02:47.87896Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-05-19T17:02:47.878992Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-05-19T17:02:47.879132Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T17:02:47.879177Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T17:02:48.164786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T17:02:48.164839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T17:02:48.164857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-05-19T17:02:48.164871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-05-19T17:02:48.164879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-05-19T17:02:48.16489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-05-19T17:02:48.1649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-05-19T17:02:48.165806Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:02:48.166441Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T17:02:48.166675Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:02:48.166706Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:02:48.166852Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T17:02:48.166921Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T17:02:48.16702Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:02:48.167122Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:02:48.167156Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:02:48.169323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T17:02:48.170505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} 2026/05/19 17:02:53 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-19T17:03:24.724924Z","caller":"traceutil/trace.go:171","msg":"trace[1436647031] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"248.029907ms","start":"2026-05-19T17:03:24.476863Z","end":"2026-05-19T17:03:24.724893Z","steps":["trace[1436647031] 'process raft request' (duration: 247.31105ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:03:24.736745Z","caller":"traceutil/trace.go:171","msg":"trace[1432617655] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"225.267331ms","start":"2026-05-19T17:03:24.511424Z","end":"2026-05-19T17:03:24.736692Z","steps":["trace[1432617655] 'process raft request' (duration: 225.039096ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:03:24.912129Z","caller":"traceutil/trace.go:171","msg":"trace[52272301] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"131.988546ms","start":"2026-05-19T17:03:24.78012Z","end":"2026-05-19T17:03:24.912109Z","steps":["trace[52272301] 'process raft request' (duration: 110.870829ms)","trace[52272301] 'compare' (duration: 20.81651ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:03:25.442519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:03:25.090678Z","time spent":"351.83288ms","remote":"127.0.0.1:44678","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T17:03:25.443314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.010358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:03:25.443408Z","caller":"traceutil/trace.go:171","msg":"trace[261800082] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"499.464847ms","start":"2026-05-19T17:03:24.943926Z","end":"2026-05-19T17:03:25.443391Z","steps":["trace[261800082] 'process raft request' (duration: 86.64933ms)","trace[261800082] 'compare' (duration: 411.836183ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:03:25.443465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:03:24.943868Z","time spent":"499.568229ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:03:25.446626Z","caller":"traceutil/trace.go:171","msg":"trace[2035734263] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"130.246596ms","start":"2026-05-19T17:03:25.316352Z","end":"2026-05-19T17:03:25.446599Z","steps":["trace[2035734263] 'process raft request' (duration: 129.664973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:03:46.824093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.961968ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T17:03:46.824205Z","caller":"traceutil/trace.go:171","msg":"trace[1552080670] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"336.962486ms","start":"2026-05-19T17:03:46.487225Z","end":"2026-05-19T17:03:46.824187Z","steps":["trace[1552080670] 'read index received' (duration: 93.810544ms)","trace[1552080670] 'applied index is now lower than readState.Index' (duration: 243.150322ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:03:46.82439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.17939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:03:46.824427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.205502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jbccb\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-19T17:03:46.824449Z","caller":"traceutil/trace.go:171","msg":"trace[499852344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"148.323343ms","start":"2026-05-19T17:03:46.676111Z","end":"2026-05-19T17:03:46.824434Z","steps":["trace[499852344] 'agreement among raft nodes before linearized reading' (duration: 148.19738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:03:46.824477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.616436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T17:03:46.824495Z","caller":"traceutil/trace.go:171","msg":"trace[447274568] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jbccb; range_end:; response_count:1; response_revision:770; }","duration":"337.310975ms","start":"2026-05-19T17:03:46.487163Z","end":"2026-05-19T17:03:46.824474Z","steps":["trace[447274568] 'agreement among raft nodes before linearized reading' (duration: 337.10348ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:03:46.824538Z","caller":"traceutil/trace.go:171","msg":"trace[654732440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"111.720028ms","start":"2026-05-19T17:03:46.712805Z","end":"2026-05-19T17:03:46.824525Z","steps":["trace[654732440] 'agreement among raft nodes before linearized reading' (duration: 111.548424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:03:46.824546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:03:46.487139Z","time spent":"337.392886ms","remote":"127.0.0.1:58816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jbccb\" "} {"level":"info","ts":"2026-05-19T17:04:35.913259Z","caller":"traceutil/trace.go:171","msg":"trace[1561128672] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"148.768764ms","start":"2026-05-19T17:04:35.76445Z","end":"2026-05-19T17:04:35.913218Z","steps":["trace[1561128672] 'process raft request' (duration: 136.923464ms)","trace[1561128672] 'compare' (duration: 11.693607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:04:35.913404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.468124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T17:04:35.913443Z","caller":"traceutil/trace.go:171","msg":"trace[1914152712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1418; }","duration":"139.570647ms","start":"2026-05-19T17:04:35.773863Z","end":"2026-05-19T17:04:35.913434Z","steps":["trace[1914152712] 'agreement among raft nodes before linearized reading' (duration: 139.428284ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:04:35.913241Z","caller":"traceutil/trace.go:171","msg":"trace[1729088468] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1457; }","duration":"139.252059ms","start":"2026-05-19T17:04:35.773923Z","end":"2026-05-19T17:04:35.913175Z","steps":["trace[1729088468] 'read index received' (duration: 127.371268ms)","trace[1729088468] 'applied index is now lower than readState.Index' (duration: 11.879871ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:04:59.959365Z","caller":"traceutil/trace.go:171","msg":"trace[781731881] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"104.890816ms","start":"2026-05-19T17:04:59.85443Z","end":"2026-05-19T17:04:59.959321Z","steps":["trace[781731881] 'process raft request' (duration: 104.622249ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:00.338341Z","caller":"traceutil/trace.go:171","msg":"trace[979579588] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"234.905656ms","start":"2026-05-19T17:05:00.103381Z","end":"2026-05-19T17:05:00.338287Z","steps":["trace[979579588] 'process raft request' (duration: 234.68022ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:14.84698Z","caller":"traceutil/trace.go:171","msg":"trace[701251940] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"130.503561ms","start":"2026-05-19T17:05:14.716443Z","end":"2026-05-19T17:05:14.846947Z","steps":["trace[701251940] 'process raft request' (duration: 130.305386ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:48.492822Z","caller":"traceutil/trace.go:171","msg":"trace[1810161504] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"124.381826ms","start":"2026-05-19T17:05:48.368421Z","end":"2026-05-19T17:05:48.492803Z","steps":["trace[1810161504] 'process raft request' (duration: 124.268433ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:48.630368Z","caller":"traceutil/trace.go:171","msg":"trace[159716516] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"110.915618ms","start":"2026-05-19T17:05:48.519421Z","end":"2026-05-19T17:05:48.630337Z","steps":["trace[159716516] 'process raft request' (duration: 102.475098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:05:54.909671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.38346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:05:54.909847Z","caller":"traceutil/trace.go:171","msg":"trace[91864364] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"231.370029ms","start":"2026-05-19T17:05:54.678451Z","end":"2026-05-19T17:05:54.909821Z","steps":["trace[91864364] 'process raft request' (duration: 105.697422ms)","trace[91864364] 'compare' (duration: 125.053142ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:05:54.923059Z","caller":"traceutil/trace.go:171","msg":"trace[401154185] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"208.942819ms","start":"2026-05-19T17:05:54.714084Z","end":"2026-05-19T17:05:54.923027Z","steps":["trace[401154185] 'process raft request' (duration: 208.811986ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:55.385862Z","caller":"traceutil/trace.go:171","msg":"trace[1500550971] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2265; }","duration":"257.367006ms","start":"2026-05-19T17:05:55.128465Z","end":"2026-05-19T17:05:55.385832Z","steps":["trace[1500550971] 'read index received' (duration: 257.145961ms)","trace[1500550971] 'applied index is now lower than readState.Index' (duration: 219.755µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:05:55.38591Z","caller":"traceutil/trace.go:171","msg":"trace[1052826301] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"279.440969ms","start":"2026-05-19T17:05:55.10643Z","end":"2026-05-19T17:05:55.385871Z","steps":["trace[1052826301] 'process raft request' (duration: 279.245864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:05:55.386381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.888238ms","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-05-19T17:05:55.386456Z","caller":"traceutil/trace.go:171","msg":"trace[898376193] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"258.002911ms","start":"2026-05-19T17:05:55.128433Z","end":"2026-05-19T17:05:55.386436Z","steps":["trace[898376193] 'agreement among raft nodes before linearized reading' (duration: 257.51327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:06:31.316771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.9987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:06:31.317261Z","caller":"traceutil/trace.go:171","msg":"trace[816301499] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"226.090635ms","start":"2026-05-19T17:06:31.091134Z","end":"2026-05-19T17:06:31.317225Z","steps":["trace[816301499] 'process raft request' (duration: 92.530911ms)","trace[816301499] 'compare' (duration: 132.864067ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:07:03.935649Z","caller":"traceutil/trace.go:171","msg":"trace[1469606899] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"111.595555ms","start":"2026-05-19T17:07:03.824025Z","end":"2026-05-19T17:07:03.935621Z","steps":["trace[1469606899] 'process raft request' (duration: 100.690655ms)","trace[1469606899] 'compare' (duration: 10.794127ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:07:03.938956Z","caller":"traceutil/trace.go:171","msg":"trace[1564086019] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"112.814533ms","start":"2026-05-19T17:07:03.826124Z","end":"2026-05-19T17:07:03.938939Z","steps":["trace[1564086019] 'process raft request' (duration: 112.727181ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:09:39.650685Z","caller":"traceutil/trace.go:171","msg":"trace[2074010315] linearizableReadLoop","detail":"{readStateIndex:3916; appliedIndex:3915; }","duration":"121.731001ms","start":"2026-05-19T17:09:39.528915Z","end":"2026-05-19T17:09:39.650646Z","steps":["trace[2074010315] 'read index received' (duration: 121.430944ms)","trace[2074010315] 'applied index is now lower than readState.Index' (duration: 299.007µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:09:39.650801Z","caller":"traceutil/trace.go:171","msg":"trace[1990486796] transaction","detail":"{read_only:false; response_revision:3776; number_of_response:1; }","duration":"130.706233ms","start":"2026-05-19T17:09:39.520048Z","end":"2026-05-19T17:09:39.650754Z","steps":["trace[1990486796] 'process raft request' (duration: 130.395726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:09:39.651005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.06376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-74bm9\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-05-19T17:09:39.65106Z","caller":"traceutil/trace.go:171","msg":"trace[1310620130] range","detail":"{range_begin:/registry/pods/openstack/keepalived-74bm9; range_end:; response_count:1; response_revision:3776; }","duration":"122.193874ms","start":"2026-05-19T17:09:39.52885Z","end":"2026-05-19T17:09:39.651044Z","steps":["trace[1310620130] 'agreement among raft nodes before linearized reading' (duration: 121.979709ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:07.189642Z","caller":"traceutil/trace.go:171","msg":"trace[764732513] linearizableReadLoop","detail":"{readStateIndex:5012; appliedIndex:5011; }","duration":"126.05298ms","start":"2026-05-19T17:11:07.06356Z","end":"2026-05-19T17:11:07.189613Z","steps":["trace[764732513] 'read index received' (duration: 45.955617ms)","trace[764732513] 'applied index is now lower than readState.Index' (duration: 80.096283ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:11:07.189734Z","caller":"traceutil/trace.go:171","msg":"trace[753228839] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"128.365146ms","start":"2026-05-19T17:11:07.061323Z","end":"2026-05-19T17:11:07.189688Z","steps":["trace[753228839] 'process raft request' (duration: 48.224523ms)","trace[753228839] 'compare' (duration: 79.923448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:11:07.189932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.352167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-19T17:11:07.189972Z","caller":"traceutil/trace.go:171","msg":"trace[504563544] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4831; }","duration":"126.459409ms","start":"2026-05-19T17:11:07.063502Z","end":"2026-05-19T17:11:07.189961Z","steps":["trace[504563544] 'agreement among raft nodes before linearized reading' (duration: 126.289985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:07.190036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.031669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T17:11:07.190073Z","caller":"traceutil/trace.go:171","msg":"trace[106066619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4831; }","duration":"103.105242ms","start":"2026-05-19T17:11:07.086956Z","end":"2026-05-19T17:11:07.190061Z","steps":["trace[106066619] 'agreement among raft nodes before linearized reading' (duration: 102.947078ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:11.079701Z","caller":"traceutil/trace.go:171","msg":"trace[1895087742] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"142.988498ms","start":"2026-05-19T17:11:10.936675Z","end":"2026-05-19T17:11:11.079664Z","steps":["trace[1895087742] 'process raft request' (duration: 121.484306ms)","trace[1895087742] 'compare' (duration: 21.347758ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:11:11.258545Z","caller":"traceutil/trace.go:171","msg":"trace[1722203031] linearizableReadLoop","detail":"{readStateIndex:5169; appliedIndex:5166; }","duration":"247.578116ms","start":"2026-05-19T17:11:11.010938Z","end":"2026-05-19T17:11:11.258516Z","steps":["trace[1722203031] 'read index received' (duration: 47.238229ms)","trace[1722203031] 'applied index is now lower than readState.Index' (duration: 200.338557ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:11:11.258601Z","caller":"traceutil/trace.go:171","msg":"trace[386583984] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"321.808682ms","start":"2026-05-19T17:11:10.93674Z","end":"2026-05-19T17:11:11.258549Z","steps":["trace[386583984] 'process raft request' (duration: 321.371781ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:11.258671Z","caller":"traceutil/trace.go:171","msg":"trace[404296538] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"321.864303ms","start":"2026-05-19T17:11:10.936749Z","end":"2026-05-19T17:11:11.258614Z","steps":["trace[404296538] 'process raft request' (duration: 321.71609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:11.258741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:11:10.936725Z","time spent":"321.943665ms","remote":"127.0.0.1:58904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1401,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:11:11.258867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:11:10.936727Z","time spent":"322.045408ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:11:11.25889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.615848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f74fa12-33e9-4734-981f-0c2488275864\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-05-19T17:11:11.258937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.20039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-8t9jq-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-05-19T17:11:11.258977Z","caller":"traceutil/trace.go:171","msg":"trace[1977817006] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f74fa12-33e9-4734-981f-0c2488275864; range_end:; response_count:1; response_revision:4985; }","duration":"207.784072ms","start":"2026-05-19T17:11:11.051172Z","end":"2026-05-19T17:11:11.258956Z","steps":["trace[1977817006] 'agreement among raft nodes before linearized reading' (duration: 207.521356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:11.258976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.007597ms","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-05-19T17:11:11.259049Z","caller":"traceutil/trace.go:171","msg":"trace[72832464] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-8t9jq-tls; range_end:; response_count:1; response_revision:4985; }","duration":"178.360364ms","start":"2026-05-19T17:11:11.080649Z","end":"2026-05-19T17:11:11.25901Z","steps":["trace[72832464] 'agreement among raft nodes before linearized reading' (duration: 178.071327ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:11.259086Z","caller":"traceutil/trace.go:171","msg":"trace[560834278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4985; }","duration":"248.182241ms","start":"2026-05-19T17:11:11.010879Z","end":"2026-05-19T17:11:11.259061Z","steps":["trace[560834278] 'agreement among raft nodes before linearized reading' (duration: 247.805842ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:22.185209Z","caller":"traceutil/trace.go:171","msg":"trace[736907699] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"106.068194ms","start":"2026-05-19T17:11:22.079109Z","end":"2026-05-19T17:11:22.185177Z","steps":["trace[736907699] 'process raft request' (duration: 105.770337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:22.419398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.823796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:11:22.419565Z","caller":"traceutil/trace.go:171","msg":"trace[652123988] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"118.926653ms","start":"2026-05-19T17:11:22.300621Z","end":"2026-05-19T17:11:22.419547Z","steps":["trace[652123988] 'process raft request' (duration: 118.873332ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:22.419583Z","caller":"traceutil/trace.go:171","msg":"trace[754420951] linearizableReadLoop","detail":"{readStateIndex:5395; appliedIndex:5394; }","duration":"124.382478ms","start":"2026-05-19T17:11:22.295167Z","end":"2026-05-19T17:11:22.419549Z","steps":["trace[754420951] 'read index received' (duration: 52.491µs)","trace[754420951] 'applied index is now lower than readState.Index' (duration: 124.327407ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:11:22.419803Z","caller":"traceutil/trace.go:171","msg":"trace[1287487510] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"147.267694ms","start":"2026-05-19T17:11:22.272503Z","end":"2026-05-19T17:11:22.419771Z","steps":["trace[1287487510] 'process raft request' (duration: 13.993016ms)","trace[1287487510] 'compare' (duration: 132.714794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:11:22.419934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.765268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8t9jq.18b106acbfc16b3f\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-05-19T17:11:22.419976Z","caller":"traceutil/trace.go:171","msg":"trace[1886334739] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8t9jq.18b106acbfc16b3f; range_end:; response_count:1; response_revision:5205; }","duration":"124.84705ms","start":"2026-05-19T17:11:22.295118Z","end":"2026-05-19T17:11:22.419965Z","steps":["trace[1886334739] 'agreement among raft nodes before linearized reading' (duration: 124.711597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:22.42008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.656455ms","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-05-19T17:11:22.420195Z","caller":"traceutil/trace.go:171","msg":"trace[1807342100] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5205; }","duration":"103.844309ms","start":"2026-05-19T17:11:22.316325Z","end":"2026-05-19T17:11:22.420169Z","steps":["trace[1807342100] 'agreement among raft nodes before linearized reading' (duration: 103.503481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:22.70287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.923463ms","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-05-19T17:11:22.702977Z","caller":"traceutil/trace.go:171","msg":"trace[1301067466] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5208; }","duration":"180.096577ms","start":"2026-05-19T17:11:22.522854Z","end":"2026-05-19T17:11:22.70295Z","steps":["trace[1301067466] 'range keys from in-memory index tree' (duration: 179.779059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:22.702859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.148889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8t9jq.18b106aaffaf2851\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-05-19T17:11:22.703103Z","caller":"traceutil/trace.go:171","msg":"trace[690375098] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8t9jq.18b106aaffaf2851; range_end:; response_count:1; response_revision:5208; }","duration":"134.395556ms","start":"2026-05-19T17:11:22.568649Z","end":"2026-05-19T17:11:22.703044Z","steps":["trace[690375098] 'range keys from in-memory index tree' (duration: 133.981705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:25.784072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.389358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:11:25.784167Z","caller":"traceutil/trace.go:171","msg":"trace[1214544365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5249; }","duration":"107.541831ms","start":"2026-05-19T17:11:25.676603Z","end":"2026-05-19T17:11:25.784145Z","steps":["trace[1214544365] 'range keys from in-memory index tree' (duration: 107.303476ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:12:48.492959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-05-19T17:12:48.565815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"71.144865ms","hash":2242107347,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":34082816,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T17:12:48.565878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2242107347,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-05-19T17:17:48.500266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6100} {"level":"info","ts":"2026-05-19T17:17:48.599679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6100,"took":"96.969081ms","hash":3321409637,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":23556096,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-19T17:17:48.599771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3321409637,"revision":6100,"compact-revision":3000} {"level":"info","ts":"2026-05-19T17:17:57.350934Z","caller":"traceutil/trace.go:171","msg":"trace[1006219372] linearizableReadLoop","detail":"{readStateIndex:8603; appliedIndex:8602; }","duration":"180.126879ms","start":"2026-05-19T17:17:57.170784Z","end":"2026-05-19T17:17:57.350911Z","steps":["trace[1006219372] 'read index received' (duration: 179.847472ms)","trace[1006219372] 'applied index is now lower than readState.Index' (duration: 278.097µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:17:57.351048Z","caller":"traceutil/trace.go:171","msg":"trace[486739947] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"222.426271ms","start":"2026-05-19T17:17:57.128566Z","end":"2026-05-19T17:17:57.350992Z","steps":["trace[486739947] 'process raft request' (duration: 222.159805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:17:57.351204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.378606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:17:57.351326Z","caller":"traceutil/trace.go:171","msg":"trace[1309677403] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8314; }","duration":"180.536479ms","start":"2026-05-19T17:17:57.170765Z","end":"2026-05-19T17:17:57.351301Z","steps":["trace[1309677403] 'agreement among raft nodes before linearized reading' (duration: 180.275823ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:17:58.054668Z","caller":"traceutil/trace.go:171","msg":"trace[585885295] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"284.777946ms","start":"2026-05-19T17:17:57.769857Z","end":"2026-05-19T17:17:58.054635Z","steps":["trace[585885295] 'process raft request' (duration: 278.364021ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:17:58.055018Z","caller":"traceutil/trace.go:171","msg":"trace[1365519474] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"284.95161ms","start":"2026-05-19T17:17:57.770023Z","end":"2026-05-19T17:17:58.054975Z","steps":["trace[1365519474] 'process raft request' (duration: 284.554861ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:17:58.055196Z","caller":"traceutil/trace.go:171","msg":"trace[1834499564] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"285.060634ms","start":"2026-05-19T17:17:57.77011Z","end":"2026-05-19T17:17:58.055171Z","steps":["trace[1834499564] 'process raft request' (duration: 284.738865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:17:58.689832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.188456ms","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-05-19T17:17:58.689923Z","caller":"traceutil/trace.go:171","msg":"trace[1261910629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8323; }","duration":"104.325189ms","start":"2026-05-19T17:17:58.585578Z","end":"2026-05-19T17:17:58.689903Z","steps":["trace[1261910629] 'range keys from in-memory index tree' (duration: 103.94121ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:26.414417Z","caller":"traceutil/trace.go:171","msg":"trace[813393913] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"237.611478ms","start":"2026-05-19T17:18:26.176769Z","end":"2026-05-19T17:18:26.41438Z","steps":["trace[813393913] 'process raft request' (duration: 237.386823ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:26.563436Z","caller":"traceutil/trace.go:171","msg":"trace[1942511585] linearizableReadLoop","detail":"{readStateIndex:8821; appliedIndex:8819; }","duration":"362.869533ms","start":"2026-05-19T17:18:26.200543Z","end":"2026-05-19T17:18:26.563413Z","steps":["trace[1942511585] 'read index received' (duration: 213.64373ms)","trace[1942511585] 'applied index is now lower than readState.Index' (duration: 149.221543ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:18:26.563473Z","caller":"traceutil/trace.go:171","msg":"trace[1446294523] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"385.464569ms","start":"2026-05-19T17:18:26.177983Z","end":"2026-05-19T17:18:26.563448Z","steps":["trace[1446294523] 'process raft request' (duration: 368.680653ms)","trace[1446294523] 'compare' (duration: 16.372386ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:18:26.563617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:18:26.177971Z","time spent":"385.556361ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:18:26.565978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.435105ms","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-05-19T17:18:26.566036Z","caller":"traceutil/trace.go:171","msg":"trace[289472557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8525; }","duration":"365.543348ms","start":"2026-05-19T17:18:26.200476Z","end":"2026-05-19T17:18:26.56602Z","steps":["trace[289472557] 'agreement among raft nodes before linearized reading' (duration: 363.079968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:26.566036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.951021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"warn","ts":"2026-05-19T17:18:26.566071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:18:26.200451Z","time spent":"365.611709ms","remote":"127.0.0.1:58902","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-05-19T17:18:26.566085Z","caller":"traceutil/trace.go:171","msg":"trace[1805102023] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8525; }","duration":"232.042694ms","start":"2026-05-19T17:18:26.334024Z","end":"2026-05-19T17:18:26.566067Z","steps":["trace[1805102023] 'agreement among raft nodes before linearized reading' (duration: 231.89541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:26.566081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.550203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T17:18:26.566201Z","caller":"traceutil/trace.go:171","msg":"trace[1911707877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8525; }","duration":"352.728497ms","start":"2026-05-19T17:18:26.213446Z","end":"2026-05-19T17:18:26.566175Z","steps":["trace[1911707877] 'agreement among raft nodes before linearized reading' (duration: 352.388419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:26.566267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:18:26.213433Z","time spent":"352.824139ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T17:18:26.566399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.812807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T17:18:26.566446Z","caller":"traceutil/trace.go:171","msg":"trace[1672101327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8525; }","duration":"344.880748ms","start":"2026-05-19T17:18:26.221554Z","end":"2026-05-19T17:18:26.566435Z","steps":["trace[1672101327] 'agreement among raft nodes before linearized reading' (duration: 344.763145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:26.566475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:18:26.221542Z","time spent":"344.926759ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:18:30.793224Z","caller":"traceutil/trace.go:171","msg":"trace[240293458] linearizableReadLoop","detail":"{readStateIndex:8843; appliedIndex:8842; }","duration":"205.855361ms","start":"2026-05-19T17:18:30.587335Z","end":"2026-05-19T17:18:30.79319Z","steps":["trace[240293458] 'read index received' (duration: 188.173774ms)","trace[240293458] 'applied index is now lower than readState.Index' (duration: 17.679817ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:18:30.793276Z","caller":"traceutil/trace.go:171","msg":"trace[1934581558] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"217.840921ms","start":"2026-05-19T17:18:30.575377Z","end":"2026-05-19T17:18:30.793218Z","steps":["trace[1934581558] 'process raft request' (duration: 200.196745ms)","trace[1934581558] 'compare' (duration: 17.219726ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:18:30.793493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.771837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:18:30.793487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.118007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-19T17:18:30.793547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.797245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T17:18:30.793592Z","caller":"traceutil/trace.go:171","msg":"trace[374640146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8545; }","duration":"206.277251ms","start":"2026-05-19T17:18:30.587291Z","end":"2026-05-19T17:18:30.793569Z","steps":["trace[374640146] 'agreement among raft nodes before linearized reading' (duration: 205.980994ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:30.793649Z","caller":"traceutil/trace.go:171","msg":"trace[962661011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8545; }","duration":"204.925978ms","start":"2026-05-19T17:18:30.588677Z","end":"2026-05-19T17:18:30.793603Z","steps":["trace[962661011] 'agreement among raft nodes before linearized reading' (duration: 204.725183ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:30.793536Z","caller":"traceutil/trace.go:171","msg":"trace[1252483069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8545; }","duration":"113.8919ms","start":"2026-05-19T17:18:30.679632Z","end":"2026-05-19T17:18:30.793524Z","steps":["trace[1252483069] 'agreement among raft nodes before linearized reading' (duration: 113.774707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:30.793815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.878367ms","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-05-19T17:18:30.793909Z","caller":"traceutil/trace.go:171","msg":"trace[1645943091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8545; }","duration":"205.035821ms","start":"2026-05-19T17:18:30.588853Z","end":"2026-05-19T17:18:30.793889Z","steps":["trace[1645943091] 'agreement among raft nodes before linearized reading' (duration: 204.746574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:30.793678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.383673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:18:30.794502Z","caller":"traceutil/trace.go:171","msg":"trace[792456147] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:8545; }","duration":"115.292214ms","start":"2026-05-19T17:18:30.679188Z","end":"2026-05-19T17:18:30.79448Z","steps":["trace[792456147] 'agreement among raft nodes before linearized reading' (duration: 114.351751ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:40.047608Z","caller":"traceutil/trace.go:171","msg":"trace[676932043] linearizableReadLoop","detail":"{readStateIndex:8944; appliedIndex:8941; }","duration":"123.40698ms","start":"2026-05-19T17:18:39.924169Z","end":"2026-05-19T17:18:40.047576Z","steps":["trace[676932043] 'read index received' (duration: 4.223753ms)","trace[676932043] 'applied index is now lower than readState.Index' (duration: 119.182277ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:18:40.047648Z","caller":"traceutil/trace.go:171","msg":"trace[1369668233] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"145.133134ms","start":"2026-05-19T17:18:39.902469Z","end":"2026-05-19T17:18:40.047602Z","steps":["trace[1369668233] 'process raft request' (duration: 144.853017ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:40.047906Z","caller":"traceutil/trace.go:171","msg":"trace[1727828868] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"124.085487ms","start":"2026-05-19T17:18:39.923749Z","end":"2026-05-19T17:18:40.047835Z","steps":["trace[1727828868] 'process raft request' (duration: 123.763079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:40.04796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.764369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qpgsp\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-05-19T17:18:40.048018Z","caller":"traceutil/trace.go:171","msg":"trace[1296928850] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qpgsp; range_end:; response_count:1; response_revision:8641; }","duration":"123.897832ms","start":"2026-05-19T17:18:39.924106Z","end":"2026-05-19T17:18:40.048004Z","steps":["trace[1296928850] 'agreement among raft nodes before linearized reading' (duration: 123.625795ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:40.690921Z","caller":"traceutil/trace.go:171","msg":"trace[265726703] linearizableReadLoop","detail":"{readStateIndex:8949; appliedIndex:8948; }","duration":"133.182055ms","start":"2026-05-19T17:18:40.557715Z","end":"2026-05-19T17:18:40.690897Z","steps":["trace[265726703] 'read index received' (duration: 102.178307ms)","trace[265726703] 'applied index is now lower than readState.Index' (duration: 30.996978ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:18:40.691253Z","caller":"traceutil/trace.go:171","msg":"trace[712612481] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"158.071417ms","start":"2026-05-19T17:18:40.532981Z","end":"2026-05-19T17:18:40.691053Z","steps":["trace[712612481] 'process raft request' (duration: 127.018197ms)","trace[712612481] 'compare' (duration: 30.772613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:18:40.691333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.600005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-05-19T17:18:40.691434Z","caller":"traceutil/trace.go:171","msg":"trace[410207281] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8646; }","duration":"133.748479ms","start":"2026-05-19T17:18:40.557664Z","end":"2026-05-19T17:18:40.691413Z","steps":["trace[410207281] 'agreement among raft nodes before linearized reading' (duration: 133.465372ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:19:02.247171Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ec.wal"} {"level":"info","ts":"2026-05-19T17:20:35.1348Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T17:20:35.140816Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T17:20:35.140936Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T17:22:48.507753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8270} {"level":"info","ts":"2026-05-19T17:22:48.604468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8270,"took":"94.165812ms","hash":1830738738,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":23904256,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-19T17:22:48.60459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1830738738,"revision":8270,"compact-revision":6100} {"level":"info","ts":"2026-05-19T17:25:37.352027Z","caller":"traceutil/trace.go:171","msg":"trace[1164417140] transaction","detail":"{read_only:false; response_revision:11835; number_of_response:1; }","duration":"181.868098ms","start":"2026-05-19T17:25:37.170131Z","end":"2026-05-19T17:25:37.351999Z","steps":["trace[1164417140] 'process raft request' (duration: 181.619152ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:25:37.362305Z","caller":"traceutil/trace.go:171","msg":"trace[909528726] transaction","detail":"{read_only:false; response_revision:11836; number_of_response:1; }","duration":"191.879461ms","start":"2026-05-19T17:25:37.170394Z","end":"2026-05-19T17:25:37.362273Z","steps":["trace[909528726] 'process raft request' (duration: 191.668316ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:25:37.36246Z","caller":"traceutil/trace.go:171","msg":"trace[2063660822] linearizableReadLoop","detail":"{readStateIndex:12240; appliedIndex:12237; }","duration":"147.54703ms","start":"2026-05-19T17:25:37.214876Z","end":"2026-05-19T17:25:37.362423Z","steps":["trace[2063660822] 'read index received' (duration: 136.928364ms)","trace[2063660822] 'applied index is now lower than readState.Index' (duration: 10.616486ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:25:37.362519Z","caller":"traceutil/trace.go:171","msg":"trace[1675017093] transaction","detail":"{read_only:false; response_revision:11837; number_of_response:1; }","duration":"169.035899ms","start":"2026-05-19T17:25:37.193471Z","end":"2026-05-19T17:25:37.362507Z","steps":["trace[1675017093] 'process raft request' (duration: 168.748813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:25:37.362643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.745116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:25:37.362692Z","caller":"traceutil/trace.go:171","msg":"trace[113833893] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11837; }","duration":"147.870219ms","start":"2026-05-19T17:25:37.21481Z","end":"2026-05-19T17:25:37.36268Z","steps":["trace[113833893] 'agreement among raft nodes before linearized reading' (duration: 147.754756ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:27:48.520022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10496} {"level":"info","ts":"2026-05-19T17:27:48.608169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10496,"took":"85.162ms","hash":3603740625,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":25931776,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T17:27:48.608267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3603740625,"revision":10496,"compact-revision":8270} {"level":"warn","ts":"2026-05-19T17:32:20.678736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.905845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-7659bd74f8-24ccx\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-05-19T17:32:20.678867Z","caller":"traceutil/trace.go:171","msg":"trace[1855372857] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-7659bd74f8-24ccx; range_end:; response_count:1; response_revision:14640; }","duration":"303.108949ms","start":"2026-05-19T17:32:20.375733Z","end":"2026-05-19T17:32:20.678842Z","steps":["trace[1855372857] 'range keys from in-memory index tree' (duration: 302.680379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:32:20.678943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:32:20.375707Z","time spent":"303.225353ms","remote":"127.0.0.1:58816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/openstack/ovn-northd-7659bd74f8-24ccx\" "} {"level":"info","ts":"2026-05-19T17:32:20.696657Z","caller":"traceutil/trace.go:171","msg":"trace[1324305091] linearizableReadLoop","detail":"{readStateIndex:15140; appliedIndex:15139; }","duration":"121.599518ms","start":"2026-05-19T17:32:20.575022Z","end":"2026-05-19T17:32:20.696622Z","steps":["trace[1324305091] 'read index received' (duration: 121.478535ms)","trace[1324305091] 'applied index is now lower than readState.Index' (duration: 119.403µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:32:20.696854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.809603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:32:20.696921Z","caller":"traceutil/trace.go:171","msg":"trace[112805804] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:14641; }","duration":"121.947056ms","start":"2026-05-19T17:32:20.574958Z","end":"2026-05-19T17:32:20.696905Z","steps":["trace[112805804] 'agreement among raft nodes before linearized reading' (duration: 121.824153ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:32:20.696979Z","caller":"traceutil/trace.go:171","msg":"trace[1408384043] transaction","detail":"{read_only:false; response_revision:14641; number_of_response:1; }","duration":"298.949558ms","start":"2026-05-19T17:32:20.397903Z","end":"2026-05-19T17:32:20.696852Z","steps":["trace[1408384043] 'process raft request' (duration: 298.489177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:32:20.714668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.85349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-drsq6.18b107d4f7831aab\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-05-19T17:32:20.714899Z","caller":"traceutil/trace.go:171","msg":"trace[752310922] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-drsq6.18b107d4f7831aab; range_end:; response_count:1; response_revision:14642; }","duration":"130.072805ms","start":"2026-05-19T17:32:20.584777Z","end":"2026-05-19T17:32:20.71485Z","steps":["trace[752310922] 'agreement among raft nodes before linearized reading' (duration: 129.678905ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:32:48.527204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12726} {"level":"info","ts":"2026-05-19T17:32:48.608436Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12726,"took":"77.975523ms","hash":672264377,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":28766208,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T17:32:48.608496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":672264377,"revision":12726,"compact-revision":10496} {"level":"warn","ts":"2026-05-19T17:33:26.132473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.563669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:33:26.13262Z","caller":"traceutil/trace.go:171","msg":"trace[128439195] transaction","detail":"{read_only:false; response_revision:15549; number_of_response:1; }","duration":"125.516451ms","start":"2026-05-19T17:33:26.007082Z","end":"2026-05-19T17:33:26.132598Z","steps":["trace[128439195] 'process raft request' (duration: 19.738557ms)","trace[128439195] 'compare' (duration: 105.432306ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:33:59.510999Z","caller":"traceutil/trace.go:171","msg":"trace[1075067746] transaction","detail":"{read_only:false; response_revision:15722; number_of_response:1; }","duration":"111.598635ms","start":"2026-05-19T17:33:59.399359Z","end":"2026-05-19T17:33:59.510958Z","steps":["trace[1075067746] 'process raft request' (duration: 111.325028ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:33:59.86264Z","caller":"traceutil/trace.go:171","msg":"trace[976034784] transaction","detail":"{read_only:false; response_revision:15723; number_of_response:1; }","duration":"134.205461ms","start":"2026-05-19T17:33:59.728406Z","end":"2026-05-19T17:33:59.862612Z","steps":["trace[976034784] 'process raft request' (duration: 134.046478ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:34:00.239338Z","caller":"traceutil/trace.go:171","msg":"trace[980904078] transaction","detail":"{read_only:false; response_revision:15725; number_of_response:1; }","duration":"119.096176ms","start":"2026-05-19T17:34:00.120218Z","end":"2026-05-19T17:34:00.239314Z","steps":["trace[980904078] 'process raft request' (duration: 114.073794ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:34:00.239528Z","caller":"traceutil/trace.go:171","msg":"trace[806653912] transaction","detail":"{read_only:false; response_revision:15726; number_of_response:1; }","duration":"119.26666ms","start":"2026-05-19T17:34:00.120237Z","end":"2026-05-19T17:34:00.239504Z","steps":["trace[806653912] 'process raft request' (duration: 119.015604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:36:53.152402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.434275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:886550"} {"level":"info","ts":"2026-05-19T17:36:53.152489Z","caller":"traceutil/trace.go:171","msg":"trace[1489980624] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17111; }","duration":"100.548398ms","start":"2026-05-19T17:36:53.051923Z","end":"2026-05-19T17:36:53.152472Z","steps":["trace[1489980624] 'agreement among raft nodes before linearized reading' (duration: 99.680548ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:36:53.153214Z","caller":"traceutil/trace.go:171","msg":"trace[628961999] transaction","detail":"{read_only:false; response_revision:17111; number_of_response:1; }","duration":"103.051078ms","start":"2026-05-19T17:36:53.05015Z","end":"2026-05-19T17:36:53.153201Z","steps":["trace[628961999] 'process raft request' (duration: 99.715617ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:37:48.53317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15022} {"level":"info","ts":"2026-05-19T17:37:48.622581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15022,"took":"85.781272ms","hash":783084112,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":30781440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T17:37:48.622657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":783084112,"revision":15022,"compact-revision":12726} {"level":"info","ts":"2026-05-19T17:39:37.843566Z","caller":"traceutil/trace.go:171","msg":"trace[245794644] transaction","detail":"{read_only:false; response_revision:18301; number_of_response:1; }","duration":"155.665371ms","start":"2026-05-19T17:39:37.687866Z","end":"2026-05-19T17:39:37.843531Z","steps":["trace[245794644] 'process raft request' (duration: 155.468786ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:39:37.848367Z","caller":"traceutil/trace.go:171","msg":"trace[2101242859] transaction","detail":"{read_only:false; response_revision:18302; number_of_response:1; }","duration":"155.306462ms","start":"2026-05-19T17:39:37.693042Z","end":"2026-05-19T17:39:37.848348Z","steps":["trace[2101242859] 'process raft request' (duration: 155.074917ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:39:37.848373Z","caller":"traceutil/trace.go:171","msg":"trace[803454624] linearizableReadLoop","detail":"{readStateIndex:18921; appliedIndex:18919; }","duration":"123.146265ms","start":"2026-05-19T17:39:37.725205Z","end":"2026-05-19T17:39:37.848351Z","steps":["trace[803454624] 'read index received' (duration: 118.187695ms)","trace[803454624] 'applied index is now lower than readState.Index' (duration: 4.95725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:39:37.84888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.667929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-05-19T17:39:37.856107Z","caller":"traceutil/trace.go:171","msg":"trace[1213018187] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:18303; }","duration":"130.905233ms","start":"2026-05-19T17:39:37.725163Z","end":"2026-05-19T17:39:37.856069Z","steps":["trace[1213018187] 'agreement among raft nodes before linearized reading' (duration: 123.639267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:39:37.856336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.897443ms","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-05-19T17:39:37.856374Z","caller":"traceutil/trace.go:171","msg":"trace[1343517784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18304; }","duration":"118.988315ms","start":"2026-05-19T17:39:37.737376Z","end":"2026-05-19T17:39:37.856364Z","steps":["trace[1343517784] 'agreement among raft nodes before linearized reading' (duration: 118.824451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:39:44.151781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.105273ms","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-05-19T17:39:44.152263Z","caller":"traceutil/trace.go:171","msg":"trace[2008217347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18360; }","duration":"142.657717ms","start":"2026-05-19T17:39:44.009584Z","end":"2026-05-19T17:39:44.152242Z","steps":["trace[2008217347] 'range keys from in-memory index tree' (duration: 141.893838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:39:44.597088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.733641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:39:44.597203Z","caller":"traceutil/trace.go:171","msg":"trace[2101516515] transaction","detail":"{read_only:false; response_revision:18363; number_of_response:1; }","duration":"267.458363ms","start":"2026-05-19T17:39:44.329723Z","end":"2026-05-19T17:39:44.597181Z","steps":["trace[2101516515] 'process raft request' (duration: 138.542227ms)","trace[2101516515] 'compare' (duration: 128.385693ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:39:44.765981Z","caller":"traceutil/trace.go:171","msg":"trace[1377557423] linearizableReadLoop","detail":"{readStateIndex:18984; appliedIndex:18983; }","duration":"115.870351ms","start":"2026-05-19T17:39:44.650085Z","end":"2026-05-19T17:39:44.765955Z","steps":["trace[1377557423] 'read index received' (duration: 102.576379ms)","trace[1377557423] 'applied index is now lower than readState.Index' (duration: 13.293072ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:39:44.766016Z","caller":"traceutil/trace.go:171","msg":"trace[1035343100] transaction","detail":"{read_only:false; response_revision:18364; number_of_response:1; }","duration":"219.815023ms","start":"2026-05-19T17:39:44.546171Z","end":"2026-05-19T17:39:44.765986Z","steps":["trace[1035343100] 'process raft request' (duration: 206.4741ms)","trace[1035343100] 'compare' (duration: 12.961062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:39:44.766147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.040695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:39:44.766214Z","caller":"traceutil/trace.go:171","msg":"trace[1901357592] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:18364; }","duration":"116.146118ms","start":"2026-05-19T17:39:44.650059Z","end":"2026-05-19T17:39:44.766205Z","steps":["trace[1901357592] 'agreement among raft nodes before linearized reading' (duration: 116.026285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:39:44.767216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.829385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wbh9j\" ","response":"range_response_count:1 size:9709"} {"level":"info","ts":"2026-05-19T17:39:44.767303Z","caller":"traceutil/trace.go:171","msg":"trace[409742979] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wbh9j; range_end:; response_count:1; response_revision:18365; }","duration":"114.961868ms","start":"2026-05-19T17:39:44.652318Z","end":"2026-05-19T17:39:44.76728Z","steps":["trace[409742979] 'agreement among raft nodes before linearized reading' (duration: 114.716663ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:39:44.967675Z","caller":"traceutil/trace.go:171","msg":"trace[1223252634] transaction","detail":"{read_only:false; response_revision:18366; number_of_response:1; }","duration":"128.737001ms","start":"2026-05-19T17:39:44.83892Z","end":"2026-05-19T17:39:44.967657Z","steps":["trace[1223252634] 'process raft request' (duration: 128.446533ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:41:30.971391Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T17:41:30.976616Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T17:41:30.97671Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-19T17:42:48.540895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17467} {"level":"info","ts":"2026-05-19T17:42:48.637555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17467,"took":"93.174333ms","hash":665428741,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":34308096,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T17:42:48.637644Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":665428741,"revision":17467,"compact-revision":15022} {"level":"warn","ts":"2026-05-19T17:45:03.97584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.706122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:45:03.976026Z","caller":"traceutil/trace.go:171","msg":"trace[1189457927] transaction","detail":"{read_only:false; response_revision:20795; number_of_response:1; }","duration":"449.87484ms","start":"2026-05-19T17:45:03.526125Z","end":"2026-05-19T17:45:03.976Z","steps":["trace[1189457927] 'process raft request' (duration: 163.900321ms)","trace[1189457927] 'compare' (duration: 285.445636ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:45:03.976112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:45:03.526099Z","time spent":"449.969112ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:45:03.977962Z","caller":"traceutil/trace.go:171","msg":"trace[1333729050] transaction","detail":"{read_only:false; response_revision:20796; number_of_response:1; }","duration":"262.322865ms","start":"2026-05-19T17:45:03.715585Z","end":"2026-05-19T17:45:03.977908Z","steps":["trace[1333729050] 'process raft request' (duration: 262.187862ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:45:03.978065Z","caller":"traceutil/trace.go:171","msg":"trace[18563671] linearizableReadLoop","detail":"{readStateIndex:21497; appliedIndex:21497; }","duration":"245.223335ms","start":"2026-05-19T17:45:03.732809Z","end":"2026-05-19T17:45:03.978032Z","steps":["trace[18563671] 'read index received' (duration: 245.209785ms)","trace[18563671] 'applied index is now lower than readState.Index' (duration: 10.72µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:45:03.978416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.577164ms","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-05-19T17:45:03.978469Z","caller":"traceutil/trace.go:171","msg":"trace[1283275948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20796; }","duration":"245.678446ms","start":"2026-05-19T17:45:03.732778Z","end":"2026-05-19T17:45:03.978456Z","steps":["trace[1283275948] 'agreement among raft nodes before linearized reading' (duration: 245.399249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:04.333744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.624592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:45:04.333938Z","caller":"traceutil/trace.go:171","msg":"trace[884645421] transaction","detail":"{read_only:false; response_revision:20797; number_of_response:1; }","duration":"472.906979ms","start":"2026-05-19T17:45:03.860962Z","end":"2026-05-19T17:45:04.333869Z","steps":["trace[884645421] 'process raft request' (duration: 288.067072ms)","trace[884645421] 'compare' (duration: 184.210862ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:45:04.334Z","caller":"traceutil/trace.go:171","msg":"trace[1953220307] linearizableReadLoop","detail":"{readStateIndex:21498; appliedIndex:21497; }","duration":"355.828786ms","start":"2026-05-19T17:45:03.978139Z","end":"2026-05-19T17:45:04.333968Z","steps":["trace[1953220307] 'read index received' (duration: 170.865806ms)","trace[1953220307] 'applied index is now lower than readState.Index' (duration: 184.96039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:45:04.334166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:45:03.860936Z","time spent":"473.128004ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:45:04.334264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.413944ms","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-05-19T17:45:04.333994Z","caller":"traceutil/trace.go:171","msg":"trace[1974144922] transaction","detail":"{read_only:false; response_revision:20798; number_of_response:1; }","duration":"350.517172ms","start":"2026-05-19T17:45:03.98346Z","end":"2026-05-19T17:45:04.333977Z","steps":["trace[1974144922] 'process raft request' (duration: 350.407519ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:45:04.334299Z","caller":"traceutil/trace.go:171","msg":"trace[1022974055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20798; }","duration":"600.482376ms","start":"2026-05-19T17:45:03.733807Z","end":"2026-05-19T17:45:04.33429Z","steps":["trace[1022974055] 'agreement among raft nodes before linearized reading' (duration: 600.304841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:04.33433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:45:03.733803Z","time spent":"600.516786ms","remote":"127.0.0.1:58902","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":"warn","ts":"2026-05-19T17:45:04.334343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:45:03.983436Z","time spent":"350.868981ms","remote":"127.0.0.1:58902","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-05-19T17:45:04.346234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.780249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:45:04.34635Z","caller":"traceutil/trace.go:171","msg":"trace[1722965871] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:20799; }","duration":"354.943033ms","start":"2026-05-19T17:45:03.991374Z","end":"2026-05-19T17:45:04.346317Z","steps":["trace[1722965871] 'agreement among raft nodes before linearized reading' (duration: 354.744358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:04.346411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:45:03.991352Z","time spent":"355.046415ms","remote":"127.0.0.1:44708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:45:04.346584Z","caller":"traceutil/trace.go:171","msg":"trace[149746740] transaction","detail":"{read_only:false; response_revision:20799; number_of_response:1; }","duration":"127.883524ms","start":"2026-05-19T17:45:04.218654Z","end":"2026-05-19T17:45:04.346537Z","steps":["trace[149746740] 'process raft request' (duration: 127.30466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:04.72601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.503755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:45:04.726096Z","caller":"traceutil/trace.go:171","msg":"trace[561854642] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:20801; }","duration":"129.621738ms","start":"2026-05-19T17:45:04.596455Z","end":"2026-05-19T17:45:04.726077Z","steps":["trace[561854642] 'count revisions from in-memory index tree' (duration: 129.28164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:04.726377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.836456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-19T17:45:04.726404Z","caller":"traceutil/trace.go:171","msg":"trace[523312798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20801; }","duration":"104.884677ms","start":"2026-05-19T17:45:04.621513Z","end":"2026-05-19T17:45:04.726398Z","steps":["trace[523312798] 'range keys from in-memory index tree' (duration: 104.64284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:04.726464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.05579ms","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-05-19T17:45:04.726571Z","caller":"traceutil/trace.go:171","msg":"trace[527976578] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20801; }","duration":"237.240075ms","start":"2026-05-19T17:45:04.489307Z","end":"2026-05-19T17:45:04.726547Z","steps":["trace[527976578] 'range keys from in-memory index tree' (duration: 236.900446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:04.726796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.199999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:45:04.727127Z","caller":"traceutil/trace.go:171","msg":"trace[1310103582] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:20801; }","duration":"277.759693ms","start":"2026-05-19T17:45:04.449342Z","end":"2026-05-19T17:45:04.727101Z","steps":["trace[1310103582] 'count revisions from in-memory index tree' (duration: 277.031365ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:45:04.992034Z","caller":"traceutil/trace.go:171","msg":"trace[385498347] linearizableReadLoop","detail":"{readStateIndex:21503; appliedIndex:21502; }","duration":"258.051857ms","start":"2026-05-19T17:45:04.733963Z","end":"2026-05-19T17:45:04.992015Z","steps":["trace[385498347] 'read index received' (duration: 257.77267ms)","trace[385498347] 'applied index is now lower than readState.Index' (duration: 278.317µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:45:04.992168Z","caller":"traceutil/trace.go:171","msg":"trace[1529481478] transaction","detail":"{read_only:false; response_revision:20802; number_of_response:1; }","duration":"260.151281ms","start":"2026-05-19T17:45:04.731967Z","end":"2026-05-19T17:45:04.992119Z","steps":["trace[1529481478] 'process raft request' (duration: 259.852813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:04.992227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.253673ms","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-05-19T17:45:04.992391Z","caller":"traceutil/trace.go:171","msg":"trace[2049476988] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20802; }","duration":"258.418176ms","start":"2026-05-19T17:45:04.733928Z","end":"2026-05-19T17:45:04.992346Z","steps":["trace[2049476988] 'agreement among raft nodes before linearized reading' (duration: 258.1674ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:45:05.2649Z","caller":"traceutil/trace.go:171","msg":"trace[111476078] linearizableReadLoop","detail":"{readStateIndex:21504; appliedIndex:21503; }","duration":"256.669793ms","start":"2026-05-19T17:45:05.008181Z","end":"2026-05-19T17:45:05.264851Z","steps":["trace[111476078] 'read index received' (duration: 239.562593ms)","trace[111476078] 'applied index is now lower than readState.Index' (duration: 17.10542ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:45:05.26528Z","caller":"traceutil/trace.go:171","msg":"trace[252491286] transaction","detail":"{read_only:false; response_revision:20803; number_of_response:1; }","duration":"263.249178ms","start":"2026-05-19T17:45:05.002011Z","end":"2026-05-19T17:45:05.26526Z","steps":["trace[252491286] 'process raft request' (duration: 245.702537ms)","trace[252491286] 'compare' (duration: 16.955556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:45:05.265608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.412182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-19T17:45:05.265657Z","caller":"traceutil/trace.go:171","msg":"trace[1357237739] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:20803; }","duration":"257.511904ms","start":"2026-05-19T17:45:05.008128Z","end":"2026-05-19T17:45:05.26564Z","steps":["trace[1357237739] 'agreement among raft nodes before linearized reading' (duration: 257.34732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:45:05.265892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.10109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:45:05.26593Z","caller":"traceutil/trace.go:171","msg":"trace[2025190952] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:20803; }","duration":"202.142161ms","start":"2026-05-19T17:45:05.063775Z","end":"2026-05-19T17:45:05.265917Z","steps":["trace[2025190952] 'agreement among raft nodes before linearized reading' (duration: 202.07569ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:47:48.545924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19827} {"level":"info","ts":"2026-05-19T17:47:48.64897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19827,"took":"100.593609ms","hash":178398328,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":30396416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T17:47:48.649047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":178398328,"revision":19827,"compact-revision":17467} {"level":"info","ts":"2026-05-19T17:49:03.791853Z","caller":"traceutil/trace.go:171","msg":"trace[1217410008] transaction","detail":"{read_only:false; response_revision:23256; number_of_response:1; }","duration":"211.316081ms","start":"2026-05-19T17:49:03.580504Z","end":"2026-05-19T17:49:03.79182Z","steps":["trace[1217410008] 'process raft request' (duration: 211.180168ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:49:03.905489Z","caller":"traceutil/trace.go:171","msg":"trace[1458133291] linearizableReadLoop","detail":"{readStateIndex:24034; appliedIndex:24032; }","duration":"262.243854ms","start":"2026-05-19T17:49:03.64321Z","end":"2026-05-19T17:49:03.905454Z","steps":["trace[1458133291] 'read index received' (duration: 148.553735ms)","trace[1458133291] 'applied index is now lower than readState.Index' (duration: 113.688469ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:49:03.905581Z","caller":"traceutil/trace.go:171","msg":"trace[569870321] transaction","detail":"{read_only:false; response_revision:23258; number_of_response:1; }","duration":"212.255586ms","start":"2026-05-19T17:49:03.693281Z","end":"2026-05-19T17:49:03.905537Z","steps":["trace[569870321] 'process raft request' (duration: 212.03875ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:49:03.90557Z","caller":"traceutil/trace.go:171","msg":"trace[1322818725] transaction","detail":"{read_only:false; response_revision:23257; number_of_response:1; }","duration":"323.979185ms","start":"2026-05-19T17:49:03.581537Z","end":"2026-05-19T17:49:03.905516Z","steps":["trace[1322818725] 'process raft request' (duration: 323.530524ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:49:03.905677Z","caller":"traceutil/trace.go:171","msg":"trace[826884363] transaction","detail":"{read_only:false; response_revision:23259; number_of_response:1; }","duration":"174.747413ms","start":"2026-05-19T17:49:03.730863Z","end":"2026-05-19T17:49:03.90561Z","steps":["trace[826884363] 'process raft request' (duration: 174.543738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:49:03.905816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:49:03.581519Z","time spent":"324.16985ms","remote":"127.0.0.1:58902","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-05-19T17:49:03.905797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.562711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:49:03.905892Z","caller":"traceutil/trace.go:171","msg":"trace[1401430828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23259; }","duration":"262.724895ms","start":"2026-05-19T17:49:03.643145Z","end":"2026-05-19T17:49:03.90587Z","steps":["trace[1401430828] 'agreement among raft nodes before linearized reading' (duration: 262.486369ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:25.79703Z","caller":"traceutil/trace.go:171","msg":"trace[977199832] linearizableReadLoop","detail":"{readStateIndex:26147; appliedIndex:26146; }","duration":"122.01837ms","start":"2026-05-19T17:52:25.674986Z","end":"2026-05-19T17:52:25.797004Z","steps":["trace[977199832] 'read index received' (duration: 121.822025ms)","trace[977199832] 'applied index is now lower than readState.Index' (duration: 195.335µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:25.797437Z","caller":"traceutil/trace.go:171","msg":"trace[1425211533] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"134.657155ms","start":"2026-05-19T17:52:25.662766Z","end":"2026-05-19T17:52:25.797423Z","steps":["trace[1425211533] 'process raft request' (duration: 134.087301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:25.798581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.559646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:25.798707Z","caller":"traceutil/trace.go:171","msg":"trace[1099942593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25318; }","duration":"123.743791ms","start":"2026-05-19T17:52:25.674936Z","end":"2026-05-19T17:52:25.79868Z","steps":["trace[1099942593] 'agreement among raft nodes before linearized reading' (duration: 123.527126ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:31.168005Z","caller":"traceutil/trace.go:171","msg":"trace[228717905] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"138.97154ms","start":"2026-05-19T17:52:31.029012Z","end":"2026-05-19T17:52:31.167984Z","steps":["trace[228717905] 'process raft request' (duration: 138.721934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:31.440592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.89486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:31.440705Z","caller":"traceutil/trace.go:171","msg":"trace[1919030653] linearizableReadLoop","detail":"{readStateIndex:26194; appliedIndex:26193; }","duration":"186.023956ms","start":"2026-05-19T17:52:31.254665Z","end":"2026-05-19T17:52:31.440689Z","steps":["trace[1919030653] 'read index received' (duration: 38.793818ms)","trace[1919030653] 'applied index is now lower than readState.Index' (duration: 147.228688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:31.440841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.18085ms","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-05-19T17:52:31.440866Z","caller":"traceutil/trace.go:171","msg":"trace[1996212499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25364; }","duration":"186.207691ms","start":"2026-05-19T17:52:31.25465Z","end":"2026-05-19T17:52:31.440858Z","steps":["trace[1996212499] 'agreement among raft nodes before linearized reading' (duration: 186.082018ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:31.441063Z","caller":"traceutil/trace.go:171","msg":"trace[1245278895] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"230.399129ms","start":"2026-05-19T17:52:31.210619Z","end":"2026-05-19T17:52:31.441018Z","steps":["trace[1245278895] 'process raft request' (duration: 82.892254ms)","trace[1245278895] 'compare' (duration: 146.681215ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:31.910866Z","caller":"traceutil/trace.go:171","msg":"trace[2143019695] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"187.036412ms","start":"2026-05-19T17:52:31.723794Z","end":"2026-05-19T17:52:31.91083Z","steps":["trace[2143019695] 'process raft request' (duration: 185.943246ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:36.159046Z","caller":"traceutil/trace.go:171","msg":"trace[617206641] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"206.245165ms","start":"2026-05-19T17:52:35.952782Z","end":"2026-05-19T17:52:36.159027Z","steps":["trace[617206641] 'process raft request' (duration: 134.03082ms)","trace[617206641] 'compare' (duration: 71.962369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:36.597148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.388697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:36.597286Z","caller":"traceutil/trace.go:171","msg":"trace[1474260721] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"450.985842ms","start":"2026-05-19T17:52:36.14628Z","end":"2026-05-19T17:52:36.597266Z","steps":["trace[1474260721] 'process raft request' (duration: 321.368839ms)","trace[1474260721] 'compare' (duration: 129.254514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:36.59734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:36.146243Z","time spent":"451.072834ms","remote":"127.0.0.1:58902","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-05-19T17:52:36.670206Z","caller":"traceutil/trace.go:171","msg":"trace[674717566] linearizableReadLoop","detail":"{readStateIndex:26231; appliedIndex:26228; }","duration":"319.688107ms","start":"2026-05-19T17:52:36.350492Z","end":"2026-05-19T17:52:36.67018Z","steps":["trace[674717566] 'read index received' (duration: 117.173082ms)","trace[674717566] 'applied index is now lower than readState.Index' (duration: 202.513865ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:36.670302Z","caller":"traceutil/trace.go:171","msg":"trace[874604090] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"521.122508ms","start":"2026-05-19T17:52:36.149106Z","end":"2026-05-19T17:52:36.670228Z","steps":["trace[874604090] 'process raft request' (duration: 520.703448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:36.670383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.36172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:36.670308Z","caller":"traceutil/trace.go:171","msg":"trace[38826443] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"323.808788ms","start":"2026-05-19T17:52:36.346453Z","end":"2026-05-19T17:52:36.670262Z","steps":["trace[38826443] 'process raft request' (duration: 323.671125ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:36.670431Z","caller":"traceutil/trace.go:171","msg":"trace[752204311] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25400; }","duration":"300.450743ms","start":"2026-05-19T17:52:36.369966Z","end":"2026-05-19T17:52:36.670417Z","steps":["trace[752204311] 'agreement among raft nodes before linearized reading' (duration: 300.356111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:36.670473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:36.369958Z","time spent":"300.503934ms","remote":"127.0.0.1:43812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:52:36.670478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.971475ms","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-05-19T17:52:36.670555Z","caller":"traceutil/trace.go:171","msg":"trace[2938481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25400; }","duration":"320.086857ms","start":"2026-05-19T17:52:36.350449Z","end":"2026-05-19T17:52:36.670536Z","steps":["trace[2938481] 'agreement among raft nodes before linearized reading' (duration: 319.841411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:36.670572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:36.149073Z","time spent":"521.350732ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:36.670609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:36.350437Z","time spent":"320.163919ms","remote":"127.0.0.1:58902","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":"warn","ts":"2026-05-19T17:52:36.670967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:36.346424Z","time spent":"324.031833ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:36.855162Z","caller":"traceutil/trace.go:171","msg":"trace[923186521] linearizableReadLoop","detail":"{readStateIndex:26232; appliedIndex:26231; }","duration":"163.131994ms","start":"2026-05-19T17:52:36.69198Z","end":"2026-05-19T17:52:36.855112Z","steps":["trace[923186521] 'read index received' (duration: 104.139678ms)","trace[923186521] 'applied index is now lower than readState.Index' (duration: 58.990876ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:36.855192Z","caller":"traceutil/trace.go:171","msg":"trace[1004917032] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"178.366152ms","start":"2026-05-19T17:52:36.676784Z","end":"2026-05-19T17:52:36.85515Z","steps":["trace[1004917032] 'process raft request' (duration: 119.314574ms)","trace[1004917032] 'compare' (duration: 58.71949ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:36.855362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.364939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:36.855411Z","caller":"traceutil/trace.go:171","msg":"trace[1229398326] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25401; }","duration":"163.487732ms","start":"2026-05-19T17:52:36.691911Z","end":"2026-05-19T17:52:36.855399Z","steps":["trace[1229398326] 'agreement among raft nodes before linearized reading' (duration: 163.364009ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:37.06039Z","caller":"traceutil/trace.go:171","msg":"trace[1181488245] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"156.134765ms","start":"2026-05-19T17:52:36.904209Z","end":"2026-05-19T17:52:37.060344Z","steps":["trace[1181488245] 'process raft request' (duration: 95.726795ms)","trace[1181488245] 'compare' (duration: 60.193725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:37.50705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.900901ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T17:52:37.508384Z","caller":"traceutil/trace.go:171","msg":"trace[1531953379] linearizableReadLoop","detail":"{readStateIndex:26237; appliedIndex:26235; }","duration":"313.5363ms","start":"2026-05-19T17:52:37.194821Z","end":"2026-05-19T17:52:37.508357Z","steps":["trace[1531953379] 'read index received' (duration: 315.178µs)","trace[1531953379] 'applied index is now lower than readState.Index' (duration: 313.219362ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:37.508509Z","caller":"traceutil/trace.go:171","msg":"trace[2120690096] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"441.557084ms","start":"2026-05-19T17:52:37.066917Z","end":"2026-05-19T17:52:37.508474Z","steps":["trace[2120690096] 'process raft request' (duration: 440.292064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:37.508667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:37.066889Z","time spent":"441.698526ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:37.508892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.069552ms","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-05-19T17:52:37.508958Z","caller":"traceutil/trace.go:171","msg":"trace[882468291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25405; }","duration":"314.218726ms","start":"2026-05-19T17:52:37.194719Z","end":"2026-05-19T17:52:37.508937Z","steps":["trace[882468291] 'agreement among raft nodes before linearized reading' (duration: 313.836387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:37.509004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:37.194685Z","time spent":"314.308898ms","remote":"127.0.0.1:58902","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-05-19T17:52:37.776996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.875371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:37.777267Z","caller":"traceutil/trace.go:171","msg":"trace[1848333557] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"259.591756ms","start":"2026-05-19T17:52:37.517648Z","end":"2026-05-19T17:52:37.77724Z","steps":["trace[1848333557] 'process raft request' (duration: 259.504064ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:37.777257Z","caller":"traceutil/trace.go:171","msg":"trace[1196076040] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"309.837549ms","start":"2026-05-19T17:52:37.467375Z","end":"2026-05-19T17:52:37.777213Z","steps":["trace[1196076040] 'process raft request' (duration: 162.624251ms)","trace[1196076040] 'compare' (duration: 146.430239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:37.777456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.583219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:52:37.77747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:37.467348Z","time spent":"310.009304ms","remote":"127.0.0.1:58902","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-05-19T17:52:37.777513Z","caller":"traceutil/trace.go:171","msg":"trace[364886063] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25407; }","duration":"327.707882ms","start":"2026-05-19T17:52:37.449792Z","end":"2026-05-19T17:52:37.7775Z","steps":["trace[364886063] 'agreement among raft nodes before linearized reading' (duration: 327.544258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:37.777556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:37.449773Z","time spent":"327.771583ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:52:37.777282Z","caller":"traceutil/trace.go:171","msg":"trace[392022217] linearizableReadLoop","detail":"{readStateIndex:26238; appliedIndex:26237; }","duration":"268.745767ms","start":"2026-05-19T17:52:37.508502Z","end":"2026-05-19T17:52:37.777248Z","steps":["trace[392022217] 'read index received' (duration: 121.516928ms)","trace[392022217] 'applied index is now lower than readState.Index' (duration: 147.226039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:37.777753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.068434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:52:37.777847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.969119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-19T17:52:37.777849Z","caller":"traceutil/trace.go:171","msg":"trace[1538401295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25407; }","duration":"101.187786ms","start":"2026-05-19T17:52:37.676643Z","end":"2026-05-19T17:52:37.777831Z","steps":["trace[1538401295] 'agreement among raft nodes before linearized reading' (duration: 101.054163ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:37.777891Z","caller":"traceutil/trace.go:171","msg":"trace[1927111419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25407; }","duration":"297.041451ms","start":"2026-05-19T17:52:37.480838Z","end":"2026-05-19T17:52:37.777879Z","steps":["trace[1927111419] 'agreement among raft nodes before linearized reading' (duration: 296.872947ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:38.257992Z","caller":"traceutil/trace.go:171","msg":"trace[1417802400] linearizableReadLoop","detail":"{readStateIndex:26241; appliedIndex:26240; }","duration":"131.715994ms","start":"2026-05-19T17:52:38.126235Z","end":"2026-05-19T17:52:38.257951Z","steps":["trace[1417802400] 'read index received' (duration: 131.078519ms)","trace[1417802400] 'applied index is now lower than readState.Index' (duration: 635.455µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:38.258079Z","caller":"traceutil/trace.go:171","msg":"trace[522881718] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"303.695532ms","start":"2026-05-19T17:52:37.954347Z","end":"2026-05-19T17:52:38.258043Z","steps":["trace[522881718] 'process raft request' (duration: 303.21854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:38.258236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.9644ms","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-05-19T17:52:38.258271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:37.954321Z","time spent":"303.838725ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:38.258285Z","caller":"traceutil/trace.go:171","msg":"trace[1522427082] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25409; }","duration":"132.092223ms","start":"2026-05-19T17:52:38.126179Z","end":"2026-05-19T17:52:38.258272Z","steps":["trace[1522427082] 'agreement among raft nodes before linearized reading' (duration: 131.97049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:38.553013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.688863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:38.553131Z","caller":"traceutil/trace.go:171","msg":"trace[27197173] linearizableReadLoop","detail":"{readStateIndex:26242; appliedIndex:26241; }","duration":"206.118282ms","start":"2026-05-19T17:52:38.346991Z","end":"2026-05-19T17:52:38.553109Z","steps":["trace[27197173] 'read index received' (duration: 62.187053ms)","trace[27197173] 'applied index is now lower than readState.Index' (duration: 143.929339ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:38.553233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.228964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:38.553277Z","caller":"traceutil/trace.go:171","msg":"trace[419187707] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25410; }","duration":"206.313027ms","start":"2026-05-19T17:52:38.346949Z","end":"2026-05-19T17:52:38.553262Z","steps":["trace[419187707] 'agreement among raft nodes before linearized reading' (duration: 206.220854ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:38.553501Z","caller":"traceutil/trace.go:171","msg":"trace[1261921773] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"290.888102ms","start":"2026-05-19T17:52:38.262581Z","end":"2026-05-19T17:52:38.55347Z","steps":["trace[1261921773] 'process raft request' (duration: 146.534983ms)","trace[1261921773] 'compare' (duration: 143.469237ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:38.733616Z","caller":"traceutil/trace.go:171","msg":"trace[1768819130] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"129.976493ms","start":"2026-05-19T17:52:38.603588Z","end":"2026-05-19T17:52:38.733564Z","steps":["trace[1768819130] 'process raft request' (duration: 116.002875ms)","trace[1768819130] 'compare' (duration: 13.451014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:38.988101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.944927ms","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-05-19T17:52:38.98829Z","caller":"traceutil/trace.go:171","msg":"trace[571793699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25413; }","duration":"129.174213ms","start":"2026-05-19T17:52:38.859097Z","end":"2026-05-19T17:52:38.988271Z","steps":["trace[571793699] 'range keys from in-memory index tree' (duration: 128.786633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:38.98864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.889706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:38.988727Z","caller":"traceutil/trace.go:171","msg":"trace[591447818] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25413; }","duration":"121.982448ms","start":"2026-05-19T17:52:38.866732Z","end":"2026-05-19T17:52:38.988714Z","steps":["trace[591447818] 'range keys from in-memory index tree' (duration: 121.821105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:38.988952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.170506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-05-19T17:52:38.989028Z","caller":"traceutil/trace.go:171","msg":"trace[220742296] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25413; }","duration":"113.244318ms","start":"2026-05-19T17:52:38.87577Z","end":"2026-05-19T17:52:38.989014Z","steps":["trace[220742296] 'range keys from in-memory index tree' (duration: 113.086834ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:39.376032Z","caller":"traceutil/trace.go:171","msg":"trace[903448370] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"342.690993ms","start":"2026-05-19T17:52:39.03332Z","end":"2026-05-19T17:52:39.376011Z","steps":["trace[903448370] 'process raft request' (duration: 340.05134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:39.376155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.033303Z","time spent":"342.784686ms","remote":"127.0.0.1:58920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":867,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:39.532203Z","caller":"traceutil/trace.go:171","msg":"trace[262428621] linearizableReadLoop","detail":"{readStateIndex:26248; appliedIndex:26246; }","duration":"496.80612ms","start":"2026-05-19T17:52:39.035382Z","end":"2026-05-19T17:52:39.532188Z","steps":["trace[262428621] 'read index received' (duration: 337.926209ms)","trace[262428621] 'applied index is now lower than readState.Index' (duration: 158.879401ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:39.532346Z","caller":"traceutil/trace.go:171","msg":"trace[15171913] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"465.165494ms","start":"2026-05-19T17:52:39.067153Z","end":"2026-05-19T17:52:39.532319Z","steps":["trace[15171913] 'process raft request' (duration: 464.9863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:39.532413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.711371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:52:39.532434Z","caller":"traceutil/trace.go:171","msg":"trace[791784455] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25419; }","duration":"291.779203ms","start":"2026-05-19T17:52:39.24065Z","end":"2026-05-19T17:52:39.532429Z","steps":["trace[791784455] 'agreement among raft nodes before linearized reading' (duration: 291.721682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:39.532443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.067145Z","time spent":"465.233677ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:39.53246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.032395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:39.532523Z","caller":"traceutil/trace.go:171","msg":"trace[301634835] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"464.265913ms","start":"2026-05-19T17:52:39.068216Z","end":"2026-05-19T17:52:39.532482Z","steps":["trace[301634835] 'process raft request' (duration: 463.944815ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:39.532565Z","caller":"traceutil/trace.go:171","msg":"trace[344318272] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25419; }","duration":"497.18408ms","start":"2026-05-19T17:52:39.035356Z","end":"2026-05-19T17:52:39.53254Z","steps":["trace[344318272] 'agreement among raft nodes before linearized reading' (duration: 496.988985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:39.532626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.035347Z","time spent":"497.266891ms","remote":"127.0.0.1:58904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-05-19T17:52:39.532615Z","caller":"traceutil/trace.go:171","msg":"trace[360341535] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"497.706292ms","start":"2026-05-19T17:52:39.03488Z","end":"2026-05-19T17:52:39.532586Z","steps":["trace[360341535] 'process raft request' (duration: 497.116698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:39.532732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.619768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:1 size:969"} {"level":"warn","ts":"2026-05-19T17:52:39.532744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.03487Z","time spent":"497.802524ms","remote":"127.0.0.1:58920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:39.532715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.068207Z","time spent":"464.402386ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:39.532782Z","caller":"traceutil/trace.go:171","msg":"trace[202345524] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:1; response_revision:25419; }","duration":"149.691349ms","start":"2026-05-19T17:52:39.383077Z","end":"2026-05-19T17:52:39.532768Z","steps":["trace[202345524] 'agreement among raft nodes before linearized reading' (duration: 149.532725ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:39.532576Z","caller":"traceutil/trace.go:171","msg":"trace[1688455903] transaction","detail":"{read_only:false; response_revision:25417; number_of_response:1; }","duration":"467.480611ms","start":"2026-05-19T17:52:39.065088Z","end":"2026-05-19T17:52:39.532568Z","steps":["trace[1688455903] 'process raft request' (duration: 467.01772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:39.533028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.065068Z","time spent":"467.934432ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:39.532657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.907452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18b108ec82327eb8\" ","response":"range_response_count:1 size:695"} {"level":"warn","ts":"2026-05-19T17:52:39.533034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.919504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:52:39.533158Z","caller":"traceutil/trace.go:171","msg":"trace[219798446] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25419; }","duration":"181.116859ms","start":"2026-05-19T17:52:39.352013Z","end":"2026-05-19T17:52:39.53313Z","steps":["trace[219798446] 'agreement among raft nodes before linearized reading' (duration: 180.828392ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:39.533105Z","caller":"traceutil/trace.go:171","msg":"trace[93896242] range","detail":"{range_begin:/registry/events/openstack/container-infra.18b108ec82327eb8; range_end:; response_count:1; response_revision:25419; }","duration":"152.407954ms","start":"2026-05-19T17:52:39.38069Z","end":"2026-05-19T17:52:39.533098Z","steps":["trace[93896242] 'agreement among raft nodes before linearized reading' (duration: 151.923762ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:39.699087Z","caller":"traceutil/trace.go:171","msg":"trace[1870469784] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"138.672502ms","start":"2026-05-19T17:52:39.560376Z","end":"2026-05-19T17:52:39.699048Z","steps":["trace[1870469784] 'process raft request' (duration: 138.5813ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:39.699146Z","caller":"traceutil/trace.go:171","msg":"trace[1881558159] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"160.51659ms","start":"2026-05-19T17:52:39.538597Z","end":"2026-05-19T17:52:39.699114Z","steps":["trace[1881558159] 'process raft request' (duration: 160.205302ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:39.699174Z","caller":"traceutil/trace.go:171","msg":"trace[373238151] linearizableReadLoop","detail":"{readStateIndex:26254; appliedIndex:26252; }","duration":"140.173688ms","start":"2026-05-19T17:52:39.558961Z","end":"2026-05-19T17:52:39.699135Z","steps":["trace[373238151] 'read index received' (duration: 137.471623ms)","trace[373238151] 'applied index is now lower than readState.Index' (duration: 2.697435ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:39.699273Z","caller":"traceutil/trace.go:171","msg":"trace[832300262] transaction","detail":"{read_only:false; response_revision:25420; number_of_response:1; }","duration":"160.644323ms","start":"2026-05-19T17:52:39.538586Z","end":"2026-05-19T17:52:39.69923Z","steps":["trace[832300262] 'process raft request' (duration: 157.911567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:39.69937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.534842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:39.699425Z","caller":"traceutil/trace.go:171","msg":"trace[939403062] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25422; }","duration":"158.628164ms","start":"2026-05-19T17:52:39.540782Z","end":"2026-05-19T17:52:39.69941Z","steps":["trace[939403062] 'agreement among raft nodes before linearized reading' (duration: 158.530331ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:40.049941Z","caller":"traceutil/trace.go:171","msg":"trace[1652082565] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"267.307512ms","start":"2026-05-19T17:52:39.782609Z","end":"2026-05-19T17:52:40.049917Z","steps":["trace[1652082565] 'process raft request' (duration: 267.126907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:40.358215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.283775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:40.358398Z","caller":"traceutil/trace.go:171","msg":"trace[234548984] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"572.588812ms","start":"2026-05-19T17:52:39.785786Z","end":"2026-05-19T17:52:40.358375Z","steps":["trace[234548984] 'process raft request' (duration: 376.074611ms)","trace[234548984] 'compare' (duration: 196.029978ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:40.358507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.785758Z","time spent":"572.690975ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:40.35854Z","caller":"traceutil/trace.go:171","msg":"trace[2033353920] linearizableReadLoop","detail":"{readStateIndex:26258; appliedIndex:26256; }","duration":"552.095898ms","start":"2026-05-19T17:52:39.806429Z","end":"2026-05-19T17:52:40.358525Z","steps":["trace[2033353920] 'read index received' (duration: 243.441946ms)","trace[2033353920] 'applied index is now lower than readState.Index' (duration: 308.652682ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:40.358613Z","caller":"traceutil/trace.go:171","msg":"trace[194982850] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"343.7228ms","start":"2026-05-19T17:52:40.014883Z","end":"2026-05-19T17:52:40.358606Z","steps":["trace[194982850] 'process raft request' (duration: 343.438013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:40.358666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:40.014862Z","time spent":"343.77066ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:40.358757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.338753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-19T17:52:40.358777Z","caller":"traceutil/trace.go:171","msg":"trace[1241207291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25426; }","duration":"552.408194ms","start":"2026-05-19T17:52:39.806363Z","end":"2026-05-19T17:52:40.358771Z","steps":["trace[1241207291] 'agreement among raft nodes before linearized reading' (duration: 552.319622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:40.358792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.806336Z","time spent":"552.452936ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T17:52:40.410245Z","caller":"traceutil/trace.go:171","msg":"trace[2011031065] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"146.402919ms","start":"2026-05-19T17:52:40.263814Z","end":"2026-05-19T17:52:40.410217Z","steps":["trace[2011031065] 'process raft request' (duration: 146.200634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:40.410507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.63759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:40.410575Z","caller":"traceutil/trace.go:171","msg":"trace[1508436644] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25427; }","duration":"533.777423ms","start":"2026-05-19T17:52:39.876776Z","end":"2026-05-19T17:52:40.410554Z","steps":["trace[1508436644] 'agreement among raft nodes before linearized reading' (duration: 533.6566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:40.410623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:39.876748Z","time spent":"533.859765ms","remote":"127.0.0.1:58854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:52:40.729985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.318925ms","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-05-19T17:52:40.730152Z","caller":"traceutil/trace.go:171","msg":"trace[1720357568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25428; }","duration":"172.479139ms","start":"2026-05-19T17:52:40.557607Z","end":"2026-05-19T17:52:40.730087Z","steps":["trace[1720357568] 'range keys from in-memory index tree' (duration: 172.019118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:40.730217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.768803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-19T17:52:40.730323Z","caller":"traceutil/trace.go:171","msg":"trace[1287643162] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25428; }","duration":"195.930196ms","start":"2026-05-19T17:52:40.534359Z","end":"2026-05-19T17:52:40.73029Z","steps":["trace[1287643162] 'range keys from in-memory index tree' (duration: 195.459705ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:48.557284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22074} {"level":"info","ts":"2026-05-19T17:52:48.690215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22074,"took":"128.088546ms","hash":4011121363,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":43372544,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-19T17:52:48.690295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4011121363,"revision":22074,"compact-revision":19827} {"level":"warn","ts":"2026-05-19T17:52:52.431465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.331238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:52.431549Z","caller":"traceutil/trace.go:171","msg":"trace[1217918750] linearizableReadLoop","detail":"{readStateIndex:26351; appliedIndex:26350; }","duration":"138.975289ms","start":"2026-05-19T17:52:52.292561Z","end":"2026-05-19T17:52:52.431536Z","steps":["trace[1217918750] 'read index received' (duration: 40.021µs)","trace[1217918750] 'applied index is now lower than readState.Index' (duration: 138.934108ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:52.431739Z","caller":"traceutil/trace.go:171","msg":"trace[2002599829] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"337.120689ms","start":"2026-05-19T17:52:52.094603Z","end":"2026-05-19T17:52:52.431724Z","steps":["trace[2002599829] 'process raft request' (duration: 15.459644ms)","trace[2002599829] 'compare' (duration: 321.072551ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:52.432068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.481341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"warn","ts":"2026-05-19T17:52:52.432317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.094579Z","time spent":"337.700734ms","remote":"127.0.0.1:58902","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-05-19T17:52:52.432344Z","caller":"traceutil/trace.go:171","msg":"trace[621173886] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25514; }","duration":"139.802659ms","start":"2026-05-19T17:52:52.292512Z","end":"2026-05-19T17:52:52.432315Z","steps":["trace[621173886] 'agreement among raft nodes before linearized reading' (duration: 139.354618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:53.028171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.822811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:53.028265Z","caller":"traceutil/trace.go:171","msg":"trace[257669370] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"590.875384ms","start":"2026-05-19T17:52:52.437376Z","end":"2026-05-19T17:52:53.028251Z","steps":["trace[257669370] 'process raft request' (duration: 95.894439ms)","trace[257669370] 'compare' (duration: 494.476852ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:53.02869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.437355Z","time spent":"590.932594ms","remote":"127.0.0.1:58902","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-05-19T17:52:53.666317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.999293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:53.666532Z","caller":"traceutil/trace.go:171","msg":"trace[1479343792] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"1.209463047s","start":"2026-05-19T17:52:52.457036Z","end":"2026-05-19T17:52:53.666499Z","steps":["trace[1479343792] 'process raft request' (duration: 868.195688ms)","trace[1479343792] 'compare' (duration: 340.478141ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:53.666627Z","caller":"traceutil/trace.go:171","msg":"trace[1878079938] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"869.376025ms","start":"2026-05-19T17:52:52.797235Z","end":"2026-05-19T17:52:53.666611Z","steps":["trace[1878079938] 'process raft request' (duration: 869.341214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:53.666695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.797217Z","time spent":"869.449857ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:53.666697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.457016Z","time spent":"1.20959695s","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:53.666818Z","caller":"traceutil/trace.go:171","msg":"trace[1212288339] linearizableReadLoop","detail":"{readStateIndex:26353; appliedIndex:26351; }","duration":"1.200547101s","start":"2026-05-19T17:52:52.466262Z","end":"2026-05-19T17:52:53.666809Z","steps":["trace[1212288339] 'read index received' (duration: 67.02339ms)","trace[1212288339] 'applied index is now lower than readState.Index' (duration: 1.133522771s)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:53.666999Z","caller":"traceutil/trace.go:171","msg":"trace[1894770248] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"869.807826ms","start":"2026-05-19T17:52:52.797179Z","end":"2026-05-19T17:52:53.666987Z","steps":["trace[1894770248] 'process raft request' (duration: 869.371776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:53.667067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.797145Z","time spent":"869.890088ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:53.667095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200837457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-19T17:52:53.667121Z","caller":"traceutil/trace.go:171","msg":"trace[1599777621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25520; }","duration":"1.200912389s","start":"2026-05-19T17:52:52.466201Z","end":"2026-05-19T17:52:53.667113Z","steps":["trace[1599777621] 'agreement among raft nodes before linearized reading' (duration: 1.200816467s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:53.667151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.466183Z","time spent":"1.200962281s","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T17:52:53.667208Z","caller":"traceutil/trace.go:171","msg":"trace[1686552235] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"1.028157464s","start":"2026-05-19T17:52:52.639038Z","end":"2026-05-19T17:52:53.667195Z","steps":["trace[1686552235] 'process raft request' (duration: 1.027383286s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:53.667287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.639012Z","time spent":"1.028234285s","remote":"127.0.0.1:58902","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-05-19T17:52:53.667317Z","caller":"traceutil/trace.go:171","msg":"trace[601841102] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"872.088151ms","start":"2026-05-19T17:52:52.795198Z","end":"2026-05-19T17:52:53.667286Z","steps":["trace[601841102] 'process raft request' (duration: 871.313793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:53.667453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.795172Z","time spent":"872.204014ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:54.293737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.937662ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:54.293847Z","caller":"traceutil/trace.go:171","msg":"trace[1151348526] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25521; }","duration":"382.072865ms","start":"2026-05-19T17:52:53.911758Z","end":"2026-05-19T17:52:54.29383Z","steps":["trace[1151348526] 'range keys from in-memory index tree' (duration: 381.922632ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:54.29445Z","caller":"traceutil/trace.go:171","msg":"trace[508507365] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"618.85417ms","start":"2026-05-19T17:52:53.675584Z","end":"2026-05-19T17:52:54.294438Z","steps":["trace[508507365] 'process raft request' (duration: 618.825969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.294536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.675567Z","time spent":"618.915522ms","remote":"127.0.0.1:58902","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-05-19T17:52:54.294682Z","caller":"traceutil/trace.go:171","msg":"trace[254459087] linearizableReadLoop","detail":"{readStateIndex:26360; appliedIndex:26357; }","duration":"627.830776ms","start":"2026-05-19T17:52:53.66684Z","end":"2026-05-19T17:52:54.294671Z","steps":["trace[254459087] 'read index received' (duration: 4.213392ms)","trace[254459087] 'applied index is now lower than readState.Index' (duration: 623.616444ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:54.294698Z","caller":"traceutil/trace.go:171","msg":"trace[1287117841] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"689.145169ms","start":"2026-05-19T17:52:53.605509Z","end":"2026-05-19T17:52:54.294654Z","steps":["trace[1287117841] 'process raft request' (duration: 688.848832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.29483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09089842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-19T17:52:54.294799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.479483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:54.294854Z","caller":"traceutil/trace.go:171","msg":"trace[1473751403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25524; }","duration":"1.090949721s","start":"2026-05-19T17:52:53.203898Z","end":"2026-05-19T17:52:54.294848Z","steps":["trace[1473751403] 'agreement among raft nodes before linearized reading' (duration: 1.090860729s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.294873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.203862Z","time spent":"1.091007053s","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:52:54.294896Z","caller":"traceutil/trace.go:171","msg":"trace[579081311] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25524; }","duration":"652.605516ms","start":"2026-05-19T17:52:53.642254Z","end":"2026-05-19T17:52:54.294859Z","steps":["trace[579081311] 'agreement among raft nodes before linearized reading' (duration: 652.457002ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:54.294865Z","caller":"traceutil/trace.go:171","msg":"trace[983108702] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"689.9932ms","start":"2026-05-19T17:52:53.604824Z","end":"2026-05-19T17:52:54.294817Z","steps":["trace[983108702] 'process raft request' (duration: 652.519644ms)","trace[983108702] 'compare' (duration: 36.914582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:54.294911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.605484Z","time spent":"689.307543ms","remote":"127.0.0.1:58902","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-05-19T17:52:54.294946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172673097s","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-05-19T17:52:54.294939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.642233Z","time spent":"652.695028ms","remote":"127.0.0.1:43726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:52:54.294986Z","caller":"traceutil/trace.go:171","msg":"trace[1036116493] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25524; }","duration":"1.172747278s","start":"2026-05-19T17:52:53.12223Z","end":"2026-05-19T17:52:54.294977Z","steps":["trace[1036116493] 'agreement among raft nodes before linearized reading' (duration: 1.172696027s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.295004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.122201Z","time spent":"1.17279824s","remote":"127.0.0.1:44744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:52:54.295048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.604806Z","time spent":"690.152293ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:54.295147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493585784s","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-05-19T17:52:54.295172Z","caller":"traceutil/trace.go:171","msg":"trace[1682207470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25524; }","duration":"1.493631867s","start":"2026-05-19T17:52:52.801534Z","end":"2026-05-19T17:52:54.295166Z","steps":["trace[1682207470] 'agreement among raft nodes before linearized reading' (duration: 1.493562754s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.295162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.114098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:52:54.295193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.801525Z","time spent":"1.493663117s","remote":"127.0.0.1:58902","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":"info","ts":"2026-05-19T17:52:54.295206Z","caller":"traceutil/trace.go:171","msg":"trace[802542387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25524; }","duration":"617.17559ms","start":"2026-05-19T17:52:53.678017Z","end":"2026-05-19T17:52:54.295193Z","steps":["trace[802542387] 'agreement among raft nodes before linearized reading' (duration: 617.106097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.295236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.678011Z","time spent":"617.219321ms","remote":"127.0.0.1:58640","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-05-19T17:52:54.295315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537307512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:52:54.295324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.923514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:54.295346Z","caller":"traceutil/trace.go:171","msg":"trace[842971050] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25524; }","duration":"1.537366624s","start":"2026-05-19T17:52:52.757967Z","end":"2026-05-19T17:52:54.295334Z","steps":["trace[842971050] 'agreement among raft nodes before linearized reading' (duration: 1.537326593s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.295365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.757949Z","time spent":"1.537412645s","remote":"127.0.0.1:37590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-19T17:52:54.295389Z","caller":"traceutil/trace.go:171","msg":"trace[243742077] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25524; }","duration":"101.999096ms","start":"2026-05-19T17:52:54.193354Z","end":"2026-05-19T17:52:54.295353Z","steps":["trace[243742077] 'agreement among raft nodes before linearized reading' (duration: 101.910914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.295429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.807845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:52:54.295465Z","caller":"traceutil/trace.go:171","msg":"trace[1221432881] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25524; }","duration":"610.867957ms","start":"2026-05-19T17:52:53.684584Z","end":"2026-05-19T17:52:54.295452Z","steps":["trace[1221432881] 'agreement among raft nodes before linearized reading' (duration: 610.803535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.29546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.024606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:52:54.295503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.684569Z","time spent":"610.924218ms","remote":"127.0.0.1:46796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:52:54.295525Z","caller":"traceutil/trace.go:171","msg":"trace[193892977] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:25524; }","duration":"581.126828ms","start":"2026-05-19T17:52:53.714378Z","end":"2026-05-19T17:52:54.295505Z","steps":["trace[193892977] 'agreement among raft nodes before linearized reading' (duration: 581.005475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.295587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.714359Z","time spent":"581.212811ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:52:54.295701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.548252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-19T17:52:54.295697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763604313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:52:54.29573Z","caller":"traceutil/trace.go:171","msg":"trace[1922564446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25524; }","duration":"534.616443ms","start":"2026-05-19T17:52:53.761108Z","end":"2026-05-19T17:52:54.295724Z","steps":["trace[1922564446] 'agreement among raft nodes before linearized reading' (duration: 534.524131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.295748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.761086Z","time spent":"534.658595ms","remote":"127.0.0.1:58902","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-05-19T17:52:54.295762Z","caller":"traceutil/trace.go:171","msg":"trace[432294741] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25524; }","duration":"1.763717576s","start":"2026-05-19T17:52:52.532025Z","end":"2026-05-19T17:52:54.295742Z","steps":["trace[432294741] 'agreement among raft nodes before linearized reading' (duration: 1.763598083s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.295681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.024935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:54.295949Z","caller":"traceutil/trace.go:171","msg":"trace[264250142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25524; }","duration":"660.515157ms","start":"2026-05-19T17:52:53.635405Z","end":"2026-05-19T17:52:54.295921Z","steps":["trace[264250142] 'agreement among raft nodes before linearized reading' (duration: 659.744418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.296065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.635348Z","time spent":"660.702962ms","remote":"127.0.0.1:58630","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-05-19T17:52:54.295825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:52.532005Z","time spent":"1.763804577s","remote":"127.0.0.1:60830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:52:54.296431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.640303ms","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-05-19T17:52:54.296505Z","caller":"traceutil/trace.go:171","msg":"trace[1262676055] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25524; }","duration":"935.999996ms","start":"2026-05-19T17:52:53.360485Z","end":"2026-05-19T17:52:54.296485Z","steps":["trace[1262676055] 'agreement among raft nodes before linearized reading' (duration: 934.212173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.296556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:53.360462Z","time spent":"936.080058ms","remote":"127.0.0.1:58790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-05-19T17:52:54.966659Z","caller":"traceutil/trace.go:171","msg":"trace[1019135798] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"664.940543ms","start":"2026-05-19T17:52:54.301697Z","end":"2026-05-19T17:52:54.966637Z","steps":["trace[1019135798] 'process raft request' (duration: 664.747538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.9668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:54.301675Z","time spent":"665.052566ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:54.967165Z","caller":"traceutil/trace.go:171","msg":"trace[258089104] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"665.434285ms","start":"2026-05-19T17:52:54.301719Z","end":"2026-05-19T17:52:54.967154Z","steps":["trace[258089104] 'process raft request' (duration: 664.869752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.967218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:54.301704Z","time spent":"665.487316ms","remote":"127.0.0.1:58902","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-05-19T17:52:54.967169Z","caller":"traceutil/trace.go:171","msg":"trace[2095780337] linearizableReadLoop","detail":"{readStateIndex:26364; appliedIndex:26361; }","duration":"665.330483ms","start":"2026-05-19T17:52:54.301795Z","end":"2026-05-19T17:52:54.967126Z","steps":["trace[2095780337] 'read index received' (duration: 28.946479ms)","trace[2095780337] 'applied index is now lower than readState.Index' (duration: 636.381244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:54.967471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.63617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f9ed8fad-2e1f-40c3-a023-ba4f895353c2\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-05-19T17:52:54.967589Z","caller":"traceutil/trace.go:171","msg":"trace[347681798] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f9ed8fad-2e1f-40c3-a023-ba4f895353c2; range_end:; response_count:1; response_revision:25527; }","duration":"665.805085ms","start":"2026-05-19T17:52:54.301762Z","end":"2026-05-19T17:52:54.967567Z","steps":["trace[347681798] 'agreement among raft nodes before linearized reading' (duration: 665.539648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:54.967637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:54.301755Z","time spent":"665.870477ms","remote":"127.0.0.1:58774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1317,"request content":"key:\"/registry/persistentvolumes/pvc-f9ed8fad-2e1f-40c3-a023-ba4f895353c2\" "} {"level":"warn","ts":"2026-05-19T17:52:55.591539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.459341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:52:55.591736Z","caller":"traceutil/trace.go:171","msg":"trace[1970638829] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"1.154250202s","start":"2026-05-19T17:52:54.437437Z","end":"2026-05-19T17:52:55.591687Z","steps":["trace[1970638829] 'process raft request' (duration: 772.533335ms)","trace[1970638829] 'compare' (duration: 380.98314ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:55.591795Z","caller":"traceutil/trace.go:171","msg":"trace[1147320437] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"214.933755ms","start":"2026-05-19T17:52:55.376846Z","end":"2026-05-19T17:52:55.59178Z","steps":["trace[1147320437] 'process raft request' (duration: 214.789012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.592119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:54.437409Z","time spent":"1.154412725s","remote":"127.0.0.1:58902","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-05-19T17:52:55.59218Z","caller":"traceutil/trace.go:171","msg":"trace[1800547136] linearizableReadLoop","detail":"{readStateIndex:26365; appliedIndex:26364; }","duration":"624.831564ms","start":"2026-05-19T17:52:54.96734Z","end":"2026-05-19T17:52:55.592172Z","steps":["trace[1800547136] 'read index received' (duration: 242.638225ms)","trace[1800547136] 'applied index is now lower than readState.Index' (duration: 382.192239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:55.592391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.75582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-19T17:52:55.59242Z","caller":"traceutil/trace.go:171","msg":"trace[910765716] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25529; }","duration":"887.834651ms","start":"2026-05-19T17:52:54.704576Z","end":"2026-05-19T17:52:55.59241Z","steps":["trace[910765716] 'agreement among raft nodes before linearized reading' (duration: 887.743139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.592438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:54.70455Z","time spent":"887.882843ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-19T17:52:55.592735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.360216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:55.592767Z","caller":"traceutil/trace.go:171","msg":"trace[1055458647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25529; }","duration":"917.450409ms","start":"2026-05-19T17:52:54.675309Z","end":"2026-05-19T17:52:55.59276Z","steps":["trace[1055458647] 'agreement among raft nodes before linearized reading' (duration: 917.394367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.592752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.991843ms","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":"warn","ts":"2026-05-19T17:52:55.592785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:54.675272Z","time spent":"917.508249ms","remote":"127.0.0.1:58630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:52:55.592802Z","caller":"traceutil/trace.go:171","msg":"trace[1972391461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25529; }","duration":"559.084805ms","start":"2026-05-19T17:52:55.033702Z","end":"2026-05-19T17:52:55.592787Z","steps":["trace[1972391461] 'agreement among raft nodes before linearized reading' (duration: 558.955572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.592849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:55.033678Z","time spent":"559.163948ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T17:52:55.592824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122420673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:52:55.592909Z","caller":"traceutil/trace.go:171","msg":"trace[1659544523] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25529; }","duration":"1.122569967s","start":"2026-05-19T17:52:54.470318Z","end":"2026-05-19T17:52:55.592888Z","steps":["trace[1659544523] 'agreement among raft nodes before linearized reading' (duration: 1.122420823s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.592957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.949724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-05-19T17:52:55.592952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:54.470289Z","time spent":"1.122652558s","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:52:55.592978Z","caller":"traceutil/trace.go:171","msg":"trace[1050161374] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25529; }","duration":"212.006475ms","start":"2026-05-19T17:52:55.380965Z","end":"2026-05-19T17:52:55.592972Z","steps":["trace[1050161374] 'agreement among raft nodes before linearized reading' (duration: 211.938133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.593037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.157266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T17:52:55.593034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.77389ms","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-05-19T17:52:55.593091Z","caller":"traceutil/trace.go:171","msg":"trace[899723557] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25529; }","duration":"173.244218ms","start":"2026-05-19T17:52:55.419834Z","end":"2026-05-19T17:52:55.593078Z","steps":["trace[899723557] 'agreement among raft nodes before linearized reading' (duration: 173.078804ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:55.593149Z","caller":"traceutil/trace.go:171","msg":"trace[1111582649] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25529; }","duration":"446.927404ms","start":"2026-05-19T17:52:55.146184Z","end":"2026-05-19T17:52:55.593111Z","steps":["trace[1111582649] 'agreement among raft nodes before linearized reading' (duration: 446.7394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.593213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:55.146166Z","time spent":"447.034806ms","remote":"127.0.0.1:44752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:52:55.593247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.361607ms","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-05-19T17:52:55.593409Z","caller":"traceutil/trace.go:171","msg":"trace[2058593159] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25529; }","duration":"558.571082ms","start":"2026-05-19T17:52:55.034818Z","end":"2026-05-19T17:52:55.593389Z","steps":["trace[2058593159] 'agreement among raft nodes before linearized reading' (duration: 558.108141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.593513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:55.034809Z","time spent":"558.691185ms","remote":"127.0.0.1:58816","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":"warn","ts":"2026-05-19T17:52:55.593172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290005194s","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-05-19T17:52:55.593985Z","caller":"traceutil/trace.go:171","msg":"trace[1681975613] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25529; }","duration":"1.290915756s","start":"2026-05-19T17:52:54.30305Z","end":"2026-05-19T17:52:55.593966Z","steps":["trace[1681975613] 'agreement among raft nodes before linearized reading' (duration: 1.289953623s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:55.59402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:54.303025Z","time spent":"1.290983268s","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"info","ts":"2026-05-19T17:52:57.170406Z","caller":"traceutil/trace.go:171","msg":"trace[188013553] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"116.741153ms","start":"2026-05-19T17:52:57.053618Z","end":"2026-05-19T17:52:57.17036Z","steps":["trace[188013553] 'process raft request' (duration: 106.082575ms)","trace[188013553] 'compare' (duration: 10.414572ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:03.61351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.972667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:03.613635Z","caller":"traceutil/trace.go:171","msg":"trace[1493819145] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"276.049744ms","start":"2026-05-19T17:53:03.337561Z","end":"2026-05-19T17:53:03.613611Z","steps":["trace[1493819145] 'process raft request' (duration: 156.883072ms)","trace[1493819145] 'compare' (duration: 118.765102ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:03.872322Z","caller":"traceutil/trace.go:171","msg":"trace[676318177] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26511; }","duration":"169.064046ms","start":"2026-05-19T17:53:03.703225Z","end":"2026-05-19T17:53:03.872289Z","steps":["trace[676318177] 'read index received' (duration: 168.524663ms)","trace[676318177] 'applied index is now lower than readState.Index' (duration: 538.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:03.872527Z","caller":"traceutil/trace.go:171","msg":"trace[591571535] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"172.455119ms","start":"2026-05-19T17:53:03.700062Z","end":"2026-05-19T17:53:03.872517Z","steps":["trace[591571535] 'process raft request' (duration: 171.708451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:03.872726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.486626ms","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-05-19T17:53:03.872764Z","caller":"traceutil/trace.go:171","msg":"trace[663977500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25673; }","duration":"169.563048ms","start":"2026-05-19T17:53:03.703187Z","end":"2026-05-19T17:53:03.87275Z","steps":["trace[663977500] 'agreement among raft nodes before linearized reading' (duration: 169.451095ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:03.876406Z","caller":"traceutil/trace.go:171","msg":"trace[1318379991] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"160.643384ms","start":"2026-05-19T17:53:03.715721Z","end":"2026-05-19T17:53:03.876365Z","steps":["trace[1318379991] 'process raft request' (duration: 160.558682ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:03.876505Z","caller":"traceutil/trace.go:171","msg":"trace[1874566680] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"161.970386ms","start":"2026-05-19T17:53:03.714499Z","end":"2026-05-19T17:53:03.87647Z","steps":["trace[1874566680] 'process raft request' (duration: 161.608277ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:03.87706Z","caller":"traceutil/trace.go:171","msg":"trace[1076758328] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"159.064295ms","start":"2026-05-19T17:53:03.717971Z","end":"2026-05-19T17:53:03.877035Z","steps":["trace[1076758328] 'process raft request' (duration: 158.357158ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:03.877105Z","caller":"traceutil/trace.go:171","msg":"trace[1637147188] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"158.469971ms","start":"2026-05-19T17:53:03.718606Z","end":"2026-05-19T17:53:03.877076Z","steps":["trace[1637147188] 'process raft request' (duration: 158.305717ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:03.877122Z","caller":"traceutil/trace.go:171","msg":"trace[2130399460] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"141.649275ms","start":"2026-05-19T17:53:03.735461Z","end":"2026-05-19T17:53:03.877111Z","steps":["trace[2130399460] 'process raft request' (duration: 141.557583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:03.87717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.925071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:53:03.877269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.420989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:53:03.87729Z","caller":"traceutil/trace.go:171","msg":"trace[1637724189] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25678; }","duration":"130.099254ms","start":"2026-05-19T17:53:03.747175Z","end":"2026-05-19T17:53:03.877275Z","steps":["trace[1637724189] 'agreement among raft nodes before linearized reading' (duration: 129.909809ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:03.877312Z","caller":"traceutil/trace.go:171","msg":"trace[600683139] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25678; }","duration":"122.491581ms","start":"2026-05-19T17:53:03.754806Z","end":"2026-05-19T17:53:03.877298Z","steps":["trace[600683139] 'agreement among raft nodes before linearized reading' (duration: 122.337098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:27.804061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.121845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:53:27.804172Z","caller":"traceutil/trace.go:171","msg":"trace[7944400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25867; }","duration":"130.313269ms","start":"2026-05-19T17:53:27.673839Z","end":"2026-05-19T17:53:27.804152Z","steps":["trace[7944400] 'range keys from in-memory index tree' (duration: 129.9198ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:27.857678Z","caller":"traceutil/trace.go:171","msg":"trace[421655095] linearizableReadLoop","detail":"{readStateIndex:26712; appliedIndex:26711; }","duration":"118.379751ms","start":"2026-05-19T17:53:27.73928Z","end":"2026-05-19T17:53:27.85766Z","steps":["trace[421655095] 'read index received' (duration: 118.204577ms)","trace[421655095] 'applied index is now lower than readState.Index' (duration: 174.664µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:27.857746Z","caller":"traceutil/trace.go:171","msg":"trace[996215651] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"137.453583ms","start":"2026-05-19T17:53:27.720262Z","end":"2026-05-19T17:53:27.857715Z","steps":["trace[996215651] 'process raft request' (duration: 137.280189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:27.85796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.652978ms","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-05-19T17:53:27.858036Z","caller":"traceutil/trace.go:171","msg":"trace[606624416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25868; }","duration":"118.784491ms","start":"2026-05-19T17:53:27.739234Z","end":"2026-05-19T17:53:27.858019Z","steps":["trace[606624416] 'agreement among raft nodes before linearized reading' (duration: 118.506354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:28.157566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.047197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:28.15766Z","caller":"traceutil/trace.go:171","msg":"trace[761510219] linearizableReadLoop","detail":"{readStateIndex:26718; appliedIndex:26717; }","duration":"143.883358ms","start":"2026-05-19T17:53:28.013761Z","end":"2026-05-19T17:53:28.157645Z","steps":["trace[761510219] 'read index received' (duration: 36.614435ms)","trace[761510219] 'applied index is now lower than readState.Index' (duration: 107.267303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:28.157799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.039321ms","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-05-19T17:53:28.15783Z","caller":"traceutil/trace.go:171","msg":"trace[299557120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25874; }","duration":"144.113663ms","start":"2026-05-19T17:53:28.013705Z","end":"2026-05-19T17:53:28.157818Z","steps":["trace[299557120] 'agreement among raft nodes before linearized reading' (duration: 143.9798ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:28.157782Z","caller":"traceutil/trace.go:171","msg":"trace[706260017] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"175.164994ms","start":"2026-05-19T17:53:27.982561Z","end":"2026-05-19T17:53:28.157726Z","steps":["trace[706260017] 'process raft request' (duration: 67.879671ms)","trace[706260017] 'compare' (duration: 106.797921ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:28.53372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.53932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:53:28.533815Z","caller":"traceutil/trace.go:171","msg":"trace[382328205] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25875; }","duration":"253.694094ms","start":"2026-05-19T17:53:28.280099Z","end":"2026-05-19T17:53:28.533793Z","steps":["trace[382328205] 'count revisions from in-memory index tree' (duration: 253.435717ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:28.53763Z","caller":"traceutil/trace.go:171","msg":"trace[1006364920] transaction","detail":"{read_only:false; response_revision:25876; number_of_response:1; }","duration":"106.014283ms","start":"2026-05-19T17:53:28.431592Z","end":"2026-05-19T17:53:28.537606Z","steps":["trace[1006364920] 'process raft request' (duration: 105.88563ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:28.689182Z","caller":"traceutil/trace.go:171","msg":"trace[1558763147] linearizableReadLoop","detail":"{readStateIndex:26721; appliedIndex:26719; }","duration":"228.942135ms","start":"2026-05-19T17:53:28.460211Z","end":"2026-05-19T17:53:28.689153Z","steps":["trace[1558763147] 'read index received' (duration: 77.283289ms)","trace[1558763147] 'applied index is now lower than readState.Index' (duration: 151.657616ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:28.689271Z","caller":"traceutil/trace.go:171","msg":"trace[1802932744] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"255.124827ms","start":"2026-05-19T17:53:28.434077Z","end":"2026-05-19T17:53:28.689202Z","steps":["trace[1802932744] 'process raft request' (duration: 248.336673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:28.689467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.222296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-19T17:53:28.689467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.241491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-19T17:53:28.689516Z","caller":"traceutil/trace.go:171","msg":"trace[1615105027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25877; }","duration":"187.315348ms","start":"2026-05-19T17:53:28.50219Z","end":"2026-05-19T17:53:28.689505Z","steps":["trace[1615105027] 'agreement among raft nodes before linearized reading' (duration: 187.141454ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:28.689536Z","caller":"traceutil/trace.go:171","msg":"trace[997718332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25877; }","duration":"229.361164ms","start":"2026-05-19T17:53:28.460157Z","end":"2026-05-19T17:53:28.689518Z","steps":["trace[997718332] 'agreement among raft nodes before linearized reading' (duration: 229.153079ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:28.803605Z","caller":"traceutil/trace.go:171","msg":"trace[99062710] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"108.147905ms","start":"2026-05-19T17:53:28.695402Z","end":"2026-05-19T17:53:28.803549Z","steps":["trace[99062710] 'process raft request' (duration: 103.647076ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:29.695638Z","caller":"traceutil/trace.go:171","msg":"trace[1933480777] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"129.733966ms","start":"2026-05-19T17:53:29.565879Z","end":"2026-05-19T17:53:29.695613Z","steps":["trace[1933480777] 'process raft request' (duration: 129.573612ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:30.106514Z","caller":"traceutil/trace.go:171","msg":"trace[949105806] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"133.335183ms","start":"2026-05-19T17:53:29.973147Z","end":"2026-05-19T17:53:30.106482Z","steps":["trace[949105806] 'process raft request' (duration: 106.918985ms)","trace[949105806] 'compare' (duration: 26.184593ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:30.425082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.972377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:30.425278Z","caller":"traceutil/trace.go:171","msg":"trace[1904637960] linearizableReadLoop","detail":"{readStateIndex:26731; appliedIndex:26727; }","duration":"243.700841ms","start":"2026-05-19T17:53:30.181564Z","end":"2026-05-19T17:53:30.425265Z","steps":["trace[1904637960] 'read index received' (duration: 64.12088ms)","trace[1904637960] 'applied index is now lower than readState.Index' (duration: 179.579241ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:30.42535Z","caller":"traceutil/trace.go:171","msg":"trace[379138415] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"424.126193ms","start":"2026-05-19T17:53:30.001216Z","end":"2026-05-19T17:53:30.425342Z","steps":["trace[379138415] 'process raft request' (duration: 244.465ms)","trace[379138415] 'compare' (duration: 178.863254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:30.425394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:30.001185Z","time spent":"424.186815ms","remote":"127.0.0.1:58902","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-05-19T17:53:30.42561Z","caller":"traceutil/trace.go:171","msg":"trace[820535889] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"423.714363ms","start":"2026-05-19T17:53:30.001889Z","end":"2026-05-19T17:53:30.425604Z","steps":["trace[820535889] 'process raft request' (duration: 423.273202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:30.425651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:30.001864Z","time spent":"423.762173ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:53:30.425786Z","caller":"traceutil/trace.go:171","msg":"trace[766929940] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"261.350157ms","start":"2026-05-19T17:53:30.164414Z","end":"2026-05-19T17:53:30.425764Z","steps":["trace[766929940] 'process raft request' (duration: 260.825215ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:30.425883Z","caller":"traceutil/trace.go:171","msg":"trace[395282750] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"423.879496ms","start":"2026-05-19T17:53:30.001996Z","end":"2026-05-19T17:53:30.425876Z","steps":["trace[395282750] 'process raft request' (duration: 423.20724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:30.425931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:30.001988Z","time spent":"423.922528ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:53:30.425953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.837052ms","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-05-19T17:53:30.426029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.457249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:53:30.42604Z","caller":"traceutil/trace.go:171","msg":"trace[229748442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25887; }","duration":"173.996836ms","start":"2026-05-19T17:53:30.252018Z","end":"2026-05-19T17:53:30.426015Z","steps":["trace[229748442] 'agreement among raft nodes before linearized reading' (duration: 173.73713ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:30.426073Z","caller":"traceutil/trace.go:171","msg":"trace[1793967461] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25887; }","duration":"244.50881ms","start":"2026-05-19T17:53:30.181549Z","end":"2026-05-19T17:53:30.426057Z","steps":["trace[1793967461] 'agreement among raft nodes before linearized reading' (duration: 244.431859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:30.426131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.380096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-19T17:53:30.426159Z","caller":"traceutil/trace.go:171","msg":"trace[2022607340] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25887; }","duration":"107.434037ms","start":"2026-05-19T17:53:30.318717Z","end":"2026-05-19T17:53:30.426151Z","steps":["trace[2022607340] 'agreement among raft nodes before linearized reading' (duration: 107.320704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:30.830055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.391538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1115"} {"level":"warn","ts":"2026-05-19T17:53:30.830083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.697875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1021"} {"level":"info","ts":"2026-05-19T17:53:30.830151Z","caller":"traceutil/trace.go:171","msg":"trace[1083338318] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:25888; }","duration":"299.551112ms","start":"2026-05-19T17:53:30.530574Z","end":"2026-05-19T17:53:30.830125Z","steps":["trace[1083338318] 'range keys from in-memory index tree' (duration: 299.102921ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:30.830196Z","caller":"traceutil/trace.go:171","msg":"trace[2056396663] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:25888; }","duration":"299.854919ms","start":"2026-05-19T17:53:30.530313Z","end":"2026-05-19T17:53:30.830167Z","steps":["trace[2056396663] 'range keys from in-memory index tree' (duration: 299.49759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:30.830188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.081522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:53:30.830274Z","caller":"traceutil/trace.go:171","msg":"trace[382988082] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25888; }","duration":"302.183386ms","start":"2026-05-19T17:53:30.528066Z","end":"2026-05-19T17:53:30.830249Z","steps":["trace[382988082] 'count revisions from in-memory index tree' (duration: 301.985371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:30.83033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:30.528016Z","time spent":"302.302128ms","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":22,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:53:30.830393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.296957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:30.830605Z","caller":"traceutil/trace.go:171","msg":"trace[930584228] linearizableReadLoop","detail":"{readStateIndex:26734; appliedIndex:26732; }","duration":"154.082945ms","start":"2026-05-19T17:53:30.676506Z","end":"2026-05-19T17:53:30.830589Z","steps":["trace[930584228] 'read index received' (duration: 96.418621ms)","trace[930584228] 'applied index is now lower than readState.Index' (duration: 57.662994ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:30.830667Z","caller":"traceutil/trace.go:171","msg":"trace[785261284] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"190.094244ms","start":"2026-05-19T17:53:30.640562Z","end":"2026-05-19T17:53:30.830656Z","steps":["trace[785261284] 'process raft request' (duration: 189.947191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:30.83071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.212548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:53:30.830763Z","caller":"traceutil/trace.go:171","msg":"trace[638217782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25890; }","duration":"154.297541ms","start":"2026-05-19T17:53:30.67645Z","end":"2026-05-19T17:53:30.830748Z","steps":["trace[638217782] 'agreement among raft nodes before linearized reading' (duration: 154.214478ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:30.830839Z","caller":"traceutil/trace.go:171","msg":"trace[1370571791] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"286.25604ms","start":"2026-05-19T17:53:30.544367Z","end":"2026-05-19T17:53:30.830623Z","steps":["trace[1370571791] 'process raft request' (duration: 91.635185ms)","trace[1370571791] 'compare' (duration: 194.078092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:30.870575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.73651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:53:30.87067Z","caller":"traceutil/trace.go:171","msg":"trace[379429117] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25891; }","duration":"106.912835ms","start":"2026-05-19T17:53:30.763737Z","end":"2026-05-19T17:53:30.87065Z","steps":["trace[379429117] 'agreement among raft nodes before linearized reading' (duration: 106.71299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:30.871019Z","caller":"traceutil/trace.go:171","msg":"trace[1198371176] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"172.247955ms","start":"2026-05-19T17:53:30.698732Z","end":"2026-05-19T17:53:30.87098Z","steps":["trace[1198371176] 'process raft request' (duration: 171.358253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:31.046531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.066791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:31.046648Z","caller":"traceutil/trace.go:171","msg":"trace[617202443] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"170.8433ms","start":"2026-05-19T17:53:30.875782Z","end":"2026-05-19T17:53:31.046625Z","steps":["trace[617202443] 'process raft request' (duration: 55.580803ms)","trace[617202443] 'compare' (duration: 114.835446ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:31.721648Z","caller":"traceutil/trace.go:171","msg":"trace[1276252179] linearizableReadLoop","detail":"{readStateIndex:26740; appliedIndex:26738; }","duration":"108.725968ms","start":"2026-05-19T17:53:31.612907Z","end":"2026-05-19T17:53:31.721633Z","steps":["trace[1276252179] 'read index received' (duration: 82.932924ms)","trace[1276252179] 'applied index is now lower than readState.Index' (duration: 25.792174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:31.721749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.837311ms","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-05-19T17:53:31.721768Z","caller":"traceutil/trace.go:171","msg":"trace[874649857] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25895; }","duration":"108.909642ms","start":"2026-05-19T17:53:31.612852Z","end":"2026-05-19T17:53:31.721762Z","steps":["trace[874649857] 'agreement among raft nodes before linearized reading' (duration: 108.857831ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:31.721759Z","caller":"traceutil/trace.go:171","msg":"trace[1620484367] transaction","detail":"{read_only:false; response_revision:25895; number_of_response:1; }","duration":"130.827713ms","start":"2026-05-19T17:53:31.590886Z","end":"2026-05-19T17:53:31.721714Z","steps":["trace[1620484367] 'process raft request' (duration: 130.69864ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:31.722159Z","caller":"traceutil/trace.go:171","msg":"trace[1640333449] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"132.905543ms","start":"2026-05-19T17:53:31.589014Z","end":"2026-05-19T17:53:31.72192Z","steps":["trace[1640333449] 'process raft request' (duration: 106.76174ms)","trace[1640333449] 'compare' (duration: 25.703561ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:31.904957Z","caller":"traceutil/trace.go:171","msg":"trace[1374906845] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"177.743746ms","start":"2026-05-19T17:53:31.727162Z","end":"2026-05-19T17:53:31.904906Z","steps":["trace[1374906845] 'process raft request' (duration: 177.568012ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:31.904881Z","caller":"traceutil/trace.go:171","msg":"trace[126859440] linearizableReadLoop","detail":"{readStateIndex:26741; appliedIndex:26740; }","duration":"176.543477ms","start":"2026-05-19T17:53:31.72832Z","end":"2026-05-19T17:53:31.904864Z","steps":["trace[126859440] 'read index received' (duration: 176.408384ms)","trace[126859440] 'applied index is now lower than readState.Index' (duration: 134.253µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:31.905246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.897636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18745"} {"level":"info","ts":"2026-05-19T17:53:31.90535Z","caller":"traceutil/trace.go:171","msg":"trace[2057097183] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25896; }","duration":"177.031399ms","start":"2026-05-19T17:53:31.728297Z","end":"2026-05-19T17:53:31.905328Z","steps":["trace[2057097183] 'agreement among raft nodes before linearized reading' (duration: 176.696921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:32.215967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.613875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:32.216062Z","caller":"traceutil/trace.go:171","msg":"trace[937164267] transaction","detail":"{read_only:false; response_revision:25898; number_of_response:1; }","duration":"232.016209ms","start":"2026-05-19T17:53:31.984029Z","end":"2026-05-19T17:53:32.216045Z","steps":["trace[937164267] 'process raft request' (duration: 123.163428ms)","trace[937164267] 'compare' (duration: 108.500303ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:32.23816Z","caller":"traceutil/trace.go:171","msg":"trace[1322049304] transaction","detail":"{read_only:false; response_revision:25899; number_of_response:1; }","duration":"125.566965ms","start":"2026-05-19T17:53:32.11257Z","end":"2026-05-19T17:53:32.238137Z","steps":["trace[1322049304] 'process raft request' (duration: 125.393571ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:32.577636Z","caller":"traceutil/trace.go:171","msg":"trace[2071644221] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"145.454526ms","start":"2026-05-19T17:53:32.432157Z","end":"2026-05-19T17:53:32.577611Z","steps":["trace[2071644221] 'process raft request' (duration: 145.265592ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:32.577871Z","caller":"traceutil/trace.go:171","msg":"trace[1027219915] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"145.62171ms","start":"2026-05-19T17:53:32.432239Z","end":"2026-05-19T17:53:32.577861Z","steps":["trace[1027219915] 'process raft request' (duration: 145.323213ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:32.578352Z","caller":"traceutil/trace.go:171","msg":"trace[1105659944] transaction","detail":"{read_only:false; response_revision:25903; number_of_response:1; }","duration":"145.588549ms","start":"2026-05-19T17:53:32.432723Z","end":"2026-05-19T17:53:32.578312Z","steps":["trace[1105659944] 'process raft request' (duration: 145.090437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:32.777535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.2479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:32.777679Z","caller":"traceutil/trace.go:171","msg":"trace[1002262668] linearizableReadLoop","detail":"{readStateIndex:26749; appliedIndex:26748; }","duration":"100.726164ms","start":"2026-05-19T17:53:32.676931Z","end":"2026-05-19T17:53:32.777657Z","steps":["trace[1002262668] 'read index received' (duration: 43.751µs)","trace[1002262668] 'applied index is now lower than readState.Index' (duration: 100.680603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:32.777776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.850768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:53:32.777811Z","caller":"traceutil/trace.go:171","msg":"trace[720658635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25904; }","duration":"100.916669ms","start":"2026-05-19T17:53:32.67688Z","end":"2026-05-19T17:53:32.777797Z","steps":["trace[720658635] 'agreement among raft nodes before linearized reading' (duration: 100.847148ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:32.777806Z","caller":"traceutil/trace.go:171","msg":"trace[1049508953] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"195.270251ms","start":"2026-05-19T17:53:32.582506Z","end":"2026-05-19T17:53:32.777776Z","steps":["trace[1049508953] 'process raft request' (duration: 78.61921ms)","trace[1049508953] 'compare' (duration: 116.075996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:33.161665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.198694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-19T17:53:33.161761Z","caller":"traceutil/trace.go:171","msg":"trace[379166838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25907; }","duration":"110.375849ms","start":"2026-05-19T17:53:33.051365Z","end":"2026-05-19T17:53:33.16174Z","steps":["trace[379166838] 'range keys from in-memory index tree' (duration: 109.918187ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:50.093957Z","caller":"traceutil/trace.go:171","msg":"trace[937122819] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"102.114919ms","start":"2026-05-19T17:53:49.991807Z","end":"2026-05-19T17:53:50.093921Z","steps":["trace[937122819] 'process raft request' (duration: 101.877923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:55.947437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.915862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:55.947792Z","caller":"traceutil/trace.go:171","msg":"trace[202245312] transaction","detail":"{read_only:false; response_revision:26080; number_of_response:1; }","duration":"151.489972ms","start":"2026-05-19T17:53:55.79628Z","end":"2026-05-19T17:53:55.94777Z","steps":["trace[202245312] 'process raft request' (duration: 47.15959ms)","trace[202245312] 'compare' (duration: 103.753938ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:56.475091Z","caller":"traceutil/trace.go:171","msg":"trace[1127048788] linearizableReadLoop","detail":"{readStateIndex:26933; appliedIndex:26932; }","duration":"152.901137ms","start":"2026-05-19T17:53:56.322168Z","end":"2026-05-19T17:53:56.475069Z","steps":["trace[1127048788] 'read index received' (duration: 152.823365ms)","trace[1127048788] 'applied index is now lower than readState.Index' (duration: 76.471µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:56.475229Z","caller":"traceutil/trace.go:171","msg":"trace[1460975909] transaction","detail":"{read_only:false; response_revision:26083; number_of_response:1; }","duration":"291.366973ms","start":"2026-05-19T17:53:56.183826Z","end":"2026-05-19T17:53:56.475193Z","steps":["trace[1460975909] 'process raft request' (duration: 291.096327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:56.475283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.103691ms","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-05-19T17:53:56.47531Z","caller":"traceutil/trace.go:171","msg":"trace[412042064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26083; }","duration":"153.183573ms","start":"2026-05-19T17:53:56.322118Z","end":"2026-05-19T17:53:56.475302Z","steps":["trace[412042064] 'agreement among raft nodes before linearized reading' (duration: 153.032869ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:56.561379Z","caller":"traceutil/trace.go:171","msg":"trace[477421081] transaction","detail":"{read_only:false; response_revision:26084; number_of_response:1; }","duration":"237.247794ms","start":"2026-05-19T17:53:56.324082Z","end":"2026-05-19T17:53:56.56133Z","steps":["trace[477421081] 'process raft request' (duration: 235.00252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:56.83565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.695154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:56.836107Z","caller":"traceutil/trace.go:171","msg":"trace[351311151] transaction","detail":"{read_only:false; response_revision:26086; number_of_response:1; }","duration":"247.651907ms","start":"2026-05-19T17:53:56.588413Z","end":"2026-05-19T17:53:56.836065Z","steps":["trace[351311151] 'process raft request' (duration: 115.362558ms)","trace[351311151] 'compare' (duration: 131.325035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:56.990801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.51835ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T17:53:56.991277Z","caller":"traceutil/trace.go:171","msg":"trace[1031591895] transaction","detail":"{read_only:false; response_revision:26087; number_of_response:1; }","duration":"334.980679ms","start":"2026-05-19T17:53:56.656253Z","end":"2026-05-19T17:53:56.991234Z","steps":["trace[1031591895] 'process raft request' (duration: 334.708422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:56.99145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:56.656222Z","time spent":"335.129511ms","remote":"127.0.0.1:58902","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-05-19T17:53:57.139234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.945506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:57.139655Z","caller":"traceutil/trace.go:171","msg":"trace[2125763012] transaction","detail":"{read_only:false; response_revision:26089; number_of_response:1; }","duration":"465.601745ms","start":"2026-05-19T17:53:56.674024Z","end":"2026-05-19T17:53:57.139626Z","steps":["trace[2125763012] 'process raft request' (duration: 465.293257ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:57.139708Z","caller":"traceutil/trace.go:171","msg":"trace[1713831532] transaction","detail":"{read_only:false; response_revision:26090; number_of_response:1; }","duration":"201.764767ms","start":"2026-05-19T17:53:56.937922Z","end":"2026-05-19T17:53:57.139686Z","steps":["trace[1713831532] 'process raft request' (duration: 201.682075ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:57.139791Z","caller":"traceutil/trace.go:171","msg":"trace[1536135494] transaction","detail":"{read_only:false; response_revision:26088; number_of_response:1; }","duration":"480.462465ms","start":"2026-05-19T17:53:56.65892Z","end":"2026-05-19T17:53:57.139382Z","steps":["trace[1536135494] 'process raft request' (duration: 332.293243ms)","trace[1536135494] 'compare' (duration: 147.526717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:57.139805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:56.673996Z","time spent":"465.736748ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:53:57.139895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:56.658905Z","time spent":"480.940616ms","remote":"127.0.0.1:58902","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-05-19T17:53:57.139711Z","caller":"traceutil/trace.go:171","msg":"trace[1945139801] linearizableReadLoop","detail":"{readStateIndex:26937; appliedIndex:26935; }","duration":"493.049288ms","start":"2026-05-19T17:53:56.646632Z","end":"2026-05-19T17:53:57.139682Z","steps":["trace[1945139801] 'read index received' (duration: 57.163081ms)","trace[1945139801] 'applied index is now lower than readState.Index' (duration: 435.883237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:57.140177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.238423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:53:57.140469Z","caller":"traceutil/trace.go:171","msg":"trace[157784225] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26090; }","duration":"493.654423ms","start":"2026-05-19T17:53:56.646574Z","end":"2026-05-19T17:53:57.140228Z","steps":["trace[157784225] 'agreement among raft nodes before linearized reading' (duration: 493.246763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:57.140513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:56.646545Z","time spent":"493.956771ms","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:53:57.143146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.318672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:53:57.143181Z","caller":"traceutil/trace.go:171","msg":"trace[862039194] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:26091; }","duration":"279.412984ms","start":"2026-05-19T17:53:56.86376Z","end":"2026-05-19T17:53:57.143173Z","steps":["trace[862039194] 'agreement among raft nodes before linearized reading' (duration: 279.325692ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:57.143427Z","caller":"traceutil/trace.go:171","msg":"trace[936213216] transaction","detail":"{read_only:false; response_revision:26091; number_of_response:1; }","duration":"128.484816ms","start":"2026-05-19T17:53:57.014934Z","end":"2026-05-19T17:53:57.143418Z","steps":["trace[936213216] 'process raft request' (duration: 128.052846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:57.143645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.140524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:53:57.143745Z","caller":"traceutil/trace.go:171","msg":"trace[393831461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26091; }","duration":"264.310678ms","start":"2026-05-19T17:53:56.879415Z","end":"2026-05-19T17:53:57.143725Z","steps":["trace[393831461] 'agreement among raft nodes before linearized reading' (duration: 264.126444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:57.143805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.372166ms","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-05-19T17:53:57.144005Z","caller":"traceutil/trace.go:171","msg":"trace[527082268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26091; }","duration":"210.671063ms","start":"2026-05-19T17:53:56.93332Z","end":"2026-05-19T17:53:57.143992Z","steps":["trace[527082268] 'agreement among raft nodes before linearized reading' (duration: 210.313474ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:57.281893Z","caller":"traceutil/trace.go:171","msg":"trace[123155795] transaction","detail":"{read_only:false; response_revision:26093; number_of_response:1; }","duration":"134.639715ms","start":"2026-05-19T17:53:57.147227Z","end":"2026-05-19T17:53:57.281867Z","steps":["trace[123155795] 'process raft request' (duration: 134.172003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:57.804421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.519338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:53:57.804505Z","caller":"traceutil/trace.go:171","msg":"trace[787508716] linearizableReadLoop","detail":"{readStateIndex:26946; appliedIndex:26945; }","duration":"128.859825ms","start":"2026-05-19T17:53:57.675631Z","end":"2026-05-19T17:53:57.804491Z","steps":["trace[787508716] 'read index received' (duration: 26.124773ms)","trace[787508716] 'applied index is now lower than readState.Index' (duration: 102.733682ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:57.804593Z","caller":"traceutil/trace.go:171","msg":"trace[963252789] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"178.482734ms","start":"2026-05-19T17:53:57.626102Z","end":"2026-05-19T17:53:57.804585Z","steps":["trace[963252789] 'process raft request' (duration: 75.727051ms)","trace[963252789] 'compare' (duration: 102.306452ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:57.804761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.624986ms","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-05-19T17:53:57.804843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.209253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:53:57.804843Z","caller":"traceutil/trace.go:171","msg":"trace[1474285258] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:26095; }","duration":"113.723178ms","start":"2026-05-19T17:53:57.6911Z","end":"2026-05-19T17:53:57.804823Z","steps":["trace[1474285258] 'agreement among raft nodes before linearized reading' (duration: 113.583825ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:57.804869Z","caller":"traceutil/trace.go:171","msg":"trace[319879142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26095; }","duration":"129.262474ms","start":"2026-05-19T17:53:57.6756Z","end":"2026-05-19T17:53:57.804862Z","steps":["trace[319879142] 'agreement among raft nodes before linearized reading' (duration: 129.206943ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:10.683137Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a37.wal"} {"level":"info","ts":"2026-05-19T17:57:11.995067Z","caller":"traceutil/trace.go:171","msg":"trace[1505928658] linearizableReadLoop","detail":"{readStateIndex:28955; appliedIndex:28954; }","duration":"126.59671ms","start":"2026-05-19T17:57:11.868453Z","end":"2026-05-19T17:57:11.99505Z","steps":["trace[1505928658] 'read index received' (duration: 123.871914ms)","trace[1505928658] 'applied index is now lower than readState.Index' (duration: 2.724216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:11.995197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.736903ms","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":"info","ts":"2026-05-19T17:57:11.995224Z","caller":"traceutil/trace.go:171","msg":"trace[183825083] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:28057; }","duration":"126.776554ms","start":"2026-05-19T17:57:11.868438Z","end":"2026-05-19T17:57:11.995214Z","steps":["trace[183825083] 'agreement among raft nodes before linearized reading' (duration: 126.700042ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:11.995394Z","caller":"traceutil/trace.go:171","msg":"trace[1831701787] transaction","detail":"{read_only:false; response_revision:28057; number_of_response:1; }","duration":"132.604465ms","start":"2026-05-19T17:57:11.862784Z","end":"2026-05-19T17:57:11.995388Z","steps":["trace[1831701787] 'process raft request' (duration: 129.556342ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:12.010015Z","caller":"traceutil/trace.go:171","msg":"trace[1974997116] transaction","detail":"{read_only:false; response_revision:28058; number_of_response:1; }","duration":"131.892629ms","start":"2026-05-19T17:57:11.878094Z","end":"2026-05-19T17:57:12.009987Z","steps":["trace[1974997116] 'process raft request' (duration: 131.676174ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:12.010247Z","caller":"traceutil/trace.go:171","msg":"trace[425784203] transaction","detail":"{read_only:false; response_revision:28060; number_of_response:1; }","duration":"129.575393ms","start":"2026-05-19T17:57:11.880653Z","end":"2026-05-19T17:57:12.010228Z","steps":["trace[425784203] 'process raft request' (duration: 129.275576ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:12.010046Z","caller":"traceutil/trace.go:171","msg":"trace[608086135] transaction","detail":"{read_only:false; response_revision:28059; number_of_response:1; }","duration":"130.466173ms","start":"2026-05-19T17:57:11.879538Z","end":"2026-05-19T17:57:12.010004Z","steps":["trace[608086135] 'process raft request' (duration: 130.361651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:33.851089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.24522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:57:33.851165Z","caller":"traceutil/trace.go:171","msg":"trace[4194106] linearizableReadLoop","detail":"{readStateIndex:29223; appliedIndex:29222; }","duration":"141.981963ms","start":"2026-05-19T17:57:33.709173Z","end":"2026-05-19T17:57:33.851155Z","steps":["trace[4194106] 'read index received' (duration: 6.58191ms)","trace[4194106] 'applied index is now lower than readState.Index' (duration: 135.399073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:33.851271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.101945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:57:33.851288Z","caller":"traceutil/trace.go:171","msg":"trace[1441520768] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:28318; }","duration":"142.145337ms","start":"2026-05-19T17:57:33.709135Z","end":"2026-05-19T17:57:33.85128Z","steps":["trace[1441520768] 'agreement among raft nodes before linearized reading' (duration: 142.045804ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:33.851415Z","caller":"traceutil/trace.go:171","msg":"trace[16384894] transaction","detail":"{read_only:false; response_revision:28318; number_of_response:1; }","duration":"143.626662ms","start":"2026-05-19T17:57:33.707781Z","end":"2026-05-19T17:57:33.851408Z","steps":["trace[16384894] 'compare' (duration: 134.967603ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:48.563017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25487} {"level":"info","ts":"2026-05-19T17:57:48.717159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25487,"took":"149.643298ms","hash":2142317475,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":35766272,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-19T17:57:48.717229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2142317475,"revision":25487,"compact-revision":22074} {"level":"info","ts":"2026-05-19T17:59:13.116071Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T17:59:13.120805Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-19T17:59:13.120916Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-19T18:02:48.56928Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28438} {"level":"info","ts":"2026-05-19T18:02:48.700172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28438,"took":"126.944442ms","hash":3661331814,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":32075776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T18:02:48.700252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3661331814,"revision":28438,"compact-revision":25487} {"level":"info","ts":"2026-05-19T18:07:48.575016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30746} {"level":"info","ts":"2026-05-19T18:07:48.704312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30746,"took":"125.22327ms","hash":2774687271,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":30408704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T18:07:48.704368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2774687271,"revision":30746,"compact-revision":28438} {"level":"info","ts":"2026-05-19T18:12:48.579844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33042} {"level":"info","ts":"2026-05-19T18:12:48.72406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33042,"took":"139.762561ms","hash":2312190883,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":31305728,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T18:12:48.724128Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2312190883,"revision":33042,"compact-revision":30746} {"level":"info","ts":"2026-05-19T18:17:48.585502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35331} {"level":"info","ts":"2026-05-19T18:17:48.728314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35331,"took":"139.478483ms","hash":1080805705,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":31490048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T18:17:48.72844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1080805705,"revision":35331,"compact-revision":33042} {"level":"info","ts":"2026-05-19T18:20:27.105304Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T18:20:27.151434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-19T18:20:27.151559Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-19T18:22:48.592528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37563} {"level":"info","ts":"2026-05-19T18:22:48.710014Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37563,"took":"112.366215ms","hash":1279866492,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":32235520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T18:22:48.710103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1279866492,"revision":37563,"compact-revision":35331}