{"level":"warn","ts":"2026-05-11T00:57:44.784452Z","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-11T00:57:44.784555Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-11T00:57:44.784746Z","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-11T00:57:44.784765Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-11T00:57:44.784785Z","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-11T00:57:44.785622Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-11T00:57:44.785705Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-11T00:57:44.790073Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.072426ms"} {"level":"info","ts":"2026-05-11T00:57:44.796869Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-11T00:57:44.796984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:57:44.797023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-11T00:57:44.797043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:57:44.797066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-11T00:57:44.797117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-11T00:57:44.803073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:57:44.804388Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:57:44.805317Z","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-11T00:57:44.807539Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:57:44.807711Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:57:44.807798Z","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-11T00:57:44.807871Z","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-11T00:57:44.807883Z","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-11T00:57:44.810603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-11T00:57:44.810939Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-11T00:57:44.812493Z","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-11T00:57:44.812667Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-11T00:57:44.812733Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-11T00:57:44.812742Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:57:44.812809Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:57:45.698324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:57:45.698386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:57:45.698423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-11T00:57:45.698442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:57:45.698453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-11T00:57:45.698468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-11T00:57:45.698482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-11T00:57:45.699529Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:45.700444Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:57:45.700439Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:57:45.700505Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:57:45.700848Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:57:45.700875Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:57:45.700957Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:45.70116Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:45.701238Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:45.703888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-11T00:57:45.704077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:58:07.527141Z","caller":"traceutil/trace.go:171","msg":"trace[1342007099] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"144.714648ms","start":"2026-05-11T00:58:07.382399Z","end":"2026-05-11T00:58:07.527114Z","steps":["trace[1342007099] 'process raft request' (duration: 144.568995ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:16.617175Z","caller":"traceutil/trace.go:171","msg":"trace[284515614] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"114.082018ms","start":"2026-05-11T00:58:16.503065Z","end":"2026-05-11T00:58:16.617147Z","steps":["trace[284515614] 'process raft request' (duration: 108.59841ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:22.96645Z","caller":"traceutil/trace.go:171","msg":"trace[1872606237] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"149.304567ms","start":"2026-05-11T00:58:22.817122Z","end":"2026-05-11T00:58:22.966426Z","steps":["trace[1872606237] 'process raft request' (duration: 148.788316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:23.132074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.612056ms","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-11T00:58:23.132256Z","caller":"traceutil/trace.go:171","msg":"trace[1839903536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"137.859442ms","start":"2026-05-11T00:58:22.994371Z","end":"2026-05-11T00:58:23.13223Z","steps":["trace[1839903536] 'range keys from in-memory index tree' (duration: 137.436211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:23.623677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.189936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:58:23.623918Z","caller":"traceutil/trace.go:171","msg":"trace[1576549997] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"444.001716ms","start":"2026-05-11T00:58:23.179865Z","end":"2026-05-11T00:58:23.623867Z","steps":["trace[1576549997] 'process raft request' (duration: 273.934961ms)","trace[1576549997] 'compare' (duration: 169.036463ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:58:23.624038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:58:23.179844Z","time spent":"444.145889ms","remote":"127.0.0.1:54058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:58:23.624241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.350188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T00:58:23.624329Z","caller":"traceutil/trace.go:171","msg":"trace[969376929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"337.488382ms","start":"2026-05-11T00:58:23.286819Z","end":"2026-05-11T00:58:23.624307Z","steps":["trace[969376929] 'agreement among raft nodes before linearized reading' (duration: 337.156525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:23.624381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:58:23.286798Z","time spent":"337.574793ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T00:58:23.623822Z","caller":"traceutil/trace.go:171","msg":"trace[854855575] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"336.93047ms","start":"2026-05-11T00:58:23.286872Z","end":"2026-05-11T00:58:23.623803Z","steps":["trace[854855575] 'read index received' (duration: 166.860695ms)","trace[854855575] 'applied index is now lower than readState.Index' (duration: 170.068245ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:58:23.624704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.074699ms","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-11T00:58:23.624746Z","caller":"traceutil/trace.go:171","msg":"trace[1288358789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"335.154771ms","start":"2026-05-11T00:58:23.289577Z","end":"2026-05-11T00:58:23.624732Z","steps":["trace[1288358789] 'agreement among raft nodes before linearized reading' (duration: 334.986837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:23.624786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:58:23.289559Z","time spent":"335.216482ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T00:58:23.624974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.647762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-476tr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-11T00:58:23.625012Z","caller":"traceutil/trace.go:171","msg":"trace[1346042775] range","detail":"{range_begin:/registry/pods/kube-system/cilium-476tr; range_end:; response_count:1; response_revision:582; }","duration":"245.721064ms","start":"2026-05-11T00:58:23.379281Z","end":"2026-05-11T00:58:23.625002Z","steps":["trace[1346042775] 'agreement among raft nodes before linearized reading' (duration: 245.611331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:41.668867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.239902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g62mg\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-11T00:58:41.668972Z","caller":"traceutil/trace.go:171","msg":"trace[1830339756] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g62mg; range_end:; response_count:1; response_revision:750; }","duration":"185.435697ms","start":"2026-05-11T00:58:41.483516Z","end":"2026-05-11T00:58:41.668952Z","steps":["trace[1830339756] 'range keys from in-memory index tree' (duration: 184.984327ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:55.066476Z","caller":"traceutil/trace.go:171","msg":"trace[804141223] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"126.036776ms","start":"2026-05-11T00:59:54.940388Z","end":"2026-05-11T00:59:55.066425Z","steps":["trace[804141223] 'process raft request' (duration: 38.489347ms)","trace[804141223] 'compare' (duration: 87.380416ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:49.470386Z","caller":"traceutil/trace.go:171","msg":"trace[1205545468] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"220.678713ms","start":"2026-05-11T01:00:49.249679Z","end":"2026-05-11T01:00:49.470357Z","steps":["trace[1205545468] 'process raft request' (duration: 220.302185ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:23.922815Z","caller":"traceutil/trace.go:171","msg":"trace[335646282] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"108.227092ms","start":"2026-05-11T01:02:23.814552Z","end":"2026-05-11T01:02:23.922779Z","steps":["trace[335646282] 'process raft request' (duration: 107.700101ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:24.389769Z","caller":"traceutil/trace.go:171","msg":"trace[1723576267] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"113.514067ms","start":"2026-05-11T01:02:24.276222Z","end":"2026-05-11T01:02:24.389736Z","steps":["trace[1723576267] 'process raft request' (duration: 113.274882ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:04:55.243068Z","caller":"traceutil/trace.go:171","msg":"trace[646025328] linearizableReadLoop","detail":"{readStateIndex:3995; appliedIndex:3994; }","duration":"114.241626ms","start":"2026-05-11T01:04:55.128803Z","end":"2026-05-11T01:04:55.243045Z","steps":["trace[646025328] 'read index received' (duration: 114.133164ms)","trace[646025328] 'applied index is now lower than readState.Index' (duration: 107.562µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:04:55.24312Z","caller":"traceutil/trace.go:171","msg":"trace[1606188307] transaction","detail":"{read_only:false; response_revision:3854; number_of_response:1; }","duration":"159.410261ms","start":"2026-05-11T01:04:55.083687Z","end":"2026-05-11T01:04:55.243097Z","steps":["trace[1606188307] 'process raft request' (duration: 159.200997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:04:55.243316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.121539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:04:55.243416Z","caller":"traceutil/trace.go:171","msg":"trace[1638716236] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:3854; }","duration":"101.243591ms","start":"2026-05-11T01:04:55.142152Z","end":"2026-05-11T01:04:55.243395Z","steps":["trace[1638716236] 'agreement among raft nodes before linearized reading' (duration: 101.067418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:04:55.243476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.661224ms","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-11T01:04:55.243527Z","caller":"traceutil/trace.go:171","msg":"trace[69669139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3854; }","duration":"114.764037ms","start":"2026-05-11T01:04:55.128754Z","end":"2026-05-11T01:04:55.243518Z","steps":["trace[69669139] 'agreement among raft nodes before linearized reading' (duration: 114.42063ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:04:55.35268Z","caller":"traceutil/trace.go:171","msg":"trace[327255028] transaction","detail":"{read_only:false; response_revision:3855; number_of_response:1; }","duration":"104.821177ms","start":"2026-05-11T01:04:55.247832Z","end":"2026-05-11T01:04:55.352653Z","steps":["trace[327255028] 'process raft request' (duration: 50.914537ms)","trace[327255028] 'compare' (duration: 53.726076ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:04:55.352856Z","caller":"traceutil/trace.go:171","msg":"trace[1448413314] transaction","detail":"{read_only:false; response_revision:3856; number_of_response:1; }","duration":"104.318706ms","start":"2026-05-11T01:04:55.248505Z","end":"2026-05-11T01:04:55.352824Z","steps":["trace[1448413314] 'process raft request' (duration: 104.098671ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:06:26.040856Z","caller":"traceutil/trace.go:171","msg":"trace[815619390] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"136.690961ms","start":"2026-05-11T01:06:25.90413Z","end":"2026-05-11T01:06:26.040821Z","steps":["trace[815619390] 'process raft request' (duration: 118.173515ms)","trace[815619390] 'compare' (duration: 18.294941ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:06:44.429549Z","caller":"traceutil/trace.go:171","msg":"trace[1458676401] linearizableReadLoop","detail":"{readStateIndex:5681; appliedIndex:5680; }","duration":"120.048255ms","start":"2026-05-11T01:06:44.309397Z","end":"2026-05-11T01:06:44.429446Z","steps":["trace[1458676401] 'read index received' (duration: 119.625556ms)","trace[1458676401] 'applied index is now lower than readState.Index' (duration: 421.159µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:06:44.429608Z","caller":"traceutil/trace.go:171","msg":"trace[817442901] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"130.956979ms","start":"2026-05-11T01:06:44.298619Z","end":"2026-05-11T01:06:44.429576Z","steps":["trace[817442901] 'process raft request' (duration: 130.371326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:06:44.429943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.510244ms","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-11T01:06:44.430056Z","caller":"traceutil/trace.go:171","msg":"trace[1201578908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5483; }","duration":"120.669068ms","start":"2026-05-11T01:06:44.309363Z","end":"2026-05-11T01:06:44.430032Z","steps":["trace[1201578908] 'agreement among raft nodes before linearized reading' (duration: 120.29917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:06:44.448847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.064785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-11T01:06:44.448958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.154086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-11T01:06:44.448942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.128016ms","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-11T01:06:44.448963Z","caller":"traceutil/trace.go:171","msg":"trace[433252445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5484; }","duration":"119.230968ms","start":"2026-05-11T01:06:44.329709Z","end":"2026-05-11T01:06:44.44894Z","steps":["trace[433252445] 'agreement among raft nodes before linearized reading' (duration: 118.960153ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:06:44.449015Z","caller":"traceutil/trace.go:171","msg":"trace[943012839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5484; }","duration":"119.235648ms","start":"2026-05-11T01:06:44.329764Z","end":"2026-05-11T01:06:44.449Z","steps":["trace[943012839] 'agreement among raft nodes before linearized reading' (duration: 119.032653ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:06:44.449018Z","caller":"traceutil/trace.go:171","msg":"trace[922096967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5484; }","duration":"119.240568ms","start":"2026-05-11T01:06:44.329748Z","end":"2026-05-11T01:06:44.448989Z","steps":["trace[922096967] 'agreement among raft nodes before linearized reading' (duration: 119.002583ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:07:45.730426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-05-11T01:07:45.807442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"75.402062ms","hash":3375613433,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":33693696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T01:07:45.807534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3375613433,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-05-11T01:12:45.805963Z","caller":"traceutil/trace.go:171","msg":"trace[917705359] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"229.75989ms","start":"2026-05-11T01:12:45.576173Z","end":"2026-05-11T01:12:45.805933Z","steps":["trace[917705359] 'process raft request' (duration: 229.585906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:45.994633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.382753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T01:12:45.994866Z","caller":"traceutil/trace.go:171","msg":"trace[575947919] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"262.150278ms","start":"2026-05-11T01:12:45.732655Z","end":"2026-05-11T01:12:45.994805Z","steps":["trace[575947919] 'process raft request' (duration: 156.326525ms)","trace[575947919] 'compare' (duration: 105.22291ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:12:45.994937Z","caller":"traceutil/trace.go:171","msg":"trace[2139721808] linearizableReadLoop","detail":"{readStateIndex:8572; appliedIndex:8571; }","duration":"187.913589ms","start":"2026-05-11T01:12:45.806976Z","end":"2026-05-11T01:12:45.99489Z","steps":["trace[2139721808] 'read index received' (duration: 81.990884ms)","trace[2139721808] 'applied index is now lower than readState.Index' (duration: 105.919264ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:12:45.995094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.122784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:12:45.995151Z","caller":"traceutil/trace.go:171","msg":"trace[1974070286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8286; }","duration":"188.207735ms","start":"2026-05-11T01:12:45.806924Z","end":"2026-05-11T01:12:45.995132Z","steps":["trace[1974070286] 'agreement among raft nodes before linearized reading' (duration: 188.130763ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:46.004211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6059} {"level":"info","ts":"2026-05-11T01:12:46.105791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6059,"took":"99.11032ms","hash":3583210609,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":23285760,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-11T01:12:46.105872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3583210609,"revision":6059,"compact-revision":2977} {"level":"info","ts":"2026-05-11T01:12:58.699404Z","caller":"traceutil/trace.go:171","msg":"trace[1906164363] linearizableReadLoop","detail":"{readStateIndex:8644; appliedIndex:8643; }","duration":"133.987422ms","start":"2026-05-11T01:12:58.56539Z","end":"2026-05-11T01:12:58.699378Z","steps":["trace[1906164363] 'read index received' (duration: 133.676225ms)","trace[1906164363] 'applied index is now lower than readState.Index' (duration: 309.897µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:12:58.699472Z","caller":"traceutil/trace.go:171","msg":"trace[52430032] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"151.757739ms","start":"2026-05-11T01:12:58.547672Z","end":"2026-05-11T01:12:58.69943Z","steps":["trace[52430032] 'process raft request' (duration: 151.480003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:58.699668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.262717ms","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-11T01:12:58.699745Z","caller":"traceutil/trace.go:171","msg":"trace[1819780876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8353; }","duration":"134.39781ms","start":"2026-05-11T01:12:58.565332Z","end":"2026-05-11T01:12:58.69973Z","steps":["trace[1819780876] 'agreement among raft nodes before linearized reading' (duration: 134.162775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:58.699771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.833724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T01:12:58.699861Z","caller":"traceutil/trace.go:171","msg":"trace[82873058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8353; }","duration":"114.971547ms","start":"2026-05-11T01:12:58.584863Z","end":"2026-05-11T01:12:58.699835Z","steps":["trace[82873058] 'agreement among raft nodes before linearized reading' (duration: 114.708361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:13:27.737368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.569028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:13:27.737547Z","caller":"traceutil/trace.go:171","msg":"trace[1722477128] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8563; }","duration":"254.837683ms","start":"2026-05-11T01:13:27.482685Z","end":"2026-05-11T01:13:27.737522Z","steps":["trace[1722477128] 'count revisions from in-memory index tree' (duration: 254.477766ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:13:40.837037Z","caller":"traceutil/trace.go:171","msg":"trace[1960573470] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8672; }","duration":"202.242953ms","start":"2026-05-11T01:13:40.634752Z","end":"2026-05-11T01:13:40.836995Z","steps":["trace[1960573470] 'process raft request' (duration: 131.387156ms)","trace[1960573470] 'compare' (duration: 70.51059ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:13:40.871196Z","caller":"traceutil/trace.go:171","msg":"trace[1494646956] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"236.077903ms","start":"2026-05-11T01:13:40.635085Z","end":"2026-05-11T01:13:40.871163Z","steps":["trace[1494646956] 'process raft request' (duration: 235.848899ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:13:40.871375Z","caller":"traceutil/trace.go:171","msg":"trace[340878657] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"157.485871ms","start":"2026-05-11T01:13:40.713851Z","end":"2026-05-11T01:13:40.871337Z","steps":["trace[340878657] 'process raft request' (duration: 157.241816ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:13:41.844827Z","caller":"traceutil/trace.go:171","msg":"trace[74729335] linearizableReadLoop","detail":"{readStateIndex:8986; appliedIndex:8985; }","duration":"223.907985ms","start":"2026-05-11T01:13:41.620888Z","end":"2026-05-11T01:13:41.844796Z","steps":["trace[74729335] 'read index received' (duration: 223.700331ms)","trace[74729335] 'applied index is now lower than readState.Index' (duration: 205.944µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:13:41.844967Z","caller":"traceutil/trace.go:171","msg":"trace[648117692] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"231.081708ms","start":"2026-05-11T01:13:41.613835Z","end":"2026-05-11T01:13:41.844917Z","steps":["trace[648117692] 'process raft request' (duration: 230.780892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:13:41.845238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.312403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-05-11T01:13:41.845331Z","caller":"traceutil/trace.go:171","msg":"trace[578252360] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8682; }","duration":"224.459987ms","start":"2026-05-11T01:13:41.62085Z","end":"2026-05-11T01:13:41.84531Z","steps":["trace[578252360] 'agreement among raft nodes before linearized reading' (duration: 224.12115ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:14:25.579976Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b7.wal"} {"level":"info","ts":"2026-05-11T01:14:42.337674Z","caller":"traceutil/trace.go:171","msg":"trace[1153994161] transaction","detail":"{read_only:false; response_revision:9319; number_of_response:1; }","duration":"125.141285ms","start":"2026-05-11T01:14:42.212499Z","end":"2026-05-11T01:14:42.33764Z","steps":["trace[1153994161] 'process raft request' (duration: 124.91273ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:15:28.78099Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:15:28.787608Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T01:15:28.787727Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T01:17:46.011293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8285} {"level":"info","ts":"2026-05-11T01:17:46.096757Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8285,"took":"81.449319ms","hash":2732249470,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":26488832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T01:17:46.096851Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2732249470,"revision":8285,"compact-revision":6059} {"level":"info","ts":"2026-05-11T01:18:30.004198Z","caller":"traceutil/trace.go:171","msg":"trace[1858815554] transaction","detail":"{read_only:false; response_revision:11210; number_of_response:1; }","duration":"111.608405ms","start":"2026-05-11T01:18:29.892554Z","end":"2026-05-11T01:18:30.004162Z","steps":["trace[1858815554] 'process raft request' (duration: 111.404081ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:18:30.015381Z","caller":"traceutil/trace.go:171","msg":"trace[869318734] transaction","detail":"{read_only:false; response_revision:11211; number_of_response:1; }","duration":"121.411032ms","start":"2026-05-11T01:18:29.893936Z","end":"2026-05-11T01:18:30.015347Z","steps":["trace[869318734] 'process raft request' (duration: 121.218138ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:22:46.020045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10774} {"level":"info","ts":"2026-05-11T01:22:46.102625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10774,"took":"80.067619ms","hash":1428554074,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":27414528,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-11T01:22:46.102683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1428554074,"revision":10774,"compact-revision":8285} {"level":"info","ts":"2026-05-11T01:24:40.591661Z","caller":"traceutil/trace.go:171","msg":"trace[1906605771] transaction","detail":"{read_only:false; response_revision:13713; number_of_response:1; }","duration":"113.218845ms","start":"2026-05-11T01:24:40.478421Z","end":"2026-05-11T01:24:40.59164Z","steps":["trace[1906605771] 'process raft request' (duration: 113.083332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:26:39.581788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.431387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T01:26:39.581887Z","caller":"traceutil/trace.go:171","msg":"trace[1217946480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15025; }","duration":"217.577629ms","start":"2026-05-11T01:26:39.36429Z","end":"2026-05-11T01:26:39.581868Z","steps":["trace[1217946480] 'range keys from in-memory index tree' (duration: 217.247303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:26:39.582001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.022819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-11T01:26:39.582108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.68076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T01:26:39.582081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.775893ms","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-11T01:26:39.582166Z","caller":"traceutil/trace.go:171","msg":"trace[319932013] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15026; }","duration":"175.793292ms","start":"2026-05-11T01:26:39.406361Z","end":"2026-05-11T01:26:39.582154Z","steps":["trace[319932013] 'agreement among raft nodes before linearized reading' (duration: 175.70223ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.582198Z","caller":"traceutil/trace.go:171","msg":"trace[559533861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15025; }","duration":"217.946279ms","start":"2026-05-11T01:26:39.364226Z","end":"2026-05-11T01:26:39.582172Z","steps":["trace[559533861] 'range keys from in-memory index tree' (duration: 217.59255ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.582138Z","caller":"traceutil/trace.go:171","msg":"trace[923856740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15025; }","duration":"218.298184ms","start":"2026-05-11T01:26:39.363809Z","end":"2026-05-11T01:26:39.582107Z","steps":["trace[923856740] 'range keys from in-memory index tree' (duration: 217.857916ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.582011Z","caller":"traceutil/trace.go:171","msg":"trace[1469028239] linearizableReadLoop","detail":"{readStateIndex:15532; appliedIndex:15531; }","duration":"175.577968ms","start":"2026-05-11T01:26:39.406411Z","end":"2026-05-11T01:26:39.581989Z","steps":["trace[1469028239] 'read index received' (duration: 86.753252ms)","trace[1469028239] 'applied index is now lower than readState.Index' (duration: 88.823586ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:26:39.582384Z","caller":"traceutil/trace.go:171","msg":"trace[1890686512] transaction","detail":"{read_only:false; response_revision:15026; number_of_response:1; }","duration":"208.836825ms","start":"2026-05-11T01:26:39.373286Z","end":"2026-05-11T01:26:39.582122Z","steps":["trace[1890686512] 'process raft request' (duration: 119.972278ms)","trace[1890686512] 'compare' (duration: 88.509379ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:26:39.582581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.878006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-05-11T01:26:39.582632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.234082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-11T01:26:39.582645Z","caller":"traceutil/trace.go:171","msg":"trace[867712255] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15026; }","duration":"175.962487ms","start":"2026-05-11T01:26:39.406664Z","end":"2026-05-11T01:26:39.582626Z","steps":["trace[867712255] 'agreement among raft nodes before linearized reading' (duration: 175.790774ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.582691Z","caller":"traceutil/trace.go:171","msg":"trace[831056155] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15026; }","duration":"175.310303ms","start":"2026-05-11T01:26:39.407366Z","end":"2026-05-11T01:26:39.582676Z","steps":["trace[831056155] 'agreement among raft nodes before linearized reading' (duration: 175.14025ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.711187Z","caller":"traceutil/trace.go:171","msg":"trace[1643388208] transaction","detail":"{read_only:false; response_revision:15027; number_of_response:1; }","duration":"124.31984ms","start":"2026-05-11T01:26:39.586823Z","end":"2026-05-11T01:26:39.711142Z","steps":["trace[1643388208] 'process raft request' (duration: 123.810859ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.711536Z","caller":"traceutil/trace.go:171","msg":"trace[1332159821] transaction","detail":"{read_only:false; response_revision:15028; number_of_response:1; }","duration":"124.492484ms","start":"2026-05-11T01:26:39.587014Z","end":"2026-05-11T01:26:39.711506Z","steps":["trace[1332159821] 'process raft request' (duration: 124.28577ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.71158Z","caller":"traceutil/trace.go:171","msg":"trace[2040478701] transaction","detail":"{read_only:false; response_revision:15029; number_of_response:1; }","duration":"124.459994ms","start":"2026-05-11T01:26:39.587099Z","end":"2026-05-11T01:26:39.711559Z","steps":["trace[2040478701] 'process raft request' (duration: 124.340962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:26:39.711634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.259924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:26:39.711524Z","caller":"traceutil/trace.go:171","msg":"trace[2035294085] linearizableReadLoop","detail":"{readStateIndex:15535; appliedIndex:15532; }","duration":"112.140652ms","start":"2026-05-11T01:26:39.599358Z","end":"2026-05-11T01:26:39.711498Z","steps":["trace[2035294085] 'read index received' (duration: 111.299163ms)","trace[2035294085] 'applied index is now lower than readState.Index' (duration: 837.089µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:26:39.71168Z","caller":"traceutil/trace.go:171","msg":"trace[374366495] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15029; }","duration":"112.338965ms","start":"2026-05-11T01:26:39.599331Z","end":"2026-05-11T01:26:39.71167Z","steps":["trace[374366495] 'agreement among raft nodes before linearized reading' (duration: 112.247434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:26:39.711809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.244063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:26:39.71184Z","caller":"traceutil/trace.go:171","msg":"trace[2097586731] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15029; }","duration":"112.360916ms","start":"2026-05-11T01:26:39.599469Z","end":"2026-05-11T01:26:39.71183Z","steps":["trace[2097586731] 'agreement among raft nodes before linearized reading' (duration: 112.252834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:26:39.711836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.71197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T01:26:39.711886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.912334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-11T01:26:39.711927Z","caller":"traceutil/trace.go:171","msg":"trace[2074844108] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15029; }","duration":"101.986267ms","start":"2026-05-11T01:26:39.609932Z","end":"2026-05-11T01:26:39.711918Z","steps":["trace[2074844108] 'agreement among raft nodes before linearized reading' (duration: 101.825153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:26:39.711949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.081136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T01:26:39.711952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.004495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:26:39.711972Z","caller":"traceutil/trace.go:171","msg":"trace[201042074] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15029; }","duration":"108.124637ms","start":"2026-05-11T01:26:39.603842Z","end":"2026-05-11T01:26:39.711966Z","steps":["trace[201042074] 'agreement among raft nodes before linearized reading' (duration: 108.075396ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.711976Z","caller":"traceutil/trace.go:171","msg":"trace[548341624] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15029; }","duration":"109.048266ms","start":"2026-05-11T01:26:39.602921Z","end":"2026-05-11T01:26:39.711969Z","steps":["trace[548341624] 'agreement among raft nodes before linearized reading' (duration: 109.008255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:26:39.712007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.299662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-11T01:26:39.712034Z","caller":"traceutil/trace.go:171","msg":"trace[478995053] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15029; }","duration":"110.345984ms","start":"2026-05-11T01:26:39.601681Z","end":"2026-05-11T01:26:39.712027Z","steps":["trace[478995053] 'agreement among raft nodes before linearized reading' (duration: 110.244492ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:26:39.711899Z","caller":"traceutil/trace.go:171","msg":"trace[954848568] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15029; }","duration":"109.808132ms","start":"2026-05-11T01:26:39.602077Z","end":"2026-05-11T01:26:39.711885Z","steps":["trace[954848568] 'agreement among raft nodes before linearized reading' (duration: 109.69559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:26:39.712104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.521019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:26:39.712129Z","caller":"traceutil/trace.go:171","msg":"trace[707336811] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15029; }","duration":"112.582451ms","start":"2026-05-11T01:26:39.599541Z","end":"2026-05-11T01:26:39.712123Z","steps":["trace[707336811] 'agreement among raft nodes before linearized reading' (duration: 112.54473ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:27:46.026716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12881} {"level":"info","ts":"2026-05-11T01:27:46.102202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12881,"took":"73.600971ms","hash":891543476,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T01:27:46.102256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":891543476,"revision":12881,"compact-revision":10774} {"level":"info","ts":"2026-05-11T01:32:21.021737Z","caller":"traceutil/trace.go:171","msg":"trace[571384871] linearizableReadLoop","detail":"{readStateIndex:18384; appliedIndex:18382; }","duration":"106.016377ms","start":"2026-05-11T01:32:20.915695Z","end":"2026-05-11T01:32:21.021711Z","steps":["trace[571384871] 'read index received' (duration: 40.078266ms)","trace[571384871] 'applied index is now lower than readState.Index' (duration: 65.936701ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:32:21.021995Z","caller":"traceutil/trace.go:171","msg":"trace[374656585] transaction","detail":"{read_only:false; response_revision:17792; number_of_response:1; }","duration":"127.580982ms","start":"2026-05-11T01:32:20.894395Z","end":"2026-05-11T01:32:21.021976Z","steps":["trace[374656585] 'process raft request' (duration: 127.181474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:21.022561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.832323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-db-init-pnhrm\" ","response":"range_response_count:1 size:7233"} {"level":"info","ts":"2026-05-11T01:32:21.02267Z","caller":"traceutil/trace.go:171","msg":"trace[1636977542] range","detail":"{range_begin:/registry/pods/openstack/heat-db-init-pnhrm; range_end:; response_count:1; response_revision:17792; }","duration":"107.026567ms","start":"2026-05-11T01:32:20.915617Z","end":"2026-05-11T01:32:21.022643Z","steps":["trace[1636977542] 'agreement among raft nodes before linearized reading' (duration: 106.625609ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:32:23.111612Z","caller":"traceutil/trace.go:171","msg":"trace[1545599281] transaction","detail":"{read_only:false; response_revision:17807; number_of_response:1; }","duration":"150.470014ms","start":"2026-05-11T01:32:22.961117Z","end":"2026-05-11T01:32:23.111587Z","steps":["trace[1545599281] 'process raft request' (duration: 150.331032ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:32:23.205633Z","caller":"traceutil/trace.go:171","msg":"trace[605904589] linearizableReadLoop","detail":"{readStateIndex:18400; appliedIndex:18399; }","duration":"146.600302ms","start":"2026-05-11T01:32:23.059006Z","end":"2026-05-11T01:32:23.205607Z","steps":["trace[605904589] 'read index received' (duration: 52.573999ms)","trace[605904589] 'applied index is now lower than readState.Index' (duration: 94.025263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:32:23.206054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.651163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.206066Z","caller":"traceutil/trace.go:171","msg":"trace[1942957554] transaction","detail":"{read_only:false; response_revision:17808; number_of_response:1; }","duration":"238.423709ms","start":"2026-05-11T01:32:22.96759Z","end":"2026-05-11T01:32:23.206014Z","steps":["trace[1942957554] 'process raft request' (duration: 219.894829ms)","trace[1942957554] 'compare' (duration: 17.927848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:32:23.206157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.584029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:32:23.206163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.469539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.206184Z","caller":"traceutil/trace.go:171","msg":"trace[563515071] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:17808; }","duration":"144.61005ms","start":"2026-05-11T01:32:23.061566Z","end":"2026-05-11T01:32:23.206176Z","steps":["trace[563515071] 'agreement among raft nodes before linearized reading' (duration: 144.569469ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:32:23.206224Z","caller":"traceutil/trace.go:171","msg":"trace[104028573] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:17808; }","duration":"146.52301ms","start":"2026-05-11T01:32:23.059679Z","end":"2026-05-11T01:32:23.206202Z","steps":["trace[104028573] 'agreement among raft nodes before linearized reading' (duration: 146.462729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.206402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.731054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.20644Z","caller":"traceutil/trace.go:171","msg":"trace[161063983] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:17808; }","duration":"146.775406ms","start":"2026-05-11T01:32:23.059656Z","end":"2026-05-11T01:32:23.206431Z","steps":["trace[161063983] 'agreement among raft nodes before linearized reading' (duration: 146.722655ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:32:23.206108Z","caller":"traceutil/trace.go:171","msg":"trace[474344988] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:17808; }","duration":"146.731425ms","start":"2026-05-11T01:32:23.059364Z","end":"2026-05-11T01:32:23.206095Z","steps":["trace[474344988] 'agreement among raft nodes before linearized reading' (duration: 146.635193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.206558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.936839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.206604Z","caller":"traceutil/trace.go:171","msg":"trace[2090182855] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:17808; }","duration":"145.99434ms","start":"2026-05-11T01:32:23.0606Z","end":"2026-05-11T01:32:23.206594Z","steps":["trace[2090182855] 'agreement among raft nodes before linearized reading' (duration: 145.923149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.206071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.01893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:32:23.20666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.079733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.206691Z","caller":"traceutil/trace.go:171","msg":"trace[1948884650] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:17808; }","duration":"147.115603ms","start":"2026-05-11T01:32:23.059565Z","end":"2026-05-11T01:32:23.20668Z","steps":["trace[1948884650] 'agreement among raft nodes before linearized reading' (duration: 147.080382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.206861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.119954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:32:23.206626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.973311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.207032Z","caller":"traceutil/trace.go:171","msg":"trace[833783663] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:17808; }","duration":"147.267407ms","start":"2026-05-11T01:32:23.059697Z","end":"2026-05-11T01:32:23.206964Z","steps":["trace[833783663] 'agreement among raft nodes before linearized reading' (duration: 147.055523ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:32:23.207027Z","caller":"traceutil/trace.go:171","msg":"trace[1754179589] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:17808; }","duration":"147.348408ms","start":"2026-05-11T01:32:23.059635Z","end":"2026-05-11T01:32:23.206984Z","steps":["trace[1754179589] 'agreement among raft nodes before linearized reading' (duration: 146.968791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.206377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.63845ms","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-11T01:32:23.2073Z","caller":"traceutil/trace.go:171","msg":"trace[1492295748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17808; }","duration":"145.618062ms","start":"2026-05-11T01:32:23.061669Z","end":"2026-05-11T01:32:23.207287Z","steps":["trace[1492295748] 'agreement among raft nodes before linearized reading' (duration: 144.484987ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:32:23.207329Z","caller":"traceutil/trace.go:171","msg":"trace[1451818244] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:17808; }","duration":"147.727286ms","start":"2026-05-11T01:32:23.058969Z","end":"2026-05-11T01:32:23.206696Z","steps":["trace[1451818244] 'agreement among raft nodes before linearized reading' (duration: 146.98664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.20764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.771575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.207731Z","caller":"traceutil/trace.go:171","msg":"trace[651511777] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:17808; }","duration":"145.957438ms","start":"2026-05-11T01:32:23.061749Z","end":"2026-05-11T01:32:23.207707Z","steps":["trace[651511777] 'agreement among raft nodes before linearized reading' (duration: 145.742104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.207969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.843192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.208038Z","caller":"traceutil/trace.go:171","msg":"trace[1322296901] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:17808; }","duration":"141.945014ms","start":"2026-05-11T01:32:23.066083Z","end":"2026-05-11T01:32:23.208028Z","steps":["trace[1322296901] 'agreement among raft nodes before linearized reading' (duration: 141.818621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.208356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.275341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.208434Z","caller":"traceutil/trace.go:171","msg":"trace[1290683603] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:17808; }","duration":"142.375533ms","start":"2026-05-11T01:32:23.06604Z","end":"2026-05-11T01:32:23.208416Z","steps":["trace[1290683603] 'agreement among raft nodes before linearized reading' (duration: 142.24823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:32:23.208336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.717135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:32:23.208764Z","caller":"traceutil/trace.go:171","msg":"trace[1188015464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17808; }","duration":"120.212986ms","start":"2026-05-11T01:32:23.088531Z","end":"2026-05-11T01:32:23.208744Z","steps":["trace[1188015464] 'agreement among raft nodes before linearized reading' (duration: 119.672825ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:32:46.033886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15469} {"level":"info","ts":"2026-05-11T01:32:46.128023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15469,"took":"90.53719ms","hash":3644064789,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":33943552,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T01:32:46.128135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3644064789,"revision":15469,"compact-revision":12881} {"level":"info","ts":"2026-05-11T01:35:33.274731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:35:33.280156Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T01:35:33.280265Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-11T01:36:59.127709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.221595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:36:59.127956Z","caller":"traceutil/trace.go:171","msg":"trace[170337082] linearizableReadLoop","detail":"{readStateIndex:20524; appliedIndex:20522; }","duration":"292.96752ms","start":"2026-05-11T01:36:58.834973Z","end":"2026-05-11T01:36:59.12794Z","steps":["trace[170337082] 'read index received' (duration: 34.78647ms)","trace[170337082] 'applied index is now lower than readState.Index' (duration: 258.1802ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:36:59.128077Z","caller":"traceutil/trace.go:171","msg":"trace[1973948814] transaction","detail":"{read_only:false; response_revision:19861; number_of_response:1; }","duration":"265.853354ms","start":"2026-05-11T01:36:58.8622Z","end":"2026-05-11T01:36:59.128053Z","steps":["trace[1973948814] 'process raft request' (duration: 265.65172ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:59.128118Z","caller":"traceutil/trace.go:171","msg":"trace[1039532827] transaction","detail":"{read_only:false; response_revision:19860; number_of_response:1; }","duration":"295.948775ms","start":"2026-05-11T01:36:58.832148Z","end":"2026-05-11T01:36:59.128097Z","steps":["trace[1039532827] 'process raft request' (duration: 145.25634ms)","trace[1039532827] 'compare' (duration: 149.99932ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:36:59.128312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.922897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-11T01:36:59.128346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.357549ms","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-11T01:36:59.128394Z","caller":"traceutil/trace.go:171","msg":"trace[1698173105] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:19861; }","duration":"243.042619ms","start":"2026-05-11T01:36:58.885328Z","end":"2026-05-11T01:36:59.128371Z","steps":["trace[1698173105] 'agreement among raft nodes before linearized reading' (duration: 242.822585ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:59.128424Z","caller":"traceutil/trace.go:171","msg":"trace[1688757272] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19861; }","duration":"293.467731ms","start":"2026-05-11T01:36:58.834938Z","end":"2026-05-11T01:36:59.128406Z","steps":["trace[1688757272] 'agreement among raft nodes before linearized reading' (duration: 293.233346ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:59.339942Z","caller":"traceutil/trace.go:171","msg":"trace[591542353] linearizableReadLoop","detail":"{readStateIndex:20527; appliedIndex:20526; }","duration":"135.726516ms","start":"2026-05-11T01:36:59.204185Z","end":"2026-05-11T01:36:59.339911Z","steps":["trace[591542353] 'read index received' (duration: 99.288491ms)","trace[591542353] 'applied index is now lower than readState.Index' (duration: 36.436655ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:36:59.340013Z","caller":"traceutil/trace.go:171","msg":"trace[523806054] transaction","detail":"{read_only:false; response_revision:19862; number_of_response:1; }","duration":"147.245071ms","start":"2026-05-11T01:36:59.19272Z","end":"2026-05-11T01:36:59.339965Z","steps":["trace[523806054] 'process raft request' (duration: 110.853747ms)","trace[523806054] 'compare' (duration: 36.17917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:36:59.340202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.000062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-11T01:36:59.340261Z","caller":"traceutil/trace.go:171","msg":"trace[1220777994] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:19862; }","duration":"136.103735ms","start":"2026-05-11T01:36:59.204142Z","end":"2026-05-11T01:36:59.340246Z","steps":["trace[1220777994] 'agreement among raft nodes before linearized reading' (duration: 135.880349ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:59.750005Z","caller":"traceutil/trace.go:171","msg":"trace[2045588277] transaction","detail":"{read_only:false; response_revision:19863; number_of_response:1; }","duration":"139.073788ms","start":"2026-05-11T01:36:59.610905Z","end":"2026-05-11T01:36:59.749979Z","steps":["trace[2045588277] 'process raft request' (duration: 138.863924ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:37:00.038214Z","caller":"traceutil/trace.go:171","msg":"trace[982614817] transaction","detail":"{read_only:false; response_revision:19864; number_of_response:1; }","duration":"131.662311ms","start":"2026-05-11T01:36:59.906527Z","end":"2026-05-11T01:37:00.038189Z","steps":["trace[982614817] 'process raft request' (duration: 131.517238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:37:00.328616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.414154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:37:00.328722Z","caller":"traceutil/trace.go:171","msg":"trace[310432196] transaction","detail":"{read_only:false; response_revision:19865; number_of_response:1; }","duration":"202.159869ms","start":"2026-05-11T01:37:00.126543Z","end":"2026-05-11T01:37:00.328703Z","steps":["trace[310432196] 'process raft request' (duration: 87.576012ms)","trace[310432196] 'compare' (duration: 114.25074ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:37:00.336162Z","caller":"traceutil/trace.go:171","msg":"trace[558122415] transaction","detail":"{read_only:false; response_revision:19866; number_of_response:1; }","duration":"152.554115ms","start":"2026-05-11T01:37:00.183568Z","end":"2026-05-11T01:37:00.336122Z","steps":["trace[558122415] 'process raft request' (duration: 152.409542ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:37:46.038879Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18069} {"level":"info","ts":"2026-05-11T01:37:46.146472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18069,"took":"103.88615ms","hash":3765478607,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":30310400,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T01:37:46.146537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3765478607,"revision":18069,"compact-revision":15469} {"level":"info","ts":"2026-05-11T01:40:32.92243Z","caller":"traceutil/trace.go:171","msg":"trace[667854857] linearizableReadLoop","detail":"{readStateIndex:22919; appliedIndex:22918; }","duration":"117.979391ms","start":"2026-05-11T01:40:32.804421Z","end":"2026-05-11T01:40:32.9224Z","steps":["trace[667854857] 'read index received' (duration: 117.812677ms)","trace[667854857] 'applied index is now lower than readState.Index' (duration: 165.234µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:40:32.922474Z","caller":"traceutil/trace.go:171","msg":"trace[750686165] transaction","detail":"{read_only:false; response_revision:22187; number_of_response:1; }","duration":"154.131528ms","start":"2026-05-11T01:40:32.768313Z","end":"2026-05-11T01:40:32.922445Z","steps":["trace[750686165] 'process raft request' (duration: 153.893954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:40:32.922703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.269706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-11T01:40:32.922788Z","caller":"traceutil/trace.go:171","msg":"trace[1759990665] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:22187; }","duration":"118.404739ms","start":"2026-05-11T01:40:32.804363Z","end":"2026-05-11T01:40:32.922767Z","steps":["trace[1759990665] 'agreement among raft nodes before linearized reading' (duration: 118.151174ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:40:33.084254Z","caller":"traceutil/trace.go:171","msg":"trace[1172407527] transaction","detail":"{read_only:false; response_revision:22188; number_of_response:1; }","duration":"156.597371ms","start":"2026-05-11T01:40:32.927598Z","end":"2026-05-11T01:40:33.084195Z","steps":["trace[1172407527] 'process raft request' (duration: 126.831368ms)","trace[1172407527] 'compare' (duration: 29.563728ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:42:46.045707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20368} {"level":"info","ts":"2026-05-11T01:42:46.151415Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20368,"took":"101.838455ms","hash":4103076428,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":42422272,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-11T01:42:46.151471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4103076428,"revision":20368,"compact-revision":18069} {"level":"info","ts":"2026-05-11T01:43:57.108015Z","caller":"traceutil/trace.go:171","msg":"trace[889064715] transaction","detail":"{read_only:false; response_revision:24232; number_of_response:1; }","duration":"121.193657ms","start":"2026-05-11T01:43:56.986799Z","end":"2026-05-11T01:43:57.107993Z","steps":["trace[889064715] 'process raft request' (duration: 121.075624ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:57.311074Z","caller":"traceutil/trace.go:171","msg":"trace[651813766] transaction","detail":"{read_only:false; response_revision:24233; number_of_response:1; }","duration":"321.952287ms","start":"2026-05-11T01:43:56.989052Z","end":"2026-05-11T01:43:57.311004Z","steps":["trace[651813766] 'process raft request' (duration: 299.152172ms)","trace[651813766] 'compare' (duration: 22.423607ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:57.311182Z","caller":"traceutil/trace.go:171","msg":"trace[1688985381] transaction","detail":"{read_only:false; response_revision:24234; number_of_response:1; }","duration":"146.167368ms","start":"2026-05-11T01:43:57.164995Z","end":"2026-05-11T01:43:57.311162Z","steps":["trace[1688985381] 'process raft request' (duration: 145.816511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:57.311285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:56.989033Z","time spent":"322.143922ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:02.204953Z","caller":"traceutil/trace.go:171","msg":"trace[61482352] transaction","detail":"{read_only:false; response_revision:24276; number_of_response:1; }","duration":"108.871415ms","start":"2026-05-11T01:44:02.096055Z","end":"2026-05-11T01:44:02.204927Z","steps":["trace[61482352] 'process raft request' (duration: 108.702552ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:02.350775Z","caller":"traceutil/trace.go:171","msg":"trace[1896370511] transaction","detail":"{read_only:false; response_revision:24277; number_of_response:1; }","duration":"101.132151ms","start":"2026-05-11T01:44:02.249603Z","end":"2026-05-11T01:44:02.350735Z","steps":["trace[1896370511] 'process raft request' (duration: 93.692112ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:02.965728Z","caller":"traceutil/trace.go:171","msg":"trace[1847932901] linearizableReadLoop","detail":"{readStateIndex:25066; appliedIndex:25065; }","duration":"103.579793ms","start":"2026-05-11T01:44:02.862122Z","end":"2026-05-11T01:44:02.965702Z","steps":["trace[1847932901] 'read index received' (duration: 29.357335ms)","trace[1847932901] 'applied index is now lower than readState.Index' (duration: 74.221398ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:02.96574Z","caller":"traceutil/trace.go:171","msg":"trace[956290012] transaction","detail":"{read_only:false; response_revision:24280; number_of_response:1; }","duration":"138.403382ms","start":"2026-05-11T01:44:02.827301Z","end":"2026-05-11T01:44:02.965704Z","steps":["trace[956290012] 'process raft request' (duration: 64.170634ms)","trace[956290012] 'compare' (duration: 74.096746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:02.965884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.758237ms","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-11T01:44:02.965918Z","caller":"traceutil/trace.go:171","msg":"trace[1135637106] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24280; }","duration":"103.865499ms","start":"2026-05-11T01:44:02.862042Z","end":"2026-05-11T01:44:02.965907Z","steps":["trace[1135637106] 'agreement among raft nodes before linearized reading' (duration: 103.762067ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:03.035309Z","caller":"traceutil/trace.go:171","msg":"trace[486147577] transaction","detail":"{read_only:false; response_revision:24281; number_of_response:1; }","duration":"121.313759ms","start":"2026-05-11T01:44:02.913958Z","end":"2026-05-11T01:44:03.035272Z","steps":["trace[486147577] 'process raft request' (duration: 121.067044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:03.227534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.664993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:44:03.227614Z","caller":"traceutil/trace.go:171","msg":"trace[1166402784] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24281; }","duration":"118.808478ms","start":"2026-05-11T01:44:03.108786Z","end":"2026-05-11T01:44:03.227594Z","steps":["trace[1166402784] 'count revisions from in-memory index tree' (duration: 118.579112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:03.228442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.762779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T01:44:03.228498Z","caller":"traceutil/trace.go:171","msg":"trace[1394522890] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24281; }","duration":"121.870092ms","start":"2026-05-11T01:44:03.106615Z","end":"2026-05-11T01:44:03.228485Z","steps":["trace[1394522890] 'count revisions from in-memory index tree' (duration: 121.684238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:03.587174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.739735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-05-11T01:44:03.58741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.923012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:03.587445Z","caller":"traceutil/trace.go:171","msg":"trace[82074966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24283; }","duration":"288.016785ms","start":"2026-05-11T01:44:03.299422Z","end":"2026-05-11T01:44:03.587439Z","steps":["trace[82074966] 'agreement among raft nodes before linearized reading' (duration: 287.947723ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:03.587337Z","caller":"traceutil/trace.go:171","msg":"trace[2055839680] transaction","detail":"{read_only:false; response_revision:24283; number_of_response:1; }","duration":"321.256772ms","start":"2026-05-11T01:44:03.266057Z","end":"2026-05-11T01:44:03.587314Z","steps":["trace[2055839680] 'process raft request' (duration: 202.257632ms)","trace[2055839680] 'compare' (duration: 118.630592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:03.587713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:03.266034Z","time spent":"321.6211ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:03.58831Z","caller":"traceutil/trace.go:171","msg":"trace[1407981174] linearizableReadLoop","detail":"{readStateIndex:25069; appliedIndex:25068; }","duration":"287.818381ms","start":"2026-05-11T01:44:03.299482Z","end":"2026-05-11T01:44:03.5873Z","steps":["trace[1407981174] 'read index received' (duration: 168.950443ms)","trace[1407981174] 'applied index is now lower than readState.Index' (duration: 118.865898ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:03.649566Z","caller":"traceutil/trace.go:171","msg":"trace[1006838589] transaction","detail":"{read_only:false; response_revision:24284; number_of_response:1; }","duration":"339.761297ms","start":"2026-05-11T01:44:03.309784Z","end":"2026-05-11T01:44:03.649545Z","steps":["trace[1006838589] 'process raft request' (duration: 339.541991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:03.649782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:03.309766Z","time spent":"339.90338ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:03.64997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.925009ms","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-11T01:44:03.650064Z","caller":"traceutil/trace.go:171","msg":"trace[1278637208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24285; }","duration":"316.051662ms","start":"2026-05-11T01:44:03.333989Z","end":"2026-05-11T01:44:03.650041Z","steps":["trace[1278637208] 'agreement among raft nodes before linearized reading' (duration: 315.769196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:03.650126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:03.333979Z","time spent":"316.132344ms","remote":"127.0.0.1:54134","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-11T01:44:03.65026Z","caller":"traceutil/trace.go:171","msg":"trace[1798233017] transaction","detail":"{read_only:false; response_revision:24285; number_of_response:1; }","duration":"321.355974ms","start":"2026-05-11T01:44:03.328874Z","end":"2026-05-11T01:44:03.65023Z","steps":["trace[1798233017] 'process raft request' (duration: 320.596718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:03.650312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.277996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:03.650363Z","caller":"traceutil/trace.go:171","msg":"trace[1357821323] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24285; }","duration":"300.368958ms","start":"2026-05-11T01:44:03.349977Z","end":"2026-05-11T01:44:03.650346Z","steps":["trace[1357821323] 'agreement among raft nodes before linearized reading' (duration: 300.271746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:03.650421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:03.349953Z","time spent":"300.45069ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:44:03.650417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:03.328854Z","time spent":"321.478007ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:03.6507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.598519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:44:03.650763Z","caller":"traceutil/trace.go:171","msg":"trace[1823935652] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:24285; }","duration":"210.68832ms","start":"2026-05-11T01:44:03.440058Z","end":"2026-05-11T01:44:03.650746Z","steps":["trace[1823935652] 'agreement among raft nodes before linearized reading' (duration: 210.590498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:03.650092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.43992ms","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-11T01:44:03.65095Z","caller":"traceutil/trace.go:171","msg":"trace[1691494614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24285; }","duration":"293.305078ms","start":"2026-05-11T01:44:03.357618Z","end":"2026-05-11T01:44:03.650923Z","steps":["trace[1691494614] 'agreement among raft nodes before linearized reading' (duration: 292.299567ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:03.758848Z","caller":"traceutil/trace.go:171","msg":"trace[488453483] transaction","detail":"{read_only:false; response_revision:24286; number_of_response:1; }","duration":"104.159426ms","start":"2026-05-11T01:44:03.65467Z","end":"2026-05-11T01:44:03.75883Z","steps":["trace[488453483] 'process raft request' (duration: 89.699008ms)","trace[488453483] 'compare' (duration: 14.203572ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:03.758979Z","caller":"traceutil/trace.go:171","msg":"trace[222947388] transaction","detail":"{read_only:false; response_revision:24287; number_of_response:1; }","duration":"104.147826ms","start":"2026-05-11T01:44:03.65482Z","end":"2026-05-11T01:44:03.758967Z","steps":["trace[222947388] 'process raft request' (duration: 103.962912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:04.056232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.458309ms","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-11T01:44:04.056333Z","caller":"traceutil/trace.go:171","msg":"trace[1574859512] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24287; }","duration":"172.595301ms","start":"2026-05-11T01:44:03.883715Z","end":"2026-05-11T01:44:04.05631Z","steps":["trace[1574859512] 'range keys from in-memory index tree' (duration: 172.197343ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:04.226858Z","caller":"traceutil/trace.go:171","msg":"trace[1282816675] linearizableReadLoop","detail":"{readStateIndex:25074; appliedIndex:25073; }","duration":"271.324141ms","start":"2026-05-11T01:44:03.955511Z","end":"2026-05-11T01:44:04.226835Z","steps":["trace[1282816675] 'read index received' (duration: 271.232719ms)","trace[1282816675] 'applied index is now lower than readState.Index' (duration: 90.492µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:04.226997Z","caller":"traceutil/trace.go:171","msg":"trace[1609950873] transaction","detail":"{read_only:false; response_revision:24288; number_of_response:1; }","duration":"287.474654ms","start":"2026-05-11T01:44:03.939487Z","end":"2026-05-11T01:44:04.226962Z","steps":["trace[1609950873] 'process raft request' (duration: 287.194088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:04.227041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.516854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:04.227087Z","caller":"traceutil/trace.go:171","msg":"trace[905172120] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24288; }","duration":"271.614817ms","start":"2026-05-11T01:44:03.955461Z","end":"2026-05-11T01:44:04.227076Z","steps":["trace[905172120] 'agreement among raft nodes before linearized reading' (duration: 271.489704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:04.418035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.729236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:04.41824Z","caller":"traceutil/trace.go:171","msg":"trace[141911962] transaction","detail":"{read_only:false; response_revision:24290; number_of_response:1; }","duration":"391.215581ms","start":"2026-05-11T01:44:04.027008Z","end":"2026-05-11T01:44:04.418224Z","steps":["trace[141911962] 'process raft request' (duration: 391.129299ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:04.418257Z","caller":"traceutil/trace.go:171","msg":"trace[1660294955] transaction","detail":"{read_only:false; response_revision:24289; number_of_response:1; }","duration":"391.710191ms","start":"2026-05-11T01:44:04.026514Z","end":"2026-05-11T01:44:04.418225Z","steps":["trace[1660294955] 'process raft request' (duration: 232.703159ms)","trace[1660294955] 'compare' (duration: 158.594293ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:04.418314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:04.02699Z","time spent":"391.291632ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:04.418498Z","caller":"traceutil/trace.go:171","msg":"trace[1273866337] transaction","detail":"{read_only:false; response_revision:24291; number_of_response:1; }","duration":"358.361731ms","start":"2026-05-11T01:44:04.060124Z","end":"2026-05-11T01:44:04.418486Z","steps":["trace[1273866337] 'process raft request' (duration: 358.065815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:04.418731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:04.060103Z","time spent":"358.425723ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:04.418464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:04.026487Z","time spent":"391.858994ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:04.422302Z","caller":"traceutil/trace.go:171","msg":"trace[1947169354] linearizableReadLoop","detail":"{readStateIndex:25079; appliedIndex:25074; }","duration":"195.356274ms","start":"2026-05-11T01:44:04.226927Z","end":"2026-05-11T01:44:04.422283Z","steps":["trace[1947169354] 'read index received' (duration: 32.231575ms)","trace[1947169354] 'applied index is now lower than readState.Index' (duration: 163.123819ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:04.422747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.593999ms","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-11T01:44:04.422805Z","caller":"traceutil/trace.go:171","msg":"trace[1608290776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24292; }","duration":"439.704971ms","start":"2026-05-11T01:44:03.983087Z","end":"2026-05-11T01:44:04.422792Z","steps":["trace[1608290776] 'agreement among raft nodes before linearized reading' (duration: 439.487896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:04.422834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:03.983064Z","time spent":"439.762943ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T01:44:04.42288Z","caller":"traceutil/trace.go:171","msg":"trace[1381214506] transaction","detail":"{read_only:false; response_revision:24292; number_of_response:1; }","duration":"212.949979ms","start":"2026-05-11T01:44:04.209888Z","end":"2026-05-11T01:44:04.422838Z","steps":["trace[1381214506] 'process raft request' (duration: 212.292104ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:04.737703Z","caller":"traceutil/trace.go:171","msg":"trace[589459283] linearizableReadLoop","detail":"{readStateIndex:25081; appliedIndex:25079; }","duration":"291.879227ms","start":"2026-05-11T01:44:04.445807Z","end":"2026-05-11T01:44:04.737687Z","steps":["trace[589459283] 'read index received' (duration: 897.918µs)","trace[589459283] 'applied index is now lower than readState.Index' (duration: 290.980229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:04.737799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.98289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:04.737778Z","caller":"traceutil/trace.go:171","msg":"trace[1854515802] transaction","detail":"{read_only:false; response_revision:24294; number_of_response:1; }","duration":"309.609135ms","start":"2026-05-11T01:44:04.428142Z","end":"2026-05-11T01:44:04.737751Z","steps":["trace[1854515802] 'process raft request' (duration: 309.419721ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:04.737825Z","caller":"traceutil/trace.go:171","msg":"trace[347094748] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24294; }","duration":"292.047041ms","start":"2026-05-11T01:44:04.445769Z","end":"2026-05-11T01:44:04.737816Z","steps":["trace[347094748] 'agreement among raft nodes before linearized reading' (duration: 291.985749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:04.737952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:04.42813Z","time spent":"309.735198ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:04.750588Z","caller":"traceutil/trace.go:171","msg":"trace[1629307801] transaction","detail":"{read_only:false; response_revision:24295; number_of_response:1; }","duration":"201.349732ms","start":"2026-05-11T01:44:04.549218Z","end":"2026-05-11T01:44:04.750568Z","steps":["trace[1629307801] 'process raft request' (duration: 201.22964ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:05.113445Z","caller":"traceutil/trace.go:171","msg":"trace[1663413709] transaction","detail":"{read_only:false; response_revision:24297; number_of_response:1; }","duration":"119.814488ms","start":"2026-05-11T01:44:04.993609Z","end":"2026-05-11T01:44:05.113424Z","steps":["trace[1663413709] 'process raft request' (duration: 119.675055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:05.371684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.918902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T01:44:05.37175Z","caller":"traceutil/trace.go:171","msg":"trace[2079947400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24297; }","duration":"129.034974ms","start":"2026-05-11T01:44:05.242701Z","end":"2026-05-11T01:44:05.371736Z","steps":["trace[2079947400] 'range keys from in-memory index tree' (duration: 128.777059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:06.534188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.806667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T01:44:06.534291Z","caller":"traceutil/trace.go:171","msg":"trace[2086914550] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24302; }","duration":"134.968881ms","start":"2026-05-11T01:44:06.399301Z","end":"2026-05-11T01:44:06.53427Z","steps":["trace[2086914550] 'count revisions from in-memory index tree' (duration: 134.633314ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:06.534479Z","caller":"traceutil/trace.go:171","msg":"trace[1537730630] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"110.127412ms","start":"2026-05-11T01:44:06.424325Z","end":"2026-05-11T01:44:06.534453Z","steps":["trace[1537730630] 'process raft request' (duration: 106.980065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:06.848561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.808542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:06.84872Z","caller":"traceutil/trace.go:171","msg":"trace[10066007] linearizableReadLoop","detail":"{readStateIndex:25092; appliedIndex:25089; }","duration":"398.086017ms","start":"2026-05-11T01:44:06.450622Z","end":"2026-05-11T01:44:06.848708Z","steps":["trace[10066007] 'read index received' (duration: 80.693816ms)","trace[10066007] 'applied index is now lower than readState.Index' (duration: 317.390701ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:06.848904Z","caller":"traceutil/trace.go:171","msg":"trace[246943812] transaction","detail":"{read_only:false; response_revision:24305; number_of_response:1; }","duration":"420.545074ms","start":"2026-05-11T01:44:06.428244Z","end":"2026-05-11T01:44:06.848789Z","steps":["trace[246943812] 'process raft request' (duration: 420.401811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:06.848952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:06.428219Z","time spent":"420.710278ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:06.848961Z","caller":"traceutil/trace.go:171","msg":"trace[1864022337] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"424.550519ms","start":"2026-05-11T01:44:06.424384Z","end":"2026-05-11T01:44:06.848934Z","steps":["trace[1864022337] 'process raft request' (duration: 261.263856ms)","trace[1864022337] 'compare' (duration: 162.467925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:06.849104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:06.424358Z","time spent":"424.689293ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:06.849173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.552537ms","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-11T01:44:06.849198Z","caller":"traceutil/trace.go:171","msg":"trace[1517261383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24305; }","duration":"398.616498ms","start":"2026-05-11T01:44:06.450574Z","end":"2026-05-11T01:44:06.849191Z","steps":["trace[1517261383] 'agreement among raft nodes before linearized reading' (duration: 398.529596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:06.849222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:06.450554Z","time spent":"398.663569ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T01:44:06.875165Z","caller":"traceutil/trace.go:171","msg":"trace[1775934459] transaction","detail":"{read_only:false; response_revision:24306; number_of_response:1; }","duration":"119.41067ms","start":"2026-05-11T01:44:06.755723Z","end":"2026-05-11T01:44:06.875133Z","steps":["trace[1775934459] 'process raft request' (duration: 119.233747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:06.891075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.117451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:44:06.891141Z","caller":"traceutil/trace.go:171","msg":"trace[1476187960] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:24308; }","duration":"156.243333ms","start":"2026-05-11T01:44:06.734884Z","end":"2026-05-11T01:44:06.891127Z","steps":["trace[1476187960] 'agreement among raft nodes before linearized reading' (duration: 156.09528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:06.891897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.239395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T01:44:06.892009Z","caller":"traceutil/trace.go:171","msg":"trace[1561385088] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24308; }","duration":"150.393679ms","start":"2026-05-11T01:44:06.741602Z","end":"2026-05-11T01:44:06.891996Z","steps":["trace[1561385088] 'agreement among raft nodes before linearized reading' (duration: 149.563952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:07.190358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.030939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:07.190494Z","caller":"traceutil/trace.go:171","msg":"trace[826135709] linearizableReadLoop","detail":"{readStateIndex:25096; appliedIndex:25095; }","duration":"183.803639ms","start":"2026-05-11T01:44:07.006665Z","end":"2026-05-11T01:44:07.190469Z","steps":["trace[826135709] 'read index received' (duration: 42.449862ms)","trace[826135709] 'applied index is now lower than readState.Index' (duration: 141.351947ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:07.190524Z","caller":"traceutil/trace.go:171","msg":"trace[957925604] transaction","detail":"{read_only:false; response_revision:24309; number_of_response:1; }","duration":"294.060924ms","start":"2026-05-11T01:44:06.896433Z","end":"2026-05-11T01:44:07.190494Z","steps":["trace[957925604] 'process raft request' (duration: 152.764608ms)","trace[957925604] 'compare' (duration: 140.892317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:07.190668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.003153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:44:07.190734Z","caller":"traceutil/trace.go:171","msg":"trace[1680619020] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:24309; }","duration":"184.118616ms","start":"2026-05-11T01:44:07.0066Z","end":"2026-05-11T01:44:07.190719Z","steps":["trace[1680619020] 'agreement among raft nodes before linearized reading' (duration: 183.966632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:07.483946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.536735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:07.484078Z","caller":"traceutil/trace.go:171","msg":"trace[571419468] transaction","detail":"{read_only:false; response_revision:24310; number_of_response:1; }","duration":"412.159515ms","start":"2026-05-11T01:44:07.07189Z","end":"2026-05-11T01:44:07.484049Z","steps":["trace[571419468] 'process raft request' (duration: 298.413167ms)","trace[571419468] 'compare' (duration: 113.33517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:07.484219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:07.071859Z","time spent":"412.281258ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:07.484089Z","caller":"traceutil/trace.go:171","msg":"trace[720225161] linearizableReadLoop","detail":"{readStateIndex:25097; appliedIndex:25096; }","duration":"293.535252ms","start":"2026-05-11T01:44:07.190531Z","end":"2026-05-11T01:44:07.484066Z","steps":["trace[720225161] 'read index received' (duration: 179.742322ms)","trace[720225161] 'applied index is now lower than readState.Index' (duration: 113.790749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:07.484267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.783693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-11T01:44:07.484283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.172169ms","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-11T01:44:07.484319Z","caller":"traceutil/trace.go:171","msg":"trace[1068678373] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24310; }","duration":"377.877215ms","start":"2026-05-11T01:44:07.106426Z","end":"2026-05-11T01:44:07.484303Z","steps":["trace[1068678373] 'agreement among raft nodes before linearized reading' (duration: 377.747893ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:07.48434Z","caller":"traceutil/trace.go:171","msg":"trace[818959189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24310; }","duration":"367.256061ms","start":"2026-05-11T01:44:07.117069Z","end":"2026-05-11T01:44:07.484325Z","steps":["trace[818959189] 'agreement among raft nodes before linearized reading' (duration: 367.015206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:07.484363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:07.106406Z","time spent":"377.947178ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":54,"response size":32,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:44:07.484375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:07.117058Z","time spent":"367.309062ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T01:44:07.929653Z","caller":"traceutil/trace.go:171","msg":"trace[167208895] transaction","detail":"{read_only:false; response_revision:24314; number_of_response:1; }","duration":"140.467457ms","start":"2026-05-11T01:44:07.789157Z","end":"2026-05-11T01:44:07.929624Z","steps":["trace[167208895] 'process raft request' (duration: 127.255697ms)","trace[167208895] 'compare' (duration: 13.058197ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:08.175859Z","caller":"traceutil/trace.go:171","msg":"trace[1881222511] linearizableReadLoop","detail":"{readStateIndex:25102; appliedIndex:25101; }","duration":"111.499621ms","start":"2026-05-11T01:44:08.064338Z","end":"2026-05-11T01:44:08.175837Z","steps":["trace[1881222511] 'read index received' (duration: 90.670769ms)","trace[1881222511] 'applied index is now lower than readState.Index' (duration: 20.827952ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:08.176186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.711956ms","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-11T01:44:08.176248Z","caller":"traceutil/trace.go:171","msg":"trace[351214944] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24315; }","duration":"111.94445ms","start":"2026-05-11T01:44:08.064292Z","end":"2026-05-11T01:44:08.176237Z","steps":["trace[351214944] 'agreement among raft nodes before linearized reading' (duration: 111.629724ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:08.176454Z","caller":"traceutil/trace.go:171","msg":"trace[127935957] transaction","detail":"{read_only:false; response_revision:24315; number_of_response:1; }","duration":"385.416016ms","start":"2026-05-11T01:44:07.791002Z","end":"2026-05-11T01:44:08.176418Z","steps":["trace[127935957] 'process raft request' (duration: 364.065503ms)","trace[127935957] 'compare' (duration: 20.526886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:08.17675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:07.790981Z","time spent":"385.646323ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:09.261647Z","caller":"traceutil/trace.go:171","msg":"trace[2122840857] linearizableReadLoop","detail":"{readStateIndex:25108; appliedIndex:25105; }","duration":"367.363283ms","start":"2026-05-11T01:44:08.894262Z","end":"2026-05-11T01:44:09.261626Z","steps":["trace[2122840857] 'read index received' (duration: 34.33135ms)","trace[2122840857] 'applied index is now lower than readState.Index' (duration: 333.031083ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:09.261765Z","caller":"traceutil/trace.go:171","msg":"trace[1381702526] transaction","detail":"{read_only:false; response_revision:24321; number_of_response:1; }","duration":"380.322679ms","start":"2026-05-11T01:44:08.881431Z","end":"2026-05-11T01:44:09.261754Z","steps":["trace[1381702526] 'process raft request' (duration: 380.059234ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:09.261747Z","caller":"traceutil/trace.go:171","msg":"trace[54373993] transaction","detail":"{read_only:false; response_revision:24320; number_of_response:1; }","duration":"406.065066ms","start":"2026-05-11T01:44:08.855633Z","end":"2026-05-11T01:44:09.261698Z","steps":["trace[54373993] 'process raft request' (duration: 342.936523ms)","trace[54373993] 'compare' (duration: 62.814517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:09.261859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:08.881412Z","time spent":"380.37825ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:09.261893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.622468ms","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-11T01:44:09.261939Z","caller":"traceutil/trace.go:171","msg":"trace[954545085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24322; }","duration":"367.671919ms","start":"2026-05-11T01:44:08.894254Z","end":"2026-05-11T01:44:09.261926Z","steps":["trace[954545085] 'agreement among raft nodes before linearized reading' (duration: 367.517206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:09.261961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:08.855606Z","time spent":"406.250441ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:09.262018Z","caller":"traceutil/trace.go:171","msg":"trace[2123364811] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"365.450352ms","start":"2026-05-11T01:44:08.896558Z","end":"2026-05-11T01:44:09.262008Z","steps":["trace[2123364811] 'process raft request' (duration: 365.032953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:09.26205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.066286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-11T01:44:09.262079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:08.896526Z","time spent":"365.516593ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:09.261982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:08.894198Z","time spent":"367.774861ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T01:44:09.262127Z","caller":"traceutil/trace.go:171","msg":"trace[1928254987] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:24322; }","duration":"351.188549ms","start":"2026-05-11T01:44:08.910922Z","end":"2026-05-11T01:44:09.26211Z","steps":["trace[1928254987] 'agreement among raft nodes before linearized reading' (duration: 350.974984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:09.262192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:08.910908Z","time spent":"351.27755ms","remote":"127.0.0.1:53944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"info","ts":"2026-05-11T01:44:13.94983Z","caller":"traceutil/trace.go:171","msg":"trace[1447331253] linearizableReadLoop","detail":"{readStateIndex:25144; appliedIndex:25143; }","duration":"145.997765ms","start":"2026-05-11T01:44:13.803809Z","end":"2026-05-11T01:44:13.949807Z","steps":["trace[1447331253] 'read index received' (duration: 145.837552ms)","trace[1447331253] 'applied index is now lower than readState.Index' (duration: 159.343µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:13.94988Z","caller":"traceutil/trace.go:171","msg":"trace[1732590612] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"222.88715ms","start":"2026-05-11T01:44:13.72696Z","end":"2026-05-11T01:44:13.949847Z","steps":["trace[1732590612] 'process raft request' (duration: 222.693526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:13.949984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.158048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:13.950031Z","caller":"traceutil/trace.go:171","msg":"trace[1958297509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24356; }","duration":"146.26072ms","start":"2026-05-11T01:44:13.803755Z","end":"2026-05-11T01:44:13.950015Z","steps":["trace[1958297509] 'agreement among raft nodes before linearized reading' (duration: 146.171849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:14.264732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.325601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:14.264886Z","caller":"traceutil/trace.go:171","msg":"trace[362435274] transaction","detail":"{read_only:false; response_revision:24357; number_of_response:1; }","duration":"454.487526ms","start":"2026-05-11T01:44:13.810365Z","end":"2026-05-11T01:44:14.264853Z","steps":["trace[362435274] 'process raft request' (duration: 316.91962ms)","trace[362435274] 'compare' (duration: 137.164638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:14.265055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:13.810348Z","time spent":"454.613389ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:14.265073Z","caller":"traceutil/trace.go:171","msg":"trace[1709775091] linearizableReadLoop","detail":"{readStateIndex:25145; appliedIndex:25144; }","duration":"315.132233ms","start":"2026-05-11T01:44:13.949899Z","end":"2026-05-11T01:44:14.265031Z","steps":["trace[1709775091] 'read index received' (duration: 177.399814ms)","trace[1709775091] 'applied index is now lower than readState.Index' (duration: 137.724859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:14.54949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.649163ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-11T01:44:14.549588Z","caller":"traceutil/trace.go:171","msg":"trace[2119522511] linearizableReadLoop","detail":"{readStateIndex:25146; appliedIndex:25145; }","duration":"284.434649ms","start":"2026-05-11T01:44:14.265141Z","end":"2026-05-11T01:44:14.549575Z","steps":["trace[2119522511] 'read index received' (duration: 71.772µs)","trace[2119522511] 'applied index is now lower than readState.Index' (duration: 284.361877ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:14.549681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.573227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:14.549703Z","caller":"traceutil/trace.go:171","msg":"trace[700064337] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:24357; }","duration":"295.630318ms","start":"2026-05-11T01:44:14.254065Z","end":"2026-05-11T01:44:14.549696Z","steps":["trace[700064337] 'agreement among raft nodes before linearized reading' (duration: 295.570367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:14.549697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.7913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:14.54987Z","caller":"traceutil/trace.go:171","msg":"trace[687076987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24357; }","duration":"745.020456ms","start":"2026-05-11T01:44:13.804826Z","end":"2026-05-11T01:44:14.549846Z","steps":["trace[687076987] 'agreement among raft nodes before linearized reading' (duration: 460.364531ms)","trace[687076987] 'range keys from in-memory index tree' (duration: 284.44923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:14.549922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:13.804807Z","time spent":"745.103237ms","remote":"127.0.0.1:53912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T01:44:14.550033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.396027ms","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-11T01:44:14.550121Z","caller":"traceutil/trace.go:171","msg":"trace[1205168283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24357; }","duration":"590.53724ms","start":"2026-05-11T01:44:13.959563Z","end":"2026-05-11T01:44:14.5501Z","steps":["trace[1205168283] 'agreement among raft nodes before linearized reading' (duration: 590.258544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:14.550171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:13.959546Z","time spent":"590.616291ms","remote":"127.0.0.1:54134","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":"warn","ts":"2026-05-11T01:44:14.550371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.961363ms","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-11T01:44:14.550459Z","caller":"traceutil/trace.go:171","msg":"trace[374271971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24357; }","duration":"350.089845ms","start":"2026-05-11T01:44:14.200351Z","end":"2026-05-11T01:44:14.550441Z","steps":["trace[374271971] 'agreement among raft nodes before linearized reading' (duration: 349.308179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:14.55054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.200329Z","time spent":"350.200148ms","remote":"127.0.0.1:54134","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":"info","ts":"2026-05-11T01:44:14.874836Z","caller":"traceutil/trace.go:171","msg":"trace[1735715994] linearizableReadLoop","detail":"{readStateIndex:25147; appliedIndex:25146; }","duration":"325.204386ms","start":"2026-05-11T01:44:14.549607Z","end":"2026-05-11T01:44:14.874811Z","steps":["trace[1735715994] 'read index received' (duration: 325.005802ms)","trace[1735715994] 'applied index is now lower than readState.Index' (duration: 197.534µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:14.874954Z","caller":"traceutil/trace.go:171","msg":"trace[2119012675] transaction","detail":"{read_only:false; response_revision:24358; number_of_response:1; }","duration":"469.541396ms","start":"2026-05-11T01:44:14.405375Z","end":"2026-05-11T01:44:14.874917Z","steps":["trace[2119012675] 'process raft request' (duration: 469.202769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:14.875042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.111212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:44:14.875116Z","caller":"traceutil/trace.go:171","msg":"trace[1354594243] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:24358; }","duration":"480.238474ms","start":"2026-05-11T01:44:14.394854Z","end":"2026-05-11T01:44:14.875092Z","steps":["trace[1354594243] 'agreement among raft nodes before linearized reading' (duration: 480.07261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:14.875114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.405359Z","time spent":"469.67969ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:14.875162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.394834Z","time spent":"480.316616ms","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":32,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:44:14.875151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.839604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:44:14.875231Z","caller":"traceutil/trace.go:171","msg":"trace[1713802121] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:24358; }","duration":"448.165001ms","start":"2026-05-11T01:44:14.427047Z","end":"2026-05-11T01:44:14.875212Z","steps":["trace[1713802121] 'agreement among raft nodes before linearized reading' (duration: 447.786183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:14.875193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.25054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:44:14.87529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.427027Z","time spent":"448.247214ms","remote":"127.0.0.1:54052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":32,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:44:14.875373Z","caller":"traceutil/trace.go:171","msg":"trace[229736260] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24358; }","duration":"587.525516ms","start":"2026-05-11T01:44:14.287803Z","end":"2026-05-11T01:44:14.875329Z","steps":["trace[229736260] 'agreement among raft nodes before linearized reading' (duration: 587.138898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:14.875465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.287781Z","time spent":"587.631667ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":30,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:44:15.245491Z","caller":"traceutil/trace.go:171","msg":"trace[1353867940] transaction","detail":"{read_only:false; response_revision:24359; number_of_response:1; }","duration":"690.983025ms","start":"2026-05-11T01:44:14.554477Z","end":"2026-05-11T01:44:15.24546Z","steps":["trace[1353867940] 'process raft request' (duration: 660.615529ms)","trace[1353867940] 'compare' (duration: 29.694792ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:15.245689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.554454Z","time spent":"691.130159ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:15.245684Z","caller":"traceutil/trace.go:171","msg":"trace[1757320460] transaction","detail":"{read_only:false; response_revision:24360; number_of_response:1; }","duration":"690.375092ms","start":"2026-05-11T01:44:14.55528Z","end":"2026-05-11T01:44:15.245655Z","steps":["trace[1757320460] 'process raft request' (duration: 690.020105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:15.245927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.555268Z","time spent":"690.561056ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:15.245985Z","caller":"traceutil/trace.go:171","msg":"trace[134689518] linearizableReadLoop","detail":"{readStateIndex:25150; appliedIndex:25147; }","duration":"371.02005ms","start":"2026-05-11T01:44:14.874906Z","end":"2026-05-11T01:44:15.245926Z","steps":["trace[134689518] 'read index received' (duration: 340.261576ms)","trace[134689518] 'applied index is now lower than readState.Index' (duration: 30.746554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:15.246077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.569634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-11T01:44:15.246006Z","caller":"traceutil/trace.go:171","msg":"trace[152879780] transaction","detail":"{read_only:false; response_revision:24362; number_of_response:1; }","duration":"299.773395ms","start":"2026-05-11T01:44:14.946174Z","end":"2026-05-11T01:44:15.245947Z","steps":["trace[152879780] 'process raft request' (duration: 299.427228ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:15.24609Z","caller":"traceutil/trace.go:171","msg":"trace[690597395] transaction","detail":"{read_only:false; response_revision:24363; number_of_response:1; }","duration":"254.712338ms","start":"2026-05-11T01:44:14.991334Z","end":"2026-05-11T01:44:15.246046Z","steps":["trace[690597395] 'process raft request' (duration: 254.314359ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:15.24612Z","caller":"traceutil/trace.go:171","msg":"trace[1952828933] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24363; }","duration":"538.640805ms","start":"2026-05-11T01:44:14.707468Z","end":"2026-05-11T01:44:15.246108Z","steps":["trace[1952828933] 'agreement among raft nodes before linearized reading' (duration: 538.495522ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:15.246339Z","caller":"traceutil/trace.go:171","msg":"trace[1379265947] transaction","detail":"{read_only:false; response_revision:24361; number_of_response:1; }","duration":"676.120139ms","start":"2026-05-11T01:44:14.570193Z","end":"2026-05-11T01:44:15.246313Z","steps":["trace[1379265947] 'process raft request' (duration: 675.248131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:15.246196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.545793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:44:15.246525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.570174Z","time spent":"676.232261ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:15.246603Z","caller":"traceutil/trace.go:171","msg":"trace[1671361858] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:24363; }","duration":"609.980662ms","start":"2026-05-11T01:44:14.636597Z","end":"2026-05-11T01:44:15.246578Z","steps":["trace[1671361858] 'agreement among raft nodes before linearized reading' (duration: 609.563133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:15.246204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.534731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:15.246671Z","caller":"traceutil/trace.go:171","msg":"trace[1670096755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24363; }","duration":"695.066362ms","start":"2026-05-11T01:44:14.551586Z","end":"2026-05-11T01:44:15.246652Z","steps":["trace[1670096755] 'agreement among raft nodes before linearized reading' (duration: 694.524591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:15.246453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.707453Z","time spent":"538.959032ms","remote":"127.0.0.1:53990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-05-11T01:44:15.246741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.55157Z","time spent":"695.152784ms","remote":"127.0.0.1:53910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T01:44:15.246874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:14.636578Z","time spent":"610.244708ms","remote":"127.0.0.1:44896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:44:20.571843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.97997ms","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-11T01:44:20.571947Z","caller":"traceutil/trace.go:171","msg":"trace[24493742] 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:24459; }","duration":"103.115883ms","start":"2026-05-11T01:44:20.468806Z","end":"2026-05-11T01:44:20.571922Z","steps":["trace[24493742] 'count revisions from in-memory index tree' (duration: 102.856956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:20.57206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.383519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:44:20.572176Z","caller":"traceutil/trace.go:171","msg":"trace[1034424612] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:24459; }","duration":"175.587463ms","start":"2026-05-11T01:44:20.396563Z","end":"2026-05-11T01:44:20.57215Z","steps":["trace[1034424612] 'count revisions from in-memory index tree' (duration: 175.265157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:21.066892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.687606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:21.067113Z","caller":"traceutil/trace.go:171","msg":"trace[1100040614] transaction","detail":"{read_only:false; response_revision:24461; number_of_response:1; }","duration":"176.079635ms","start":"2026-05-11T01:44:20.89101Z","end":"2026-05-11T01:44:21.06709Z","steps":["trace[1100040614] 'process raft request' (duration: 48.083523ms)","trace[1100040614] 'compare' (duration: 127.483121ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:21.25334Z","caller":"traceutil/trace.go:171","msg":"trace[2002374169] linearizableReadLoop","detail":"{readStateIndex:25254; appliedIndex:25253; }","duration":"147.355904ms","start":"2026-05-11T01:44:21.105963Z","end":"2026-05-11T01:44:21.253319Z","steps":["trace[2002374169] 'read index received' (duration: 76.690411ms)","trace[2002374169] 'applied index is now lower than readState.Index' (duration: 70.664383ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:21.25345Z","caller":"traceutil/trace.go:171","msg":"trace[21499466] transaction","detail":"{read_only:false; response_revision:24463; number_of_response:1; }","duration":"157.194534ms","start":"2026-05-11T01:44:21.096193Z","end":"2026-05-11T01:44:21.253387Z","steps":["trace[21499466] 'process raft request' (duration: 86.425278ms)","trace[21499466] 'compare' (duration: 70.604291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:21.253552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.578989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-05-11T01:44:21.25358Z","caller":"traceutil/trace.go:171","msg":"trace[5517662] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24463; }","duration":"147.645461ms","start":"2026-05-11T01:44:21.105928Z","end":"2026-05-11T01:44:21.253573Z","steps":["trace[5517662] 'agreement among raft nodes before linearized reading' (duration: 147.467117ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:23.180731Z","caller":"traceutil/trace.go:171","msg":"trace[943123267] linearizableReadLoop","detail":"{readStateIndex:25271; appliedIndex:25270; }","duration":"103.711175ms","start":"2026-05-11T01:44:23.077001Z","end":"2026-05-11T01:44:23.180712Z","steps":["trace[943123267] 'read index received' (duration: 103.592302ms)","trace[943123267] 'applied index is now lower than readState.Index' (duration: 117.823µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:23.180903Z","caller":"traceutil/trace.go:171","msg":"trace[57284873] transaction","detail":"{read_only:false; response_revision:24480; number_of_response:1; }","duration":"108.030658ms","start":"2026-05-11T01:44:23.072812Z","end":"2026-05-11T01:44:23.180843Z","steps":["trace[57284873] 'process raft request' (duration: 107.778282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:23.18109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.077944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57c64f1a-4c1c-46cd-adc1-be5337be40cb\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-05-11T01:44:23.18112Z","caller":"traceutil/trace.go:171","msg":"trace[996092930] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57c64f1a-4c1c-46cd-adc1-be5337be40cb; range_end:; response_count:1; response_revision:24480; }","duration":"104.161316ms","start":"2026-05-11T01:44:23.076951Z","end":"2026-05-11T01:44:23.181112Z","steps":["trace[996092930] 'agreement among raft nodes before linearized reading' (duration: 103.940511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:26.63501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.102264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18570"} {"level":"info","ts":"2026-05-11T01:44:26.635129Z","caller":"traceutil/trace.go:171","msg":"trace[62071794] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24525; }","duration":"128.260668ms","start":"2026-05-11T01:44:26.506837Z","end":"2026-05-11T01:44:26.635097Z","steps":["trace[62071794] 'range keys from in-memory index tree' (duration: 127.90033ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:47.538045Z","caller":"traceutil/trace.go:171","msg":"trace[1960941933] transaction","detail":"{read_only:false; response_revision:24702; number_of_response:1; }","duration":"122.684019ms","start":"2026-05-11T01:44:47.415335Z","end":"2026-05-11T01:44:47.538019Z","steps":["trace[1960941933] 'process raft request' (duration: 122.518535ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:47.541019Z","caller":"traceutil/trace.go:171","msg":"trace[845173234] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"110.96409ms","start":"2026-05-11T01:44:47.430017Z","end":"2026-05-11T01:44:47.540982Z","steps":["trace[845173234] 'process raft request' (duration: 110.840297ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:47.541258Z","caller":"traceutil/trace.go:171","msg":"trace[813755417] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"100.724871ms","start":"2026-05-11T01:44:47.440517Z","end":"2026-05-11T01:44:47.541242Z","steps":["trace[813755417] 'process raft request' (duration: 100.400284ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:47.541291Z","caller":"traceutil/trace.go:171","msg":"trace[1695276544] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"122.049415ms","start":"2026-05-11T01:44:47.419208Z","end":"2026-05-11T01:44:47.541257Z","steps":["trace[1695276544] 'process raft request' (duration: 121.440133ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:52.909388Z","caller":"traceutil/trace.go:171","msg":"trace[1628072025] linearizableReadLoop","detail":"{readStateIndex:25542; appliedIndex:25541; }","duration":"102.815467ms","start":"2026-05-11T01:44:52.806552Z","end":"2026-05-11T01:44:52.909368Z","steps":["trace[1628072025] 'read index received' (duration: 102.692644ms)","trace[1628072025] 'applied index is now lower than readState.Index' (duration: 121.723µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:52.909434Z","caller":"traceutil/trace.go:171","msg":"trace[1122178559] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"123.006167ms","start":"2026-05-11T01:44:52.786397Z","end":"2026-05-11T01:44:52.909403Z","steps":["trace[1122178559] 'process raft request' (duration: 122.850154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:52.909493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.92103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:52.909553Z","caller":"traceutil/trace.go:171","msg":"trace[1937301375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24745; }","duration":"103.021852ms","start":"2026-05-11T01:44:52.806519Z","end":"2026-05-11T01:44:52.909541Z","steps":["trace[1937301375] 'agreement among raft nodes before linearized reading' (duration: 102.92686ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:53.406097Z","caller":"traceutil/trace.go:171","msg":"trace[220665255] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"107.834174ms","start":"2026-05-11T01:44:53.298235Z","end":"2026-05-11T01:44:53.406069Z","steps":["trace[220665255] 'process raft request' (duration: 107.565338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.559485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.147361ms","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-11T01:44:53.559579Z","caller":"traceutil/trace.go:171","msg":"trace[1677715935] 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:24746; }","duration":"132.287345ms","start":"2026-05-11T01:44:53.427272Z","end":"2026-05-11T01:44:53.559559Z","steps":["trace[1677715935] 'range keys from in-memory index tree' (duration: 132.08596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.887944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.776641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:53.888319Z","caller":"traceutil/trace.go:171","msg":"trace[1945926195] linearizableReadLoop","detail":"{readStateIndex:25548; appliedIndex:25543; }","duration":"323.396669ms","start":"2026-05-11T01:44:53.564898Z","end":"2026-05-11T01:44:53.888295Z","steps":["trace[1945926195] 'read index received' (duration: 7.631802ms)","trace[1945926195] 'applied index is now lower than readState.Index' (duration: 315.763417ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:53.888397Z","caller":"traceutil/trace.go:171","msg":"trace[164147324] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"325.200979ms","start":"2026-05-11T01:44:53.563151Z","end":"2026-05-11T01:44:53.888352Z","steps":["trace[164147324] 'process raft request' (duration: 325.008995ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:53.888465Z","caller":"traceutil/trace.go:171","msg":"trace[1298888036] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"324.895842ms","start":"2026-05-11T01:44:53.563553Z","end":"2026-05-11T01:44:53.888449Z","steps":["trace[1298888036] 'process raft request' (duration: 324.683577ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:53.888552Z","caller":"traceutil/trace.go:171","msg":"trace[312179292] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"329.093731ms","start":"2026-05-11T01:44:53.559445Z","end":"2026-05-11T01:44:53.888539Z","steps":["trace[312179292] 'process raft request' (duration: 103.595704ms)","trace[312179292] 'compare' (duration: 224.442875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:53.888572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.563538Z","time spent":"324.979763ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:53.888633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.563124Z","time spent":"325.378622ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:53.888761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.853949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:53.888817Z","caller":"traceutil/trace.go:171","msg":"trace[1378559404] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24751; }","duration":"323.940071ms","start":"2026-05-11T01:44:53.564863Z","end":"2026-05-11T01:44:53.888803Z","steps":["trace[1378559404] 'agreement among raft nodes before linearized reading' (duration: 323.852309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.888647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.55942Z","time spent":"329.175892ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:53.888859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.564849Z","time spent":"324.000082ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-11T01:44:53.889011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.433272ms","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-11T01:44:53.889054Z","caller":"traceutil/trace.go:171","msg":"trace[1036000084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24751; }","duration":"316.512003ms","start":"2026-05-11T01:44:53.57253Z","end":"2026-05-11T01:44:53.889042Z","steps":["trace[1036000084] 'agreement among raft nodes before linearized reading' (duration: 316.38809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.889086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.572512Z","time spent":"316.565335ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T01:44:53.888343Z","caller":"traceutil/trace.go:171","msg":"trace[1038880412] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"326.542477ms","start":"2026-05-11T01:44:53.561763Z","end":"2026-05-11T01:44:53.888305Z","steps":["trace[1038880412] 'process raft request' (duration: 326.309222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.889207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.56175Z","time spent":"327.421855ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:53.889354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.693078ms","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-11T01:44:53.889388Z","caller":"traceutil/trace.go:171","msg":"trace[1628474637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24751; }","duration":"316.754519ms","start":"2026-05-11T01:44:53.572623Z","end":"2026-05-11T01:44:53.889377Z","steps":["trace[1628474637] 'agreement among raft nodes before linearized reading' (duration: 316.652227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.889416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.572619Z","time spent":"316.790811ms","remote":"127.0.0.1:54134","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":"warn","ts":"2026-05-11T01:44:53.88944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.843638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T01:44:53.889499Z","caller":"traceutil/trace.go:171","msg":"trace[2039158188] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24751; }","duration":"306.9337ms","start":"2026-05-11T01:44:53.582547Z","end":"2026-05-11T01:44:53.889481Z","steps":["trace[2039158188] 'agreement among raft nodes before linearized reading' (duration: 306.722186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.889563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.582527Z","time spent":"307.027722ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T01:44:53.889596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.968424ms","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-11T01:44:53.889655Z","caller":"traceutil/trace.go:171","msg":"trace[1294204778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24751; }","duration":"317.041565ms","start":"2026-05-11T01:44:53.572598Z","end":"2026-05-11T01:44:53.889639Z","steps":["trace[1294204778] 'agreement among raft nodes before linearized reading' (duration: 316.877042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.88966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.858003ms","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-11T01:44:53.889698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.572593Z","time spent":"317.097827ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T01:44:53.889702Z","caller":"traceutil/trace.go:171","msg":"trace[1660977595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24751; }","duration":"121.918785ms","start":"2026-05-11T01:44:53.76777Z","end":"2026-05-11T01:44:53.889689Z","steps":["trace[1660977595] 'agreement among raft nodes before linearized reading' (duration: 121.826923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:53.889763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.339788ms","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-11T01:44:53.889801Z","caller":"traceutil/trace.go:171","msg":"trace[143748591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24751; }","duration":"142.41317ms","start":"2026-05-11T01:44:53.747377Z","end":"2026-05-11T01:44:53.88979Z","steps":["trace[143748591] 'agreement among raft nodes before linearized reading' (duration: 142.304827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:54.255704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.019905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:54.256Z","caller":"traceutil/trace.go:171","msg":"trace[347152361] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"359.142891ms","start":"2026-05-11T01:44:53.896838Z","end":"2026-05-11T01:44:54.255981Z","steps":["trace[347152361] 'process raft request' (duration: 358.991657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:54.256071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.896818Z","time spent":"359.219962ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:54.256203Z","caller":"traceutil/trace.go:171","msg":"trace[1430007389] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"359.482908ms","start":"2026-05-11T01:44:53.896662Z","end":"2026-05-11T01:44:54.256145Z","steps":["trace[1430007389] 'process raft request' (duration: 150.916861ms)","trace[1430007389] 'compare' (duration: 207.606457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:54.256429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.896611Z","time spent":"359.675263ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:54.256672Z","caller":"traceutil/trace.go:171","msg":"trace[1655457151] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"358.763153ms","start":"2026-05-11T01:44:53.89789Z","end":"2026-05-11T01:44:54.256653Z","steps":["trace[1655457151] 'process raft request' (duration: 358.052087ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:54.256711Z","caller":"traceutil/trace.go:171","msg":"trace[820573394] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"353.44647ms","start":"2026-05-11T01:44:53.90325Z","end":"2026-05-11T01:44:54.256696Z","steps":["trace[820573394] 'process raft request' (duration: 353.295566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:54.256768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.89786Z","time spent":"358.863434ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:54.256784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.896905Z","time spent":"359.840676ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:54.256812Z","caller":"traceutil/trace.go:171","msg":"trace[1442281296] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"250.429398ms","start":"2026-05-11T01:44:54.006293Z","end":"2026-05-11T01:44:54.256723Z","steps":["trace[1442281296] 'process raft request' (duration: 250.377487ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:54.256822Z","caller":"traceutil/trace.go:171","msg":"trace[498369348] linearizableReadLoop","detail":"{readStateIndex:25552; appliedIndex:25548; }","duration":"359.617442ms","start":"2026-05-11T01:44:53.89717Z","end":"2026-05-11T01:44:54.256788Z","steps":["trace[498369348] 'read index received' (duration: 6.388137ms)","trace[498369348] 'applied index is now lower than readState.Index' (duration: 353.226484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:54.257033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.830286ms","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-11T01:44:54.257083Z","caller":"traceutil/trace.go:171","msg":"trace[886573123] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24758; }","duration":"359.931378ms","start":"2026-05-11T01:44:53.897138Z","end":"2026-05-11T01:44:54.257069Z","steps":["trace[886573123] 'agreement among raft nodes before linearized reading' (duration: 359.808156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:54.257116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:53.897118Z","time spent":"359.989249ms","remote":"127.0.0.1:54142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-05-11T01:44:54.485574Z","caller":"traceutil/trace.go:171","msg":"trace[37387316] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"289.716863ms","start":"2026-05-11T01:44:54.195821Z","end":"2026-05-11T01:44:54.485538Z","steps":["trace[37387316] 'process raft request' (duration: 211.692834ms)","trace[37387316] 'compare' (duration: 77.770014ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:54.485774Z","caller":"traceutil/trace.go:171","msg":"trace[1582485202] linearizableReadLoop","detail":"{readStateIndex:25556; appliedIndex:25555; }","duration":"228.838307ms","start":"2026-05-11T01:44:54.256908Z","end":"2026-05-11T01:44:54.485746Z","steps":["trace[1582485202] 'read index received' (duration: 150.602244ms)","trace[1582485202] 'applied index is now lower than readState.Index' (duration: 78.234363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:54.72476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.241381ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"warn","ts":"2026-05-11T01:44:54.725009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.389086ms","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-11T01:44:54.72506Z","caller":"traceutil/trace.go:171","msg":"trace[139530161] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24759; }","duration":"532.484398ms","start":"2026-05-11T01:44:54.192558Z","end":"2026-05-11T01:44:54.725043Z","steps":["trace[139530161] 'agreement among raft nodes before linearized reading' (duration: 293.191876ms)","trace[139530161] 'count revisions from in-memory index tree' (duration: 239.19994ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:54.725093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.13081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-11T01:44:54.725103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:54.192531Z","time spent":"532.56425ms","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":98,"response size":32,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:44:54.72512Z","caller":"traceutil/trace.go:171","msg":"trace[327393146] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24759; }","duration":"634.192002ms","start":"2026-05-11T01:44:54.090919Z","end":"2026-05-11T01:44:54.725111Z","steps":["trace[327393146] 'agreement among raft nodes before linearized reading' (duration: 394.945061ms)","trace[327393146] 'range keys from in-memory index tree' (duration: 239.127429ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:54.72514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:54.090897Z","time spent":"634.237704ms","remote":"127.0.0.1:54058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-11T01:44:54.725302Z","caller":"traceutil/trace.go:171","msg":"trace[315915302] linearizableReadLoop","detail":"{readStateIndex:25558; appliedIndex:25556; }","duration":"239.448735ms","start":"2026-05-11T01:44:54.485846Z","end":"2026-05-11T01:44:54.725294Z","steps":["trace[315915302] 'read index received' (duration: 48.741µs)","trace[315915302] 'applied index is now lower than readState.Index' (duration: 239.399174ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:54.725336Z","caller":"traceutil/trace.go:171","msg":"trace[648736101] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"294.841342ms","start":"2026-05-11T01:44:54.430489Z","end":"2026-05-11T01:44:54.72533Z","steps":["trace[648736101] 'process raft request' (duration: 294.349662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:55.023979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.755095ms","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-11T01:44:55.024072Z","caller":"traceutil/trace.go:171","msg":"trace[1483226318] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24760; }","duration":"760.881167ms","start":"2026-05-11T01:44:54.263168Z","end":"2026-05-11T01:44:55.024049Z","steps":["trace[1483226318] 'agreement among raft nodes before linearized reading' (duration: 462.188723ms)","trace[1483226318] 'range keys from in-memory index tree' (duration: 298.563682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:55.024114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:54.263152Z","time spent":"760.952058ms","remote":"127.0.0.1:54142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-05-11T01:44:55.02398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.015691ms","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":"warn","ts":"2026-05-11T01:44:55.024178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.533317ms","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-11T01:44:55.024267Z","caller":"traceutil/trace.go:171","msg":"trace[1745109170] 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:24760; }","duration":"424.285116ms","start":"2026-05-11T01:44:54.599905Z","end":"2026-05-11T01:44:55.02419Z","steps":["trace[1745109170] 'agreement among raft nodes before linearized reading' (duration: 125.470649ms)","trace[1745109170] 'count revisions from in-memory index tree' (duration: 298.546792ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:55.0243Z","caller":"traceutil/trace.go:171","msg":"trace[681271310] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24760; }","duration":"295.669941ms","start":"2026-05-11T01:44:54.728596Z","end":"2026-05-11T01:44:55.024266Z","steps":["trace[681271310] 'range keys from in-memory index tree' (duration: 295.391815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:55.024345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:54.599886Z","time spent":"424.43661ms","remote":"127.0.0.1:44864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":30,"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-11T01:44:55.024116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.14135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:55.024637Z","caller":"traceutil/trace.go:171","msg":"trace[125892691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24760; }","duration":"219.239694ms","start":"2026-05-11T01:44:54.805384Z","end":"2026-05-11T01:44:55.024624Z","steps":["trace[125892691] 'range keys from in-memory index tree' (duration: 218.027418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:55.765199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.589671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:55.765345Z","caller":"traceutil/trace.go:171","msg":"trace[2090950780] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"187.598061ms","start":"2026-05-11T01:44:55.577731Z","end":"2026-05-11T01:44:55.765329Z","steps":["trace[2090950780] 'process raft request' (duration: 54.810116ms)","trace[2090950780] 'compare' (duration: 132.285854ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:56.153398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.019428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:56.15373Z","caller":"traceutil/trace.go:171","msg":"trace[8013746] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"259.044351ms","start":"2026-05-11T01:44:55.894601Z","end":"2026-05-11T01:44:56.153645Z","steps":["trace[8013746] 'process raft request' (duration: 151.667957ms)","trace[8013746] 'compare' (duration: 106.464275ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:56.176545Z","caller":"traceutil/trace.go:171","msg":"trace[1959950552] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"281.819266ms","start":"2026-05-11T01:44:55.894641Z","end":"2026-05-11T01:44:56.17646Z","steps":["trace[1959950552] 'process raft request' (duration: 281.589181ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:56.176758Z","caller":"traceutil/trace.go:171","msg":"trace[700651898] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"280.975597ms","start":"2026-05-11T01:44:55.895756Z","end":"2026-05-11T01:44:56.176731Z","steps":["trace[700651898] 'process raft request' (duration: 280.60838ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:56.176863Z","caller":"traceutil/trace.go:171","msg":"trace[2015798120] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"281.040069ms","start":"2026-05-11T01:44:55.895766Z","end":"2026-05-11T01:44:56.176806Z","steps":["trace[2015798120] 'process raft request' (duration: 280.880185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:56.177216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.793939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T01:44:56.176933Z","caller":"traceutil/trace.go:171","msg":"trace[228952209] linearizableReadLoop","detail":"{readStateIndex:25564; appliedIndex:25560; }","duration":"269.546804ms","start":"2026-05-11T01:44:55.907372Z","end":"2026-05-11T01:44:56.176685Z","steps":["trace[228952209] 'read index received' (duration: 138.895025ms)","trace[228952209] 'applied index is now lower than readState.Index' (duration: 130.416964ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:56.177298Z","caller":"traceutil/trace.go:171","msg":"trace[744820434] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24766; }","duration":"269.941023ms","start":"2026-05-11T01:44:55.90734Z","end":"2026-05-11T01:44:56.177281Z","steps":["trace[744820434] 'agreement among raft nodes before linearized reading' (duration: 269.687477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:56.177562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.305399ms","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-11T01:44:56.177602Z","caller":"traceutil/trace.go:171","msg":"trace[801532535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24766; }","duration":"269.388831ms","start":"2026-05-11T01:44:55.908202Z","end":"2026-05-11T01:44:56.177591Z","steps":["trace[801532535] 'agreement among raft nodes before linearized reading' (duration: 269.205827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:56.446151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.601049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:56.446385Z","caller":"traceutil/trace.go:171","msg":"trace[1359733114] linearizableReadLoop","detail":"{readStateIndex:25566; appliedIndex:25564; }","duration":"186.266502ms","start":"2026-05-11T01:44:56.2601Z","end":"2026-05-11T01:44:56.446367Z","steps":["trace[1359733114] 'read index received' (duration: 46.352325ms)","trace[1359733114] 'applied index is now lower than readState.Index' (duration: 139.913307ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:56.446454Z","caller":"traceutil/trace.go:171","msg":"trace[2069497402] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"263.373863ms","start":"2026-05-11T01:44:56.183041Z","end":"2026-05-11T01:44:56.446415Z","steps":["trace[2069497402] 'process raft request' (duration: 263.20955ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:56.446469Z","caller":"traceutil/trace.go:171","msg":"trace[1779217884] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"263.624508ms","start":"2026-05-11T01:44:56.182796Z","end":"2026-05-11T01:44:56.44642Z","steps":["trace[1779217884] 'process raft request' (duration: 123.669011ms)","trace[1779217884] 'compare' (duration: 139.471707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:56.446648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.634049ms","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-11T01:44:56.446665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.552748ms","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-11T01:44:56.446686Z","caller":"traceutil/trace.go:171","msg":"trace[1591700039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24768; }","duration":"185.674189ms","start":"2026-05-11T01:44:56.261002Z","end":"2026-05-11T01:44:56.446676Z","steps":["trace[1591700039] 'agreement among raft nodes before linearized reading' (duration: 185.539527ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:56.446728Z","caller":"traceutil/trace.go:171","msg":"trace[1929237374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24768; }","duration":"186.65437ms","start":"2026-05-11T01:44:56.260051Z","end":"2026-05-11T01:44:56.446706Z","steps":["trace[1929237374] 'agreement among raft nodes before linearized reading' (duration: 186.487067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:56.446805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.602618ms","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-11T01:44:56.446873Z","caller":"traceutil/trace.go:171","msg":"trace[942446930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24768; }","duration":"185.730081ms","start":"2026-05-11T01:44:56.261127Z","end":"2026-05-11T01:44:56.446857Z","steps":["trace[942446930] 'agreement among raft nodes before linearized reading' (duration: 185.499495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:56.446844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.135148ms","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-11T01:44:56.446966Z","caller":"traceutil/trace.go:171","msg":"trace[677661392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24768; }","duration":"185.296833ms","start":"2026-05-11T01:44:56.261637Z","end":"2026-05-11T01:44:56.446934Z","steps":["trace[677661392] 'agreement among raft nodes before linearized reading' (duration: 184.927754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:56.74149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.304307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:56.74164Z","caller":"traceutil/trace.go:171","msg":"trace[1374986123] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"289.445608ms","start":"2026-05-11T01:44:56.452174Z","end":"2026-05-11T01:44:56.74162Z","steps":["trace[1374986123] 'process raft request' (duration: 146.924256ms)","trace[1374986123] 'compare' (duration: 142.182905ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:56.829732Z","caller":"traceutil/trace.go:171","msg":"trace[1573628357] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"377.520222ms","start":"2026-05-11T01:44:56.452191Z","end":"2026-05-11T01:44:56.829711Z","steps":["trace[1573628357] 'process raft request' (duration: 377.347828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:56.829892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:56.452168Z","time spent":"377.636865ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:56.830684Z","caller":"traceutil/trace.go:171","msg":"trace[1864315200] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"377.512871ms","start":"2026-05-11T01:44:56.453129Z","end":"2026-05-11T01:44:56.830642Z","steps":["trace[1864315200] 'process raft request' (duration: 376.99634ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:56.830797Z","caller":"traceutil/trace.go:171","msg":"trace[1685782688] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"101.087181ms","start":"2026-05-11T01:44:56.729689Z","end":"2026-05-11T01:44:56.830777Z","steps":["trace[1685782688] 'process raft request' (duration: 100.60501ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:56.830689Z","caller":"traceutil/trace.go:171","msg":"trace[1340959870] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"378.447381ms","start":"2026-05-11T01:44:56.452192Z","end":"2026-05-11T01:44:56.830639Z","steps":["trace[1340959870] 'process raft request' (duration: 377.486831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:56.831038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:56.453119Z","time spent":"377.720296ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:56.831058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:56.45217Z","time spent":"378.743099ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:00.539556Z","caller":"traceutil/trace.go:171","msg":"trace[170468437] linearizableReadLoop","detail":"{readStateIndex:25590; appliedIndex:25589; }","duration":"418.754608ms","start":"2026-05-11T01:45:00.120786Z","end":"2026-05-11T01:45:00.53954Z","steps":["trace[170468437] 'read index received' (duration: 418.728567ms)","trace[170468437] 'applied index is now lower than readState.Index' (duration: 25.491µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:00.539633Z","caller":"traceutil/trace.go:171","msg":"trace[1390475952] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"433.215676ms","start":"2026-05-11T01:45:00.106394Z","end":"2026-05-11T01:45:00.53961Z","steps":["trace[1390475952] 'process raft request' (duration: 433.052563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.539713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.917443ms","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-11T01:45:00.539733Z","caller":"traceutil/trace.go:171","msg":"trace[1711034850] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24791; }","duration":"418.987744ms","start":"2026-05-11T01:45:00.120739Z","end":"2026-05-11T01:45:00.539726Z","steps":["trace[1711034850] 'agreement among raft nodes before linearized reading' (duration: 418.86959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.539737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.106364Z","time spent":"433.319819ms","remote":"127.0.0.1:54112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5417,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T01:45:00.539751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.120717Z","time spent":"419.029935ms","remote":"127.0.0.1:54058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-11T01:45:00.720655Z","caller":"traceutil/trace.go:171","msg":"trace[1309641008] transaction","detail":"{read_only:false; response_revision:24793; number_of_response:1; }","duration":"533.881607ms","start":"2026-05-11T01:45:00.186747Z","end":"2026-05-11T01:45:00.720629Z","steps":["trace[1309641008] 'process raft request' (duration: 533.796466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.720801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.186737Z","time spent":"533.989379ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:00.720964Z","caller":"traceutil/trace.go:171","msg":"trace[1552207553] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"532.649421ms","start":"2026-05-11T01:45:00.188291Z","end":"2026-05-11T01:45:00.72094Z","steps":["trace[1552207553] 'process raft request' (duration: 532.297974ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.721003Z","caller":"traceutil/trace.go:171","msg":"trace[1004858427] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"548.726704ms","start":"2026-05-11T01:45:00.172263Z","end":"2026-05-11T01:45:00.72099Z","steps":["trace[1004858427] 'process raft request' (duration: 514.862424ms)","trace[1004858427] 'compare' (duration: 33.280188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:00.721061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.172243Z","time spent":"548.787295ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:00.721108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.188269Z","time spent":"532.756724ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:00.721366Z","caller":"traceutil/trace.go:171","msg":"trace[1893442128] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"531.544768ms","start":"2026-05-11T01:45:00.18981Z","end":"2026-05-11T01:45:00.721355Z","steps":["trace[1893442128] 'process raft request' (duration: 531.041847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.721421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.189796Z","time spent":"531.594938ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:00.7216Z","caller":"traceutil/trace.go:171","msg":"trace[1001515795] linearizableReadLoop","detail":"{readStateIndex:25595; appliedIndex:25590; }","duration":"181.993222ms","start":"2026-05-11T01:45:00.539597Z","end":"2026-05-11T01:45:00.72159Z","steps":["trace[1001515795] 'read index received' (duration: 147.540709ms)","trace[1001515795] 'applied index is now lower than readState.Index' (duration: 34.451663ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:00.721705Z","caller":"traceutil/trace.go:171","msg":"trace[1688908153] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"308.191087ms","start":"2026-05-11T01:45:00.4135Z","end":"2026-05-11T01:45:00.721691Z","steps":["trace[1688908153] 'process raft request' (duration: 307.813669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.721766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.413483Z","time spent":"308.249188ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:00.722063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.315408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T01:45:00.7221Z","caller":"traceutil/trace.go:171","msg":"trace[1297449762] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24796; }","duration":"260.362579ms","start":"2026-05-11T01:45:00.461726Z","end":"2026-05-11T01:45:00.722088Z","steps":["trace[1297449762] 'agreement among raft nodes before linearized reading' (duration: 260.229086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.722291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.225212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:45:00.722339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.379375ms","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-11T01:45:00.72235Z","caller":"traceutil/trace.go:171","msg":"trace[1931073464] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24796; }","duration":"327.334864ms","start":"2026-05-11T01:45:00.394997Z","end":"2026-05-11T01:45:00.722332Z","steps":["trace[1931073464] 'agreement among raft nodes before linearized reading' (duration: 327.219802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.72234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.053633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:00.722428Z","caller":"traceutil/trace.go:171","msg":"trace[1859817137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24796; }","duration":"264.465077ms","start":"2026-05-11T01:45:00.457918Z","end":"2026-05-11T01:45:00.722383Z","steps":["trace[1859817137] 'agreement among raft nodes before linearized reading' (duration: 264.289853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.722431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.394976Z","time spent":"327.439036ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":30,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:45:00.722486Z","caller":"traceutil/trace.go:171","msg":"trace[1237310680] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24796; }","duration":"409.294338ms","start":"2026-05-11T01:45:00.313164Z","end":"2026-05-11T01:45:00.722458Z","steps":["trace[1237310680] 'agreement among raft nodes before linearized reading' (duration: 408.980011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.722552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.313142Z","time spent":"409.396739ms","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:45:00.798534Z","caller":"traceutil/trace.go:171","msg":"trace[838250827] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"254.974554ms","start":"2026-05-11T01:45:00.543511Z","end":"2026-05-11T01:45:00.798486Z","steps":["trace[838250827] 'process raft request' (duration: 254.705208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.798738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.080954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:00.798832Z","caller":"traceutil/trace.go:171","msg":"trace[145583897] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:24798; }","duration":"253.203626ms","start":"2026-05-11T01:45:00.545603Z","end":"2026-05-11T01:45:00.798807Z","steps":["trace[145583897] 'agreement among raft nodes before linearized reading' (duration: 253.046562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.798804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.255783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:00.79886Z","caller":"traceutil/trace.go:171","msg":"trace[1039883157] transaction","detail":"{read_only:false; response_revision:24798; number_of_response:1; }","duration":"250.151041ms","start":"2026-05-11T01:45:00.548668Z","end":"2026-05-11T01:45:00.798819Z","steps":["trace[1039883157] 'process raft request' (duration: 249.672311ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.798905Z","caller":"traceutil/trace.go:171","msg":"trace[58173998] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24798; }","duration":"211.425607ms","start":"2026-05-11T01:45:00.587458Z","end":"2026-05-11T01:45:00.798884Z","steps":["trace[58173998] 'agreement among raft nodes before linearized reading' (duration: 211.232633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.799042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.318111ms","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-11T01:45:00.799113Z","caller":"traceutil/trace.go:171","msg":"trace[1176238769] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24798; }","duration":"255.427784ms","start":"2026-05-11T01:45:00.543669Z","end":"2026-05-11T01:45:00.799097Z","steps":["trace[1176238769] 'agreement among raft nodes before linearized reading' (duration: 255.212409ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:01.034989Z","caller":"traceutil/trace.go:171","msg":"trace[300731089] linearizableReadLoop","detail":"{readStateIndex:25599; appliedIndex:25597; }","duration":"236.327967ms","start":"2026-05-11T01:45:00.79863Z","end":"2026-05-11T01:45:01.034958Z","steps":["trace[300731089] 'read index received' (duration: 236.124573ms)","trace[300731089] 'applied index is now lower than readState.Index' (duration: 201.734µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:01.035033Z","caller":"traceutil/trace.go:171","msg":"trace[101801251] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"304.285403ms","start":"2026-05-11T01:45:00.730698Z","end":"2026-05-11T01:45:01.034983Z","steps":["trace[101801251] 'process raft request' (duration: 303.993587ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:01.035068Z","caller":"traceutil/trace.go:171","msg":"trace[1120979836] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"304.199891ms","start":"2026-05-11T01:45:00.730841Z","end":"2026-05-11T01:45:01.035041Z","steps":["trace[1120979836] 'process raft request' (duration: 304.043178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.035205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.975298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:45:01.035227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.921986ms","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-11T01:45:01.035253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.730675Z","time spent":"304.455916ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:01.035271Z","caller":"traceutil/trace.go:171","msg":"trace[1389501367] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24800; }","duration":"313.096571ms","start":"2026-05-11T01:45:00.722158Z","end":"2026-05-11T01:45:01.035255Z","steps":["trace[1389501367] 'agreement among raft nodes before linearized reading' (duration: 312.974518ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:01.035294Z","caller":"traceutil/trace.go:171","msg":"trace[475852983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24800; }","duration":"281.033398ms","start":"2026-05-11T01:45:00.754244Z","end":"2026-05-11T01:45:01.035277Z","steps":["trace[475852983] 'agreement among raft nodes before linearized reading' (duration: 280.790763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.035276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.730832Z","time spent":"304.314083ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:01.035327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.722139Z","time spent":"313.173782ms","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:45:01.231743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.332019ms","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-11T01:45:01.231853Z","caller":"traceutil/trace.go:171","msg":"trace[561132301] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24800; }","duration":"126.464291ms","start":"2026-05-11T01:45:01.105373Z","end":"2026-05-11T01:45:01.231837Z","steps":["trace[561132301] 'range keys from in-memory index tree' (duration: 126.306208ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:01.232261Z","caller":"traceutil/trace.go:171","msg":"trace[853739810] linearizableReadLoop","detail":"{readStateIndex:25601; appliedIndex:25599; }","duration":"197.138844ms","start":"2026-05-11T01:45:01.035074Z","end":"2026-05-11T01:45:01.232213Z","steps":["trace[853739810] 'read index received' (duration: 196.802637ms)","trace[853739810] 'applied index is now lower than readState.Index' (duration: 334.687µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:01.232424Z","caller":"traceutil/trace.go:171","msg":"trace[1661188609] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"430.419357ms","start":"2026-05-11T01:45:00.801973Z","end":"2026-05-11T01:45:01.232393Z","steps":["trace[1661188609] 'process raft request' (duration: 429.705542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.232438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.498595ms","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-11T01:45:01.232552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.801945Z","time spent":"430.550899ms","remote":"127.0.0.1:54058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5614,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T01:45:01.232552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.235016ms","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-11T01:45:01.232588Z","caller":"traceutil/trace.go:171","msg":"trace[2088534269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24802; }","duration":"385.329758ms","start":"2026-05-11T01:45:00.847249Z","end":"2026-05-11T01:45:01.232579Z","steps":["trace[2088534269] 'agreement among raft nodes before linearized reading' (duration: 385.179305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.232581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.214553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18570"} {"level":"info","ts":"2026-05-11T01:45:01.232284Z","caller":"traceutil/trace.go:171","msg":"trace[42658477] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"390.169991ms","start":"2026-05-11T01:45:00.842088Z","end":"2026-05-11T01:45:01.232258Z","steps":["trace[42658477] 'process raft request' (duration: 390.045299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.232622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.847224Z","time spent":"385.392629ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T01:45:01.232651Z","caller":"traceutil/trace.go:171","msg":"trace[1955057401] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24802; }","duration":"431.313755ms","start":"2026-05-11T01:45:00.801323Z","end":"2026-05-11T01:45:01.232637Z","steps":["trace[1955057401] 'agreement among raft nodes before linearized reading' (duration: 431.140472ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:01.232597Z","caller":"traceutil/trace.go:171","msg":"trace[433983429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24802; }","duration":"388.71637ms","start":"2026-05-11T01:45:00.843854Z","end":"2026-05-11T01:45:01.232571Z","steps":["trace[433983429] 'agreement among raft nodes before linearized reading' (duration: 388.404783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.232691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.801313Z","time spent":"431.370437ms","remote":"127.0.0.1:54052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18594,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-11T01:45:01.232716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.842063Z","time spent":"390.591781ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:01.232819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.843818Z","time spent":"388.900774ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T01:45:01.233145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.271503ms","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-11T01:45:01.233193Z","caller":"traceutil/trace.go:171","msg":"trace[2117166086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24802; }","duration":"390.318744ms","start":"2026-05-11T01:45:00.842862Z","end":"2026-05-11T01:45:01.233181Z","steps":["trace[2117166086] 'agreement among raft nodes before linearized reading' (duration: 389.498246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.233225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.8428Z","time spent":"390.417985ms","remote":"127.0.0.1:54134","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-11T01:45:01.497495Z","caller":"traceutil/trace.go:171","msg":"trace[1076102545] linearizableReadLoop","detail":"{readStateIndex:25602; appliedIndex:25601; }","duration":"265.13659ms","start":"2026-05-11T01:45:01.232332Z","end":"2026-05-11T01:45:01.497468Z","steps":["trace[1076102545] 'read index received' (duration: 212.676944ms)","trace[1076102545] 'applied index is now lower than readState.Index' (duration: 52.458346ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:01.497592Z","caller":"traceutil/trace.go:171","msg":"trace[530937856] transaction","detail":"{read_only:false; response_revision:24803; number_of_response:1; }","duration":"456.680405ms","start":"2026-05-11T01:45:01.04087Z","end":"2026-05-11T01:45:01.497551Z","steps":["trace[530937856] 'process raft request' (duration: 404.045036ms)","trace[530937856] 'compare' (duration: 52.410685ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:01.497705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.45307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:01.497772Z","caller":"traceutil/trace.go:171","msg":"trace[1113301225] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:24803; }","duration":"291.571154ms","start":"2026-05-11T01:45:01.206185Z","end":"2026-05-11T01:45:01.497756Z","steps":["trace[1113301225] 'agreement among raft nodes before linearized reading' (duration: 291.44235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.497798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.040842Z","time spent":"456.860229ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:01.743898Z","caller":"traceutil/trace.go:171","msg":"trace[1627257284] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"503.699295ms","start":"2026-05-11T01:45:01.240152Z","end":"2026-05-11T01:45:01.743851Z","steps":["trace[1627257284] 'process raft request' (duration: 503.670895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.744031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.240132Z","time spent":"503.823257ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:01.744285Z","caller":"traceutil/trace.go:171","msg":"trace[2058771404] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"507.589579ms","start":"2026-05-11T01:45:01.236689Z","end":"2026-05-11T01:45:01.744278Z","steps":["trace[2058771404] 'process raft request' (duration: 506.861943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.744333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.236671Z","time spent":"507.64357ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:01.744844Z","caller":"traceutil/trace.go:171","msg":"trace[577333353] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"507.933016ms","start":"2026-05-11T01:45:01.236904Z","end":"2026-05-11T01:45:01.744837Z","steps":["trace[577333353] 'process raft request' (duration: 506.776921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.744881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.236888Z","time spent":"507.975027ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:01.744996Z","caller":"traceutil/trace.go:171","msg":"trace[82201139] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"507.970017ms","start":"2026-05-11T01:45:01.237021Z","end":"2026-05-11T01:45:01.744991Z","steps":["trace[82201139] 'process raft request' (duration: 506.686039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.745024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.237012Z","time spent":"508.000288ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T01:45:01.745167Z","caller":"traceutil/trace.go:171","msg":"trace[1234654707] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"507.889405ms","start":"2026-05-11T01:45:01.237269Z","end":"2026-05-11T01:45:01.745159Z","steps":["trace[1234654707] 'process raft request' (duration: 506.481834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.745193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.237262Z","time spent":"507.916225ms","remote":"127.0.0.1:54058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5660,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:01.745321Z","caller":"traceutil/trace.go:171","msg":"trace[544924811] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"505.195467ms","start":"2026-05-11T01:45:01.240118Z","end":"2026-05-11T01:45:01.745314Z","steps":["trace[544924811] 'process raft request' (duration: 503.672614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.745347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.240109Z","time spent":"505.223287ms","remote":"127.0.0.1:54112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:01.745851Z","caller":"traceutil/trace.go:171","msg":"trace[2094701802] linearizableReadLoop","detail":"{readStateIndex:25608; appliedIndex:25608; }","duration":"248.251862ms","start":"2026-05-11T01:45:01.49759Z","end":"2026-05-11T01:45:01.745842Z","steps":["trace[2094701802] 'read index received' (duration: 248.247472ms)","trace[2094701802] 'applied index is now lower than readState.Index' (duration: 3.76µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:01.745974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.532214ms","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-11T01:45:01.745995Z","caller":"traceutil/trace.go:171","msg":"trace[1510990218] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24809; }","duration":"433.580664ms","start":"2026-05-11T01:45:01.312409Z","end":"2026-05-11T01:45:01.745989Z","steps":["trace[1510990218] 'agreement among raft nodes before linearized reading' (duration: 433.537783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.746016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.312393Z","time spent":"433.617714ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-11T01:45:01.938832Z","caller":"traceutil/trace.go:171","msg":"trace[1008709414] linearizableReadLoop","detail":"{readStateIndex:25609; appliedIndex:25608; }","duration":"192.929534ms","start":"2026-05-11T01:45:01.745874Z","end":"2026-05-11T01:45:01.938803Z","steps":["trace[1008709414] 'read index received' (duration: 125.374937ms)","trace[1008709414] 'applied index is now lower than readState.Index' (duration: 67.552957ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:01.939057Z","caller":"traceutil/trace.go:171","msg":"trace[149279649] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"363.552404ms","start":"2026-05-11T01:45:01.575451Z","end":"2026-05-11T01:45:01.939004Z","steps":["trace[149279649] 'process raft request' (duration: 295.695561ms)","trace[149279649] 'compare' (duration: 67.423024ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:01.939236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.180051ms","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-11T01:45:01.939289Z","caller":"traceutil/trace.go:171","msg":"trace[475817655] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:24810; }","duration":"265.258673ms","start":"2026-05-11T01:45:01.674019Z","end":"2026-05-11T01:45:01.939278Z","steps":["trace[475817655] 'agreement among raft nodes before linearized reading' (duration: 265.146181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:01.939284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.575428Z","time spent":"363.767089ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:02.248641Z","caller":"traceutil/trace.go:171","msg":"trace[2020040876] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"496.481513ms","start":"2026-05-11T01:45:01.752114Z","end":"2026-05-11T01:45:02.248596Z","steps":["trace[2020040876] 'process raft request' (duration: 464.504432ms)","trace[2020040876] 'compare' (duration: 31.424549ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:02.248716Z","caller":"traceutil/trace.go:171","msg":"trace[1850254937] linearizableReadLoop","detail":"{readStateIndex:25611; appliedIndex:25609; }","duration":"309.803602ms","start":"2026-05-11T01:45:01.938897Z","end":"2026-05-11T01:45:02.248701Z","steps":["trace[1850254937] 'read index received' (duration: 277.737749ms)","trace[1850254937] 'applied index is now lower than readState.Index' (duration: 32.065413ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:02.248733Z","caller":"traceutil/trace.go:171","msg":"trace[834421439] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"397.046837ms","start":"2026-05-11T01:45:01.851646Z","end":"2026-05-11T01:45:02.248693Z","steps":["trace[834421439] 'process raft request' (duration: 396.839652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:02.248857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.752095Z","time spent":"496.668967ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T01:45:02.248888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.070827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:02.249278Z","caller":"traceutil/trace.go:171","msg":"trace[1046749650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24813; }","duration":"444.542858ms","start":"2026-05-11T01:45:01.804728Z","end":"2026-05-11T01:45:02.249271Z","steps":["trace[1046749650] 'agreement among raft nodes before linearized reading' (duration: 444.072058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:02.249284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.651433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-05-11T01:45:02.249304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.804694Z","time spent":"444.605259ms","remote":"127.0.0.1:53912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T01:45:02.249322Z","caller":"traceutil/trace.go:171","msg":"trace[1547923587] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:24813; }","duration":"290.749895ms","start":"2026-05-11T01:45:01.958563Z","end":"2026-05-11T01:45:02.249313Z","steps":["trace[1547923587] 'agreement among raft nodes before linearized reading' (duration: 290.562131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:02.249069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.851619Z","time spent":"397.244701ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:02.249187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.111328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:02.249466Z","caller":"traceutil/trace.go:171","msg":"trace[679245940] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24813; }","duration":"499.424754ms","start":"2026-05-11T01:45:01.750034Z","end":"2026-05-11T01:45:02.249459Z","steps":["trace[679245940] 'agreement among raft nodes before linearized reading' (duration: 499.128728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:02.249483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.750015Z","time spent":"499.463986ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-11T01:45:02.24919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.6303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29641065-kv7f2\" ","response":"range_response_count:1 size:5678"} {"level":"info","ts":"2026-05-11T01:45:02.249586Z","caller":"traceutil/trace.go:171","msg":"trace[1504101176] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29641065-kv7f2; range_end:; response_count:1; response_revision:24813; }","duration":"484.044028ms","start":"2026-05-11T01:45:01.76553Z","end":"2026-05-11T01:45:02.249574Z","steps":["trace[1504101176] 'agreement among raft nodes before linearized reading' (duration: 483.579698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:02.249609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:01.76552Z","time spent":"484.083289ms","remote":"127.0.0.1:54058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5702,"request content":"key:\"/registry/pods/openstack/heat-engine-cleaner-29641065-kv7f2\" "} {"level":"info","ts":"2026-05-11T01:45:02.63973Z","caller":"traceutil/trace.go:171","msg":"trace[246014471] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25612; }","duration":"368.371746ms","start":"2026-05-11T01:45:02.271333Z","end":"2026-05-11T01:45:02.639705Z","steps":["trace[246014471] 'read index received' (duration: 343.864254ms)","trace[246014471] 'applied index is now lower than readState.Index' (duration: 24.506452ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:02.639944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.637712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-11T01:45:02.63998Z","caller":"traceutil/trace.go:171","msg":"trace[1175874401] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24814; }","duration":"368.691883ms","start":"2026-05-11T01:45:02.271277Z","end":"2026-05-11T01:45:02.639969Z","steps":["trace[1175874401] 'agreement among raft nodes before linearized reading' (duration: 368.515839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:02.640013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:02.271211Z","time spent":"368.794356ms","remote":"127.0.0.1:53150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5708,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-11T01:45:02.640331Z","caller":"traceutil/trace.go:171","msg":"trace[373437786] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"379.477003ms","start":"2026-05-11T01:45:02.260839Z","end":"2026-05-11T01:45:02.640316Z","steps":["trace[373437786] 'process raft request' (duration: 354.331078ms)","trace[373437786] 'compare' (duration: 24.150694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:02.640449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:02.260807Z","time spent":"379.552924ms","remote":"127.0.0.1:54058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6969,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:02.872597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.895886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:02.873Z","caller":"traceutil/trace.go:171","msg":"trace[449859544] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"145.628939ms","start":"2026-05-11T01:45:02.727347Z","end":"2026-05-11T01:45:02.872976Z","steps":["trace[449859544] 'process raft request' (duration: 145.369263ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:02.873034Z","caller":"traceutil/trace.go:171","msg":"trace[1605112523] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"144.647276ms","start":"2026-05-11T01:45:02.728365Z","end":"2026-05-11T01:45:02.873013Z","steps":["trace[1605112523] 'process raft request' (duration: 144.474413ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:02.873032Z","caller":"traceutil/trace.go:171","msg":"trace[2015056788] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"145.615468ms","start":"2026-05-11T01:45:02.727385Z","end":"2026-05-11T01:45:02.873Z","steps":["trace[2015056788] 'process raft request' (duration: 145.419454ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:02.873195Z","caller":"traceutil/trace.go:171","msg":"trace[1983549125] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"146.778652ms","start":"2026-05-11T01:45:02.726409Z","end":"2026-05-11T01:45:02.873188Z","steps":["trace[1983549125] 'process raft request' (duration: 14.170611ms)","trace[1983549125] 'compare' (duration: 131.705882ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:03.86005Z","caller":"traceutil/trace.go:171","msg":"trace[1538504665] linearizableReadLoop","detail":"{readStateIndex:25624; appliedIndex:25623; }","duration":"188.970341ms","start":"2026-05-11T01:45:03.671056Z","end":"2026-05-11T01:45:03.860027Z","steps":["trace[1538504665] 'read index received' (duration: 188.810587ms)","trace[1538504665] 'applied index is now lower than readState.Index' (duration: 158.804µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:03.860196Z","caller":"traceutil/trace.go:171","msg":"trace[1410059920] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"205.107264ms","start":"2026-05-11T01:45:03.655053Z","end":"2026-05-11T01:45:03.86016Z","steps":["trace[1410059920] 'process raft request' (duration: 204.822388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.860317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.230996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-11T01:45:03.86039Z","caller":"traceutil/trace.go:171","msg":"trace[1456080122] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24825; }","duration":"189.355929ms","start":"2026-05-11T01:45:03.671019Z","end":"2026-05-11T01:45:03.860375Z","steps":["trace[1456080122] 'agreement among raft nodes before linearized reading' (duration: 189.141185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.860402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.186347ms","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-11T01:45:03.860445Z","caller":"traceutil/trace.go:171","msg":"trace[1088109687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24825; }","duration":"112.303739ms","start":"2026-05-11T01:45:03.748129Z","end":"2026-05-11T01:45:03.860433Z","steps":["trace[1088109687] 'agreement among raft nodes before linearized reading' (duration: 112.075225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.860451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.653572ms","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-11T01:45:03.860531Z","caller":"traceutil/trace.go:171","msg":"trace[1869732920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24825; }","duration":"109.772205ms","start":"2026-05-11T01:45:03.750741Z","end":"2026-05-11T01:45:03.860513Z","steps":["trace[1869732920] 'agreement among raft nodes before linearized reading' (duration: 109.53386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.861196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.753058ms","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-11T01:45:03.861288Z","caller":"traceutil/trace.go:171","msg":"trace[2064686629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24825; }","duration":"111.87287ms","start":"2026-05-11T01:45:03.749397Z","end":"2026-05-11T01:45:03.86127Z","steps":["trace[2064686629] 'agreement among raft nodes before linearized reading' (duration: 111.234557ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.74753Z","caller":"traceutil/trace.go:171","msg":"trace[1958372007] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"194.369535ms","start":"2026-05-11T01:45:04.553137Z","end":"2026-05-11T01:45:04.747506Z","steps":["trace[1958372007] 'process raft request' (duration: 117.698994ms)","trace[1958372007] 'compare' (duration: 76.534429ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.98198Z","caller":"traceutil/trace.go:171","msg":"trace[1017057664] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"102.025099ms","start":"2026-05-11T01:45:04.879938Z","end":"2026-05-11T01:45:04.981964Z","steps":["trace[1017057664] 'process raft request' (duration: 101.989699ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.982103Z","caller":"traceutil/trace.go:171","msg":"trace[1576088140] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"221.933741ms","start":"2026-05-11T01:45:04.760161Z","end":"2026-05-11T01:45:04.982095Z","steps":["trace[1576088140] 'process raft request' (duration: 131.967617ms)","trace[1576088140] 'compare' (duration: 89.616817ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.982162Z","caller":"traceutil/trace.go:171","msg":"trace[867385828] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"103.992002ms","start":"2026-05-11T01:45:04.878145Z","end":"2026-05-11T01:45:04.982137Z","steps":["trace[867385828] 'process raft request' (duration: 103.734757ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.982194Z","caller":"traceutil/trace.go:171","msg":"trace[913617614] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"104.211757ms","start":"2026-05-11T01:45:04.877958Z","end":"2026-05-11T01:45:04.982169Z","steps":["trace[913617614] 'process raft request' (duration: 103.89699ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.982203Z","caller":"traceutil/trace.go:171","msg":"trace[1206801883] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"103.800838ms","start":"2026-05-11T01:45:04.878375Z","end":"2026-05-11T01:45:04.982176Z","steps":["trace[1206801883] 'process raft request' (duration: 103.527072ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:25.598038Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069af.wal"} {"level":"info","ts":"2026-05-11T01:47:46.053739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23520} {"level":"info","ts":"2026-05-11T01:47:46.186568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23520,"took":"129.308411ms","hash":1170645382,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":37130240,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-11T01:47:46.186634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1170645382,"revision":23520,"compact-revision":20368} {"level":"info","ts":"2026-05-11T01:52:46.060906Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26452} {"level":"info","ts":"2026-05-11T01:52:46.206698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26452,"took":"141.762867ms","hash":3871823095,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":31993856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T01:52:46.206788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3871823095,"revision":26452,"compact-revision":23520} {"level":"info","ts":"2026-05-11T01:53:10.349034Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:53:10.361701Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T01:53:10.361811Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T01:57:46.067954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28910} {"level":"info","ts":"2026-05-11T01:57:46.203387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28910,"took":"129.910876ms","hash":4115404584,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":29851648,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T01:57:46.203441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4115404584,"revision":28910,"compact-revision":26452} {"level":"info","ts":"2026-05-11T02:02:46.077464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31133} {"level":"info","ts":"2026-05-11T02:02:46.18919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31133,"took":"108.293824ms","hash":1834065401,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":32473088,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T02:02:46.189266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1834065401,"revision":31133,"compact-revision":28910} {"level":"info","ts":"2026-05-11T02:07:46.089671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33527} {"level":"info","ts":"2026-05-11T02:07:46.246149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33527,"took":"152.117147ms","hash":3618936644,"current-db-size-bytes":60125184,"current-db-size":"60 MB","current-db-size-in-use-bytes":32309248,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T02:07:46.246231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3618936644,"revision":33527,"compact-revision":31133} {"level":"info","ts":"2026-05-11T02:12:46.095599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35788} {"level":"info","ts":"2026-05-11T02:12:46.239371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35788,"took":"140.00296ms","hash":2926045681,"current-db-size-bytes":61435904,"current-db-size":"61 MB","current-db-size-in-use-bytes":31490048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T02:12:46.239443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2926045681,"revision":35788,"compact-revision":33527} {"level":"info","ts":"2026-05-11T02:14:27.619761Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T02:14:27.623564Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T02:14:27.623669Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}