{"level":"warn","ts":"2026-06-02T06:55:22.953312Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:55:22.953417Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-06-02T06:55:22.953491Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:55:22.953503Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-02T06:55:22.95352Z","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-06-02T06:55:22.95411Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-06-02T06:55:22.954179Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-06-02T06:55:22.957209Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.780349ms"} {"level":"info","ts":"2026-06-02T06:55:22.963031Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-06-02T06:55:22.96311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T06:55:22.963136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-06-02T06:55:22.963149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T06:55:22.963164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-06-02T06:55:22.963213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-06-02T06:55:22.968647Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T06:55:22.970689Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T06:55:22.97229Z","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-06-02T06:55:22.975969Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T06:55:22.976207Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T06:55:22.976345Z","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-06-02T06:55:22.976475Z","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-06-02T06:55:22.976537Z","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-06-02T06:55:22.978779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-06-02T06:55:22.97893Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-02T06:55:22.983748Z","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-06-02T06:55:22.983811Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-02T06:55:22.983852Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-02T06:55:22.984136Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T06:55:22.984191Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T06:55:23.364986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T06:55:23.365034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T06:55:23.365063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-06-02T06:55:23.365077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-06-02T06:55:23.365084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-02T06:55:23.365098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-06-02T06:55:23.365107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-02T06:55:23.366125Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:55:23.366371Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T06:55:23.366388Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:55:23.366424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:55:23.366772Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T06:55:23.36688Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T06:55:23.366994Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:55:23.367061Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:55:23.367078Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:55:23.368633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T06:55:23.370093Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-06-02T06:55:27.776027Z","caller":"traceutil/trace.go:171","msg":"trace[661062850] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"165.045262ms","start":"2026-06-02T06:55:27.610954Z","end":"2026-06-02T06:55:27.775999Z","steps":["trace[661062850] 'process raft request' (duration: 164.877889ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:58.860694Z","caller":"traceutil/trace.go:171","msg":"trace[208120383] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"110.564153ms","start":"2026-06-02T06:55:58.750105Z","end":"2026-06-02T06:55:58.860669Z","steps":["trace[208120383] 'process raft request' (duration: 110.40825ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:58.861887Z","caller":"traceutil/trace.go:171","msg":"trace[872914678] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"108.240833ms","start":"2026-06-02T06:55:58.753614Z","end":"2026-06-02T06:55:58.861855Z","steps":["trace[872914678] 'process raft request' (duration: 107.968957ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:08.128618Z","caller":"traceutil/trace.go:171","msg":"trace[767962423] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"172.199521ms","start":"2026-06-02T06:56:07.956387Z","end":"2026-06-02T06:56:08.128586Z","steps":["trace[767962423] 'process raft request' (duration: 171.413635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:08.688909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.801589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:56:08.688997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.167309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-klcjn\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-02T06:56:08.689057Z","caller":"traceutil/trace.go:171","msg":"trace[1774332545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"328.000273ms","start":"2026-06-02T06:56:08.361035Z","end":"2026-06-02T06:56:08.689036Z","steps":["trace[1774332545] 'range keys from in-memory index tree' (duration: 327.595245ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:08.689081Z","caller":"traceutil/trace.go:171","msg":"trace[1565154001] range","detail":"{range_begin:/registry/pods/kube-system/cilium-klcjn; range_end:; response_count:1; response_revision:639; }","duration":"163.309931ms","start":"2026-06-02T06:56:08.52575Z","end":"2026-06-02T06:56:08.68906Z","steps":["trace[1565154001] 'range keys from in-memory index tree' (duration: 162.933643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:08.689314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:08.361015Z","time spent":"328.265369ms","remote":"127.0.0.1:38460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T06:57:37.491092Z","caller":"traceutil/trace.go:171","msg":"trace[335769383] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"108.609443ms","start":"2026-06-02T06:57:37.38245Z","end":"2026-06-02T06:57:37.49106Z","steps":["trace[335769383] 'process raft request' (duration: 108.371178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:37.814924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.482564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:57:37.815083Z","caller":"traceutil/trace.go:171","msg":"trace[1396210398] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"318.816602ms","start":"2026-06-02T06:57:37.496239Z","end":"2026-06-02T06:57:37.815056Z","steps":["trace[1396210398] 'process raft request' (duration: 39.331736ms)","trace[1396210398] 'compare' (duration: 278.317251ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:57:37.81518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:37.496196Z","time spent":"318.937666ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:57:37.820139Z","caller":"traceutil/trace.go:171","msg":"trace[1572424371] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"323.819492ms","start":"2026-06-02T06:57:37.496308Z","end":"2026-06-02T06:57:37.820128Z","steps":["trace[1572424371] 'process raft request' (duration: 323.757001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:37.820207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:37.496283Z","time spent":"323.888644ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:57:37.820255Z","caller":"traceutil/trace.go:171","msg":"trace[932671063] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1933; }","duration":"196.014308ms","start":"2026-06-02T06:57:37.624192Z","end":"2026-06-02T06:57:37.820206Z","steps":["trace[932671063] 'read index received' (duration: 195.992447ms)","trace[932671063] 'applied index is now lower than readState.Index' (duration: 18.311µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:57:37.820521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.313535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T06:57:37.820569Z","caller":"traceutil/trace.go:171","msg":"trace[985456444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"196.416406ms","start":"2026-06-02T06:57:37.624141Z","end":"2026-06-02T06:57:37.820557Z","steps":["trace[985456444] 'agreement among raft nodes before linearized reading' (duration: 196.196761ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:37.821979Z","caller":"traceutil/trace.go:171","msg":"trace[1324358258] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"166.211871ms","start":"2026-06-02T06:57:37.655734Z","end":"2026-06-02T06:57:37.821945Z","steps":["trace[1324358258] 'process raft request' (duration: 166.038018ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:49.689245Z","caller":"traceutil/trace.go:171","msg":"trace[899682037] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"139.136655ms","start":"2026-06-02T06:57:49.549978Z","end":"2026-06-02T06:57:49.689115Z","steps":["trace[899682037] 'process raft request' (duration: 138.838349ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:50.007483Z","caller":"traceutil/trace.go:171","msg":"trace[222626930] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2032; }","duration":"136.081998ms","start":"2026-06-02T06:57:49.871375Z","end":"2026-06-02T06:57:50.007457Z","steps":["trace[222626930] 'read index received' (duration: 135.832573ms)","trace[222626930] 'applied index is now lower than readState.Index' (duration: 248.175µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:57:50.007542Z","caller":"traceutil/trace.go:171","msg":"trace[1104081748] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"145.193748ms","start":"2026-06-02T06:57:49.862314Z","end":"2026-06-02T06:57:50.007508Z","steps":["trace[1104081748] 'process raft request' (duration: 144.988674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:50.007797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.480598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-02T06:57:50.007793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.403025ms","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-06-02T06:57:50.007834Z","caller":"traceutil/trace.go:171","msg":"trace[1455594754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1960; }","duration":"132.540059ms","start":"2026-06-02T06:57:49.875286Z","end":"2026-06-02T06:57:50.007826Z","steps":["trace[1455594754] 'agreement among raft nodes before linearized reading' (duration: 132.437497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:50.007832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.363698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T06:57:50.007864Z","caller":"traceutil/trace.go:171","msg":"trace[386491450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1960; }","duration":"136.529457ms","start":"2026-06-02T06:57:49.871318Z","end":"2026-06-02T06:57:50.007847Z","steps":["trace[386491450] 'agreement among raft nodes before linearized reading' (duration: 136.290842ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:50.007893Z","caller":"traceutil/trace.go:171","msg":"trace[1113308577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1960; }","duration":"133.45898ms","start":"2026-06-02T06:57:49.874417Z","end":"2026-06-02T06:57:50.007876Z","steps":["trace[1113308577] 'agreement among raft nodes before linearized reading' (duration: 133.254886ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:27.925543Z","caller":"traceutil/trace.go:171","msg":"trace[1961982368] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"121.800929ms","start":"2026-06-02T06:58:27.803718Z","end":"2026-06-02T06:58:27.925519Z","steps":["trace[1961982368] 'process raft request' (duration: 121.619056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:28.298869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.71181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:58:28.298957Z","caller":"traceutil/trace.go:171","msg":"trace[1170865037] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"373.453613ms","start":"2026-06-02T06:58:27.92549Z","end":"2026-06-02T06:58:28.298944Z","steps":["trace[1170865037] 'read index received' (duration: 141.550839ms)","trace[1170865037] 'applied index is now lower than readState.Index' (duration: 231.901974ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:28.298981Z","caller":"traceutil/trace.go:171","msg":"trace[44397926] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"378.414507ms","start":"2026-06-02T06:58:27.920542Z","end":"2026-06-02T06:58:28.298957Z","steps":["trace[44397926] 'process raft request' (duration: 146.546024ms)","trace[44397926] 'compare' (duration: 231.623778ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:28.299074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.909266ms","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-06-02T06:58:28.299108Z","caller":"traceutil/trace.go:171","msg":"trace[2133787671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"401.995257ms","start":"2026-06-02T06:58:27.897104Z","end":"2026-06-02T06:58:28.2991Z","steps":["trace[2133787671] 'agreement among raft nodes before linearized reading' (duration: 401.896055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:28.299136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:27.897089Z","time spent":"402.040298ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T06:58:28.299098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:27.920507Z","time spent":"378.52724ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:58:28.299326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.415126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:28.299409Z","caller":"traceutil/trace.go:171","msg":"trace[743736093] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2178; }","duration":"420.556019ms","start":"2026-06-02T06:58:27.878835Z","end":"2026-06-02T06:58:28.299391Z","steps":["trace[743736093] 'agreement among raft nodes before linearized reading' (duration: 420.417136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:28.299457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:27.878811Z","time spent":"420.635991ms","remote":"127.0.0.1:53396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-06-02T06:58:28.560818Z","caller":"traceutil/trace.go:171","msg":"trace[802596119] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"234.499429ms","start":"2026-06-02T06:58:28.326293Z","end":"2026-06-02T06:58:28.560792Z","steps":["trace[802596119] 'process raft request' (duration: 234.425027ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:28.560831Z","caller":"traceutil/trace.go:171","msg":"trace[1109756371] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"234.55177ms","start":"2026-06-02T06:58:28.326249Z","end":"2026-06-02T06:58:28.560801Z","steps":["trace[1109756371] 'process raft request' (duration: 234.314995ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:28.561076Z","caller":"traceutil/trace.go:171","msg":"trace[1042227886] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"234.722453ms","start":"2026-06-02T06:58:28.326313Z","end":"2026-06-02T06:58:28.561035Z","steps":["trace[1042227886] 'process raft request' (duration: 234.446427ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:18.601296Z","caller":"traceutil/trace.go:171","msg":"trace[167170632] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"122.163849ms","start":"2026-06-02T06:59:18.479104Z","end":"2026-06-02T06:59:18.601268Z","steps":["trace[167170632] 'process raft request' (duration: 121.946465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:03:51.424474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.168901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:03:51.424603Z","caller":"traceutil/trace.go:171","msg":"trace[1849190381] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5051; }","duration":"181.331075ms","start":"2026-06-02T07:03:51.243247Z","end":"2026-06-02T07:03:51.424578Z","steps":["trace[1849190381] 'range keys from in-memory index tree' (duration: 181.043049ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:03:51.838272Z","caller":"traceutil/trace.go:171","msg":"trace[2046091922] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"134.067056ms","start":"2026-06-02T07:03:51.704169Z","end":"2026-06-02T07:03:51.838237Z","steps":["trace[2046091922] 'process raft request' (duration: 47.201459ms)","trace[2046091922] 'compare' (duration: 86.714304ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:03:51.861542Z","caller":"traceutil/trace.go:171","msg":"trace[1692860729] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"137.08235ms","start":"2026-06-02T07:03:51.724434Z","end":"2026-06-02T07:03:51.861516Z","steps":["trace[1692860729] 'process raft request' (duration: 136.933617ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:04:05.242205Z","caller":"traceutil/trace.go:171","msg":"trace[1879379687] linearizableReadLoop","detail":"{readStateIndex:5516; appliedIndex:5515; }","duration":"187.640971ms","start":"2026-06-02T07:04:05.05453Z","end":"2026-06-02T07:04:05.242171Z","steps":["trace[1879379687] 'read index received' (duration: 187.376066ms)","trace[1879379687] 'applied index is now lower than readState.Index' (duration: 263.195µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:04:05.242256Z","caller":"traceutil/trace.go:171","msg":"trace[227107429] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"212.266987ms","start":"2026-06-02T07:04:05.029967Z","end":"2026-06-02T07:04:05.242234Z","steps":["trace[227107429] 'process raft request' (duration: 211.980721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:04:05.242443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.897136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-02T07:04:05.242507Z","caller":"traceutil/trace.go:171","msg":"trace[581657754] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5319; }","duration":"188.008369ms","start":"2026-06-02T07:04:05.054479Z","end":"2026-06-02T07:04:05.242487Z","steps":["trace[581657754] 'agreement among raft nodes before linearized reading' (duration: 187.825235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:04:05.242618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.610996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g64fk\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-02T07:04:05.242712Z","caller":"traceutil/trace.go:171","msg":"trace[257876480] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g64fk; range_end:; response_count:1; response_revision:5319; }","duration":"184.743969ms","start":"2026-06-02T07:04:05.057947Z","end":"2026-06-02T07:04:05.242691Z","steps":["trace[257876480] 'agreement among raft nodes before linearized reading' (duration: 184.467653ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:04:12.688698Z","caller":"traceutil/trace.go:171","msg":"trace[1308665972] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"127.911674ms","start":"2026-06-02T07:04:12.560731Z","end":"2026-06-02T07:04:12.688642Z","steps":["trace[1308665972] 'process raft request' (duration: 99.783523ms)","trace[1308665972] 'compare' (duration: 27.769054ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:05:23.397636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-06-02T07:05:23.470103Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"70.87561ms","hash":4075052494,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":34168832,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T07:05:23.470154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4075052494,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-06-02T07:10:23.407046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6137} {"level":"info","ts":"2026-06-02T07:10:23.51921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6137,"took":"108.927822ms","hash":1775139033,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":21512192,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-02T07:10:23.519265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1775139033,"revision":6137,"compact-revision":3000} {"level":"info","ts":"2026-06-02T07:10:37.868621Z","caller":"traceutil/trace.go:171","msg":"trace[389970173] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"169.266684ms","start":"2026-06-02T07:10:37.699326Z","end":"2026-06-02T07:10:37.868592Z","steps":["trace[389970173] 'process raft request' (duration: 169.07243ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:10:49.608507Z","caller":"traceutil/trace.go:171","msg":"trace[1389538437] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"111.442426ms","start":"2026-06-02T07:10:49.497017Z","end":"2026-06-02T07:10:49.60846Z","steps":["trace[1389538437] 'process raft request' (duration: 111.13797ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:10:50.593314Z","caller":"traceutil/trace.go:171","msg":"trace[1630104194] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8767; }","duration":"152.504776ms","start":"2026-06-02T07:10:50.440784Z","end":"2026-06-02T07:10:50.593289Z","steps":["trace[1630104194] 'read index received' (duration: 152.313042ms)","trace[1630104194] 'applied index is now lower than readState.Index' (duration: 190.244µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:10:50.593346Z","caller":"traceutil/trace.go:171","msg":"trace[1544333174] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"228.312546ms","start":"2026-06-02T07:10:50.365009Z","end":"2026-06-02T07:10:50.593322Z","steps":["trace[1544333174] 'process raft request' (duration: 228.102392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:10:50.593558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.757183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T07:10:50.593597Z","caller":"traceutil/trace.go:171","msg":"trace[622873022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8471; }","duration":"152.853484ms","start":"2026-06-02T07:10:50.440734Z","end":"2026-06-02T07:10:50.593587Z","steps":["trace[622873022] 'agreement among raft nodes before linearized reading' (duration: 152.701751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:10:50.593788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.141435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T07:10:50.593834Z","caller":"traceutil/trace.go:171","msg":"trace[1334462661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8471; }","duration":"132.208817ms","start":"2026-06-02T07:10:50.461612Z","end":"2026-06-02T07:10:50.593821Z","steps":["trace[1334462661] 'agreement among raft nodes before linearized reading' (duration: 132.077474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:10:50.593958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.422535ms","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-06-02T07:10:50.594138Z","caller":"traceutil/trace.go:171","msg":"trace[128170956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8471; }","duration":"152.647529ms","start":"2026-06-02T07:10:50.441457Z","end":"2026-06-02T07:10:50.594105Z","steps":["trace[128170956] 'agreement among raft nodes before linearized reading' (duration: 152.136429ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:11:19.2215Z","caller":"traceutil/trace.go:171","msg":"trace[1420397015] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"242.845249ms","start":"2026-06-02T07:11:18.978635Z","end":"2026-06-02T07:11:19.22148Z","steps":["trace[1420397015] 'process raft request' (duration: 242.729497ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:11:22.925213Z","caller":"traceutil/trace.go:171","msg":"trace[1482298072] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:9001; }","duration":"143.067419ms","start":"2026-06-02T07:11:22.782105Z","end":"2026-06-02T07:11:22.925173Z","steps":["trace[1482298072] 'read index received' (duration: 142.823244ms)","trace[1482298072] 'applied index is now lower than readState.Index' (duration: 242.795µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:11:22.925354Z","caller":"traceutil/trace.go:171","msg":"trace[1848307198] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"153.39342ms","start":"2026-06-02T07:11:22.771907Z","end":"2026-06-02T07:11:22.9253Z","steps":["trace[1848307198] 'process raft request' (duration: 153.000331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:11:22.925507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.782147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T07:11:22.925549Z","caller":"traceutil/trace.go:171","msg":"trace[916260994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8695; }","duration":"137.90498ms","start":"2026-06-02T07:11:22.787632Z","end":"2026-06-02T07:11:22.925537Z","steps":["trace[916260994] 'agreement among raft nodes before linearized reading' (duration: 137.718936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:11:22.925581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.467287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T07:11:22.92562Z","caller":"traceutil/trace.go:171","msg":"trace[52245440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8695; }","duration":"143.535569ms","start":"2026-06-02T07:11:22.782076Z","end":"2026-06-02T07:11:22.925612Z","steps":["trace[52245440] 'agreement among raft nodes before linearized reading' (duration: 143.231163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:11:22.925656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.724485ms","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-06-02T07:11:22.925729Z","caller":"traceutil/trace.go:171","msg":"trace[1896509595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8695; }","duration":"136.848267ms","start":"2026-06-02T07:11:22.788865Z","end":"2026-06-02T07:11:22.925713Z","steps":["trace[1896509595] 'agreement among raft nodes before linearized reading' (duration: 136.561621ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:11:23.046584Z","caller":"traceutil/trace.go:171","msg":"trace[166331370] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"116.775531ms","start":"2026-06-02T07:11:22.929761Z","end":"2026-06-02T07:11:23.046536Z","steps":["trace[166331370] 'process raft request' (duration: 84.798882ms)","trace[166331370] 'compare' (duration: 31.594091ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:11:23.046619Z","caller":"traceutil/trace.go:171","msg":"trace[1130863126] transaction","detail":"{read_only:false; response_revision:8697; number_of_response:1; }","duration":"116.805822ms","start":"2026-06-02T07:11:22.929783Z","end":"2026-06-02T07:11:23.046589Z","steps":["trace[1130863126] 'process raft request' (duration: 116.655869ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:11:23.050738Z","caller":"traceutil/trace.go:171","msg":"trace[952225524] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"119.879466ms","start":"2026-06-02T07:11:22.930838Z","end":"2026-06-02T07:11:23.050718Z","steps":["trace[952225524] 'process raft request' (duration: 119.713853ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:11:33.482304Z","caller":"traceutil/trace.go:171","msg":"trace[2098158454] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"181.271282ms","start":"2026-06-02T07:11:33.300902Z","end":"2026-06-02T07:11:33.482173Z","steps":["trace[2098158454] 'process raft request' (duration: 180.981136ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:11:34.285336Z","caller":"traceutil/trace.go:171","msg":"trace[841563987] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"100.515295ms","start":"2026-06-02T07:11:34.184774Z","end":"2026-06-02T07:11:34.285289Z","steps":["trace[841563987] 'process raft request' (duration: 99.943063ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:11:53.526323Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002483.wal"} {"level":"warn","ts":"2026-06-02T07:12:53.045273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.811423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:12:53.045396Z","caller":"traceutil/trace.go:171","msg":"trace[1856550914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9562; }","duration":"198.004697ms","start":"2026-06-02T07:12:52.847366Z","end":"2026-06-02T07:12:53.045371Z","steps":["trace[1856550914] 'range keys from in-memory index tree' (duration: 197.69723ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:13:04.960921Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T07:13:04.974422Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-02T07:13:04.974551Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-02T07:15:23.415216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8199} {"level":"info","ts":"2026-06-02T07:15:23.482579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8199,"took":"64.410819ms","hash":2996819966,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":28315648,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T07:15:23.482674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996819966,"revision":8199,"compact-revision":6137} {"level":"info","ts":"2026-06-02T07:18:28.94597Z","caller":"traceutil/trace.go:171","msg":"trace[111189737] transaction","detail":"{read_only:false; response_revision:11978; number_of_response:1; }","duration":"115.852667ms","start":"2026-06-02T07:18:28.830087Z","end":"2026-06-02T07:18:28.94594Z","steps":["trace[111189737] 'process raft request' (duration: 115.672533ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:18:29.1245Z","caller":"traceutil/trace.go:171","msg":"trace[662380850] transaction","detail":"{read_only:false; response_revision:11979; number_of_response:1; }","duration":"151.793002ms","start":"2026-06-02T07:18:28.972665Z","end":"2026-06-02T07:18:29.124458Z","steps":["trace[662380850] 'process raft request' (duration: 149.77121ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:20:23.422494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10558} {"level":"info","ts":"2026-06-02T07:20:23.50595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10558,"took":"80.358032ms","hash":2165267474,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":26148864,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-02T07:20:23.506039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2165267474,"revision":10558,"compact-revision":8199} {"level":"info","ts":"2026-06-02T07:25:01.048617Z","caller":"traceutil/trace.go:171","msg":"trace[1750127485] transaction","detail":"{read_only:false; response_revision:14605; number_of_response:1; }","duration":"318.479153ms","start":"2026-06-02T07:25:00.730114Z","end":"2026-06-02T07:25:01.048593Z","steps":["trace[1750127485] 'process raft request' (duration: 318.35014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:25:01.048772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.4629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:594911"} {"level":"info","ts":"2026-06-02T07:25:01.048845Z","caller":"traceutil/trace.go:171","msg":"trace[477032143] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14604; }","duration":"431.852488ms","start":"2026-06-02T07:25:00.616979Z","end":"2026-06-02T07:25:01.048831Z","steps":["trace[477032143] 'range keys from in-memory index tree' (duration: 430.10344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:25:01.048891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:25:00.616943Z","time spent":"431.9338ms","remote":"127.0.0.1:38672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":63,"response size":594934,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-02T07:25:01.048778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:25:00.730079Z","time spent":"318.622585ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:25:07.928634Z","caller":"traceutil/trace.go:171","msg":"trace[394771784] linearizableReadLoop","detail":"{readStateIndex:15165; appliedIndex:15164; }","duration":"145.845894ms","start":"2026-06-02T07:25:07.782757Z","end":"2026-06-02T07:25:07.928603Z","steps":["trace[394771784] 'read index received' (duration: 89.727178ms)","trace[394771784] 'applied index is now lower than readState.Index' (duration: 56.117496ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:25:07.92871Z","caller":"traceutil/trace.go:171","msg":"trace[1152489267] transaction","detail":"{read_only:false; response_revision:14656; number_of_response:1; }","duration":"189.370979ms","start":"2026-06-02T07:25:07.739301Z","end":"2026-06-02T07:25:07.928672Z","steps":["trace[1152489267] 'process raft request' (duration: 133.291615ms)","trace[1152489267] 'compare' (duration: 55.85927ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:25:07.92898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.207441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-02T07:25:07.929025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.509813ms","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-06-02T07:25:07.929163Z","caller":"traceutil/trace.go:171","msg":"trace[380610432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14656; }","duration":"136.653836ms","start":"2026-06-02T07:25:07.792448Z","end":"2026-06-02T07:25:07.929102Z","steps":["trace[380610432] 'agreement among raft nodes before linearized reading' (duration: 136.429792ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:25:07.929029Z","caller":"traceutil/trace.go:171","msg":"trace[1424557931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14656; }","duration":"146.318624ms","start":"2026-06-02T07:25:07.782699Z","end":"2026-06-02T07:25:07.929018Z","steps":["trace[1424557931] 'agreement among raft nodes before linearized reading' (duration: 146.105119ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:25:08.086003Z","caller":"traceutil/trace.go:171","msg":"trace[1687753359] transaction","detail":"{read_only:false; response_revision:14657; number_of_response:1; }","duration":"152.539698ms","start":"2026-06-02T07:25:07.933426Z","end":"2026-06-02T07:25:08.085966Z","steps":["trace[1687753359] 'process raft request' (duration: 106.959099ms)","trace[1687753359] 'compare' (duration: 45.325264ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:25:08.08615Z","caller":"traceutil/trace.go:171","msg":"trace[1722079552] transaction","detail":"{read_only:false; response_revision:14658; number_of_response:1; }","duration":"152.148249ms","start":"2026-06-02T07:25:07.93397Z","end":"2026-06-02T07:25:08.086118Z","steps":["trace[1722079552] 'process raft request' (duration: 151.952185ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:25:23.460212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12747} {"level":"info","ts":"2026-06-02T07:25:23.540101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12747,"took":"76.124906ms","hash":2824794890,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":28504064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T07:25:23.540177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2824794890,"revision":12747,"compact-revision":10558} {"level":"warn","ts":"2026-06-02T07:26:49.868126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.790925ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:26:49.868672Z","caller":"traceutil/trace.go:171","msg":"trace[145616733] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15725; }","duration":"133.937958ms","start":"2026-06-02T07:26:49.7343Z","end":"2026-06-02T07:26:49.868238Z","steps":["trace[145616733] 'range keys from in-memory index tree' (duration: 133.777214ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:26:50.013322Z","caller":"traceutil/trace.go:171","msg":"trace[1885366412] transaction","detail":"{read_only:false; response_revision:15726; number_of_response:1; }","duration":"111.026616ms","start":"2026-06-02T07:26:49.902261Z","end":"2026-06-02T07:26:50.013287Z","steps":["trace[1885366412] 'process raft request' (duration: 110.786441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:26:50.202587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.382229ms","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-06-02T07:26:50.202698Z","caller":"traceutil/trace.go:171","msg":"trace[915756583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15726; }","duration":"122.560652ms","start":"2026-06-02T07:26:50.080116Z","end":"2026-06-02T07:26:50.202676Z","steps":["trace[915756583] 'range keys from in-memory index tree' (duration: 122.154944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:26:50.202673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.19497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g64fk\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-02T07:26:50.202796Z","caller":"traceutil/trace.go:171","msg":"trace[1285214635] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g64fk; range_end:; response_count:1; response_revision:15726; }","duration":"112.359073ms","start":"2026-06-02T07:26:50.090407Z","end":"2026-06-02T07:26:50.202766Z","steps":["trace[1285214635] 'range keys from in-memory index tree' (duration: 111.907064ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:30:23.467433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14912} {"level":"info","ts":"2026-06-02T07:30:23.568589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14912,"took":"97.55369ms","hash":378853375,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":30461952,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T07:30:23.5687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":378853375,"revision":14912,"compact-revision":12747} {"level":"warn","ts":"2026-06-02T07:32:45.60661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.877408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T07:32:45.606838Z","caller":"traceutil/trace.go:171","msg":"trace[748730109] linearizableReadLoop","detail":"{readStateIndex:18978; appliedIndex:18977; }","duration":"258.187606ms","start":"2026-06-02T07:32:45.348628Z","end":"2026-06-02T07:32:45.606816Z","steps":["trace[748730109] 'read index received' (duration: 113.029721ms)","trace[748730109] 'applied index is now lower than readState.Index' (duration: 145.155855ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:32:45.606845Z","caller":"traceutil/trace.go:171","msg":"trace[1813132170] transaction","detail":"{read_only:false; response_revision:18349; number_of_response:1; }","duration":"261.247231ms","start":"2026-06-02T07:32:45.345573Z","end":"2026-06-02T07:32:45.606821Z","steps":["trace[1813132170] 'process raft request' (duration: 116.024755ms)","trace[1813132170] 'compare' (duration: 144.672774ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:32:45.607022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.39967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fj9dm\" ","response":"range_response_count:1 size:9793"} {"level":"info","ts":"2026-06-02T07:32:45.60706Z","caller":"traceutil/trace.go:171","msg":"trace[984082436] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fj9dm; range_end:; response_count:1; response_revision:18349; }","duration":"258.462922ms","start":"2026-06-02T07:32:45.348588Z","end":"2026-06-02T07:32:45.607051Z","steps":["trace[984082436] 'agreement among raft nodes before linearized reading' (duration: 258.284008ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:32:45.631606Z","caller":"traceutil/trace.go:171","msg":"trace[205620650] transaction","detail":"{read_only:false; response_revision:18350; number_of_response:1; }","duration":"202.160771ms","start":"2026-06-02T07:32:45.429369Z","end":"2026-06-02T07:32:45.63153Z","steps":["trace[205620650] 'process raft request' (duration: 201.604779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:32:45.955968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.080368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T07:32:45.956088Z","caller":"traceutil/trace.go:171","msg":"trace[317232970] linearizableReadLoop","detail":"{readStateIndex:18981; appliedIndex:18980; }","duration":"205.236837ms","start":"2026-06-02T07:32:45.750833Z","end":"2026-06-02T07:32:45.95607Z","steps":["trace[317232970] 'read index received' (duration: 64.849143ms)","trace[317232970] 'applied index is now lower than readState.Index' (duration: 140.386004ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:32:45.956168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.340629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:32:45.956207Z","caller":"traceutil/trace.go:171","msg":"trace[783969162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:18352; }","duration":"205.4319ms","start":"2026-06-02T07:32:45.750763Z","end":"2026-06-02T07:32:45.956195Z","steps":["trace[783969162] 'agreement among raft nodes before linearized reading' (duration: 205.355519ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:32:45.956397Z","caller":"traceutil/trace.go:171","msg":"trace[184572624] transaction","detail":"{read_only:false; response_revision:18352; number_of_response:1; }","duration":"289.757889ms","start":"2026-06-02T07:32:45.666527Z","end":"2026-06-02T07:32:45.956285Z","steps":["trace[184572624] 'process raft request' (duration: 149.218222ms)","trace[184572624] 'compare' (duration: 139.761411ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:32:46.233953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.901428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:32:46.234083Z","caller":"traceutil/trace.go:171","msg":"trace[964752902] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:18352; }","duration":"136.140843ms","start":"2026-06-02T07:32:46.097917Z","end":"2026-06-02T07:32:46.234058Z","steps":["trace[964752902] 'count revisions from in-memory index tree' (duration: 135.683283ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:32:46.461647Z","caller":"traceutil/trace.go:171","msg":"trace[650534896] linearizableReadLoop","detail":"{readStateIndex:18982; appliedIndex:18981; }","duration":"101.302501ms","start":"2026-06-02T07:32:46.360324Z","end":"2026-06-02T07:32:46.461627Z","steps":["trace[650534896] 'read index received' (duration: 101.131817ms)","trace[650534896] 'applied index is now lower than readState.Index' (duration: 169.564µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:32:46.461787Z","caller":"traceutil/trace.go:171","msg":"trace[986254799] transaction","detail":"{read_only:false; response_revision:18353; number_of_response:1; }","duration":"211.512452ms","start":"2026-06-02T07:32:46.250223Z","end":"2026-06-02T07:32:46.461736Z","steps":["trace[986254799] 'process raft request' (duration: 211.244965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:32:46.461859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.509646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:32:46.46196Z","caller":"traceutil/trace.go:171","msg":"trace[919982086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18353; }","duration":"101.668129ms","start":"2026-06-02T07:32:46.360269Z","end":"2026-06-02T07:32:46.461937Z","steps":["trace[919982086] 'agreement among raft nodes before linearized reading' (duration: 101.479905ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:34:25.443055Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T07:34:25.463896Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-02T07:34:25.464Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-02T07:35:23.474297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17388} {"level":"info","ts":"2026-06-02T07:35:23.570711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17388,"took":"92.268248ms","hash":2990734629,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":34344960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T07:35:23.570788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2990734629,"revision":17388,"compact-revision":14912} {"level":"info","ts":"2026-06-02T07:38:55.712545Z","caller":"traceutil/trace.go:171","msg":"trace[968437619] transaction","detail":"{read_only:false; response_revision:21147; number_of_response:1; }","duration":"123.66011ms","start":"2026-06-02T07:38:55.58885Z","end":"2026-06-02T07:38:55.712511Z","steps":["trace[968437619] 'process raft request' (duration: 123.20911ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:38:56.778493Z","caller":"traceutil/trace.go:171","msg":"trace[604534834] transaction","detail":"{read_only:false; response_revision:21150; number_of_response:1; }","duration":"148.753275ms","start":"2026-06-02T07:38:56.629706Z","end":"2026-06-02T07:38:56.778459Z","steps":["trace[604534834] 'process raft request' (duration: 145.197009ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:38:56.814719Z","caller":"traceutil/trace.go:171","msg":"trace[2127802562] transaction","detail":"{read_only:false; response_revision:21151; number_of_response:1; }","duration":"184.457107ms","start":"2026-06-02T07:38:56.630231Z","end":"2026-06-02T07:38:56.814688Z","steps":["trace[2127802562] 'process raft request' (duration: 184.287724ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:40:23.482116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19723} {"level":"info","ts":"2026-06-02T07:40:23.612017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19723,"took":"124.958057ms","hash":2313559706,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":29630464,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T07:40:23.61209Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2313559706,"revision":19723,"compact-revision":17388} {"level":"warn","ts":"2026-06-02T07:42:56.735153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955115343174549,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:42:56.930759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.017992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-02T07:42:56.930851Z","caller":"traceutil/trace.go:171","msg":"trace[640592020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23618; }","duration":"721.175455ms","start":"2026-06-02T07:42:56.209655Z","end":"2026-06-02T07:42:56.93083Z","steps":["trace[640592020] 'range keys from in-memory index tree' (duration: 720.821077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:56.930893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.20963Z","time spent":"721.254436ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T07:42:56.931064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.006169ms","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:6"} {"level":"info","ts":"2026-06-02T07:42:56.931181Z","caller":"traceutil/trace.go:171","msg":"trace[901879867] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:23618; }","duration":"760.199243ms","start":"2026-06-02T07:42:56.170955Z","end":"2026-06-02T07:42:56.931155Z","steps":["trace[901879867] 'count revisions from in-memory index tree' (duration: 759.855167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:56.931243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.170928Z","time spent":"760.301406ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:42:57.131563Z","caller":"traceutil/trace.go:171","msg":"trace[298378360] transaction","detail":"{read_only:false; response_revision:23619; number_of_response:1; }","duration":"901.470178ms","start":"2026-06-02T07:42:56.230052Z","end":"2026-06-02T07:42:57.131522Z","steps":["trace[298378360] 'process raft request' (duration: 901.172401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.131837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.23002Z","time spent":"901.668582ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:42:57.412365Z","caller":"traceutil/trace.go:171","msg":"trace[1542618694] linearizableReadLoop","detail":"{readStateIndex:24420; appliedIndex:24416; }","duration":"1.178198895s","start":"2026-06-02T07:42:56.234134Z","end":"2026-06-02T07:42:57.412333Z","steps":["trace[1542618694] 'read index received' (duration: 897.064044ms)","trace[1542618694] 'applied index is now lower than readState.Index' (duration: 281.133191ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:42:57.412611Z","caller":"traceutil/trace.go:171","msg":"trace[1085482179] transaction","detail":"{read_only:false; response_revision:23621; number_of_response:1; }","duration":"1.103835956s","start":"2026-06-02T07:42:56.308731Z","end":"2026-06-02T07:42:57.412567Z","steps":["trace[1085482179] 'process raft request' (duration: 1.103317064s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:42:57.412644Z","caller":"traceutil/trace.go:171","msg":"trace[1672749446] transaction","detail":"{read_only:false; response_revision:23620; number_of_response:1; }","duration":"1.1784632s","start":"2026-06-02T07:42:56.234129Z","end":"2026-06-02T07:42:57.412593Z","steps":["trace[1672749446] 'process raft request' (duration: 1.177704993s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:42:57.412683Z","caller":"traceutil/trace.go:171","msg":"trace[1479424099] transaction","detail":"{read_only:false; response_revision:23623; number_of_response:1; }","duration":"552.834338ms","start":"2026-06-02T07:42:56.859765Z","end":"2026-06-02T07:42:57.4126Z","steps":["trace[1479424099] 'process raft request' (duration: 552.426489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.412804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.3087Z","time spent":"1.10401177s","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:42:57.412614Z","caller":"traceutil/trace.go:171","msg":"trace[875096339] transaction","detail":"{read_only:false; response_revision:23622; number_of_response:1; }","duration":"1.036122412s","start":"2026-06-02T07:42:56.376454Z","end":"2026-06-02T07:42:57.412577Z","steps":["trace[875096339] 'process raft request' (duration: 1.035675721s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.412829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178669064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-02T07:42:57.412895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.234091Z","time spent":"1.178655254s","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:42:57.412916Z","caller":"traceutil/trace.go:171","msg":"trace[1690566260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23624; }","duration":"1.178817878s","start":"2026-06-02T07:42:56.234078Z","end":"2026-06-02T07:42:57.412896Z","steps":["trace[1690566260] 'agreement among raft nodes before linearized reading' (duration: 1.178524331s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.412931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.859733Z","time spent":"553.056883ms","remote":"127.0.0.1:38552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":973,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T07:42:57.412624Z","caller":"traceutil/trace.go:171","msg":"trace[1536531526] transaction","detail":"{read_only:false; response_revision:23624; number_of_response:1; }","duration":"475.476776ms","start":"2026-06-02T07:42:56.937101Z","end":"2026-06-02T07:42:57.412577Z","steps":["trace[1536531526] 'process raft request' (duration: 475.165729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.412986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.376352Z","time spent":"1.0365368s","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:42:57.412985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.234054Z","time spent":"1.178916959s","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T07:42:57.413163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.937071Z","time spent":"475.958886ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:42:57.416113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099792729s","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-06-02T07:42:57.41617Z","caller":"traceutil/trace.go:171","msg":"trace[631896421] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23626; }","duration":"1.099879461s","start":"2026-06-02T07:42:56.316276Z","end":"2026-06-02T07:42:57.416156Z","steps":["trace[631896421] 'agreement among raft nodes before linearized reading' (duration: 1.099726968s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.416214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.316266Z","time spent":"1.099938052s","remote":"127.0.0.1:38672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-02T07:42:57.416528Z","caller":"traceutil/trace.go:171","msg":"trace[856774339] transaction","detail":"{read_only:false; response_revision:23626; number_of_response:1; }","duration":"108.053191ms","start":"2026-06-02T07:42:57.308439Z","end":"2026-06-02T07:42:57.416492Z","steps":["trace[856774339] 'process raft request' (duration: 107.51282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.416648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035685453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T07:42:57.416809Z","caller":"traceutil/trace.go:171","msg":"trace[910212753] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:23626; }","duration":"1.035893557s","start":"2026-06-02T07:42:56.380899Z","end":"2026-06-02T07:42:57.416792Z","steps":["trace[910212753] 'agreement among raft nodes before linearized reading' (duration: 1.035713333s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.416747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.31046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:42:57.416929Z","caller":"traceutil/trace.go:171","msg":"trace[1446612072] transaction","detail":"{read_only:false; response_revision:23625; number_of_response:1; }","duration":"194.967278ms","start":"2026-06-02T07:42:57.221945Z","end":"2026-06-02T07:42:57.416913Z","steps":["trace[1446612072] 'process raft request' (duration: 193.873835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.416837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053132267s","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-06-02T07:42:57.416972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.306156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-06-02T07:42:57.416588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076416346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-02T07:42:57.416952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.382325ms","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-06-02T07:42:57.417017Z","caller":"traceutil/trace.go:171","msg":"trace[897491740] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:23626; }","duration":"472.349457ms","start":"2026-06-02T07:42:56.944658Z","end":"2026-06-02T07:42:57.417007Z","steps":["trace[897491740] 'agreement among raft nodes before linearized reading' (duration: 472.238805ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:42:57.416975Z","caller":"traceutil/trace.go:171","msg":"trace[635907243] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:23626; }","duration":"534.630087ms","start":"2026-06-02T07:42:56.882312Z","end":"2026-06-02T07:42:57.416942Z","steps":["trace[635907243] 'agreement among raft nodes before linearized reading' (duration: 534.173517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.416874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083934058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T07:42:57.417184Z","caller":"traceutil/trace.go:171","msg":"trace[460359559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23626; }","duration":"1.084296646s","start":"2026-06-02T07:42:56.332878Z","end":"2026-06-02T07:42:57.417175Z","steps":["trace[460359559] 'agreement among raft nodes before linearized reading' (duration: 1.083912668s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:42:57.417181Z","caller":"traceutil/trace.go:171","msg":"trace[1783907555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23626; }","duration":"1.076919937s","start":"2026-06-02T07:42:56.340122Z","end":"2026-06-02T07:42:57.417042Z","steps":["trace[1783907555] 'agreement among raft nodes before linearized reading' (duration: 1.076382416s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.417222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.332841Z","time spent":"1.084367508s","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T07:42:57.416461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.064004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T07:42:57.417297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.340093Z","time spent":"1.077163063s","remote":"127.0.0.1:38774","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-06-02T07:42:57.417306Z","caller":"traceutil/trace.go:171","msg":"trace[535225744] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:23626; }","duration":"877.956302ms","start":"2026-06-02T07:42:56.539334Z","end":"2026-06-02T07:42:57.41729Z","steps":["trace[535225744] 'agreement among raft nodes before linearized reading' (duration: 877.085194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.417351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.539306Z","time spent":"878.028984ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:42:57.417045Z","caller":"traceutil/trace.go:171","msg":"trace[1592935424] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23626; }","duration":"180.520348ms","start":"2026-06-02T07:42:57.236515Z","end":"2026-06-02T07:42:57.417035Z","steps":["trace[1592935424] 'agreement among raft nodes before linearized reading' (duration: 180.170761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.416848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.380869Z","time spent":"1.035967098s","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:42:57.417007Z","caller":"traceutil/trace.go:171","msg":"trace[563573338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23626; }","duration":"1.053330151s","start":"2026-06-02T07:42:56.363659Z","end":"2026-06-02T07:42:57.416989Z","steps":["trace[563573338] 'agreement among raft nodes before linearized reading' (duration: 1.053001324s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:42:57.417049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.944597Z","time spent":"472.44437ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-06-02T07:42:57.41749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.363644Z","time spent":"1.053828611s","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T07:42:57.418486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:42:56.882298Z","time spent":"536.116779ms","remote":"127.0.0.1:38622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:45:23.491728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21952} {"level":"info","ts":"2026-06-02T07:45:23.612544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21952,"took":"116.911741ms","hash":4051351216,"current-db-size-bytes":59146240,"current-db-size":"59 MB","current-db-size-in-use-bytes":43626496,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-02T07:45:23.612627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4051351216,"revision":21952,"compact-revision":19723} {"level":"info","ts":"2026-06-02T07:46:10.600778Z","caller":"traceutil/trace.go:171","msg":"trace[349483406] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"110.25597ms","start":"2026-06-02T07:46:10.490488Z","end":"2026-06-02T07:46:10.600744Z","steps":["trace[349483406] 'process raft request' (duration: 110.066636ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:10.845784Z","caller":"traceutil/trace.go:171","msg":"trace[1171286937] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"147.843007ms","start":"2026-06-02T07:46:10.697901Z","end":"2026-06-02T07:46:10.845744Z","steps":["trace[1171286937] 'process raft request' (duration: 147.628013ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:11.171442Z","caller":"traceutil/trace.go:171","msg":"trace[1770459906] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"472.315577ms","start":"2026-06-02T07:46:10.699095Z","end":"2026-06-02T07:46:11.17141Z","steps":["trace[1770459906] 'process raft request' (duration: 452.528492ms)","trace[1770459906] 'compare' (duration: 19.169992ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:11.171629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:10.699065Z","time spent":"472.46531ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:11.171732Z","caller":"traceutil/trace.go:171","msg":"trace[1238218315] linearizableReadLoop","detail":"{readStateIndex:26477; appliedIndex:26473; }","duration":"386.642367ms","start":"2026-06-02T07:46:10.785064Z","end":"2026-06-02T07:46:11.171707Z","steps":["trace[1238218315] 'read index received' (duration: 61.526912ms)","trace[1238218315] 'applied index is now lower than readState.Index' (duration: 325.114675ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:11.171893Z","caller":"traceutil/trace.go:171","msg":"trace[1481706334] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"471.300327ms","start":"2026-06-02T07:46:10.700584Z","end":"2026-06-02T07:46:11.171884Z","steps":["trace[1481706334] 'process raft request' (duration: 470.715884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.172002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:10.700574Z","time spent":"471.346238ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:11.172147Z","caller":"traceutil/trace.go:171","msg":"trace[1673417736] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"470.882657ms","start":"2026-06-02T07:46:10.70125Z","end":"2026-06-02T07:46:11.172133Z","steps":["trace[1673417736] 'process raft request' (duration: 470.414046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.17221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.153119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T07:46:11.17222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:10.701224Z","time spent":"470.964858ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:11.172245Z","caller":"traceutil/trace.go:171","msg":"trace[557763906] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:25625; }","duration":"387.21766ms","start":"2026-06-02T07:46:10.785016Z","end":"2026-06-02T07:46:11.172234Z","steps":["trace[557763906] 'agreement among raft nodes before linearized reading' (duration: 387.164689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.17227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:10.785001Z","time spent":"387.263361ms","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:46:11.172373Z","caller":"traceutil/trace.go:171","msg":"trace[2038660615] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"471.616224ms","start":"2026-06-02T07:46:10.700746Z","end":"2026-06-02T07:46:11.172362Z","steps":["trace[2038660615] 'process raft request' (duration: 470.868167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.172438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:10.700716Z","time spent":"471.691165ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:11.481461Z","caller":"traceutil/trace.go:171","msg":"trace[1703150772] linearizableReadLoop","detail":"{readStateIndex:26479; appliedIndex:26477; }","duration":"309.631432ms","start":"2026-06-02T07:46:11.171799Z","end":"2026-06-02T07:46:11.481431Z","steps":["trace[1703150772] 'read index received' (duration: 259.27425ms)","trace[1703150772] 'applied index is now lower than readState.Index' (duration: 50.355882ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:11.481761Z","caller":"traceutil/trace.go:171","msg":"trace[2064353065] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"558.99994ms","start":"2026-06-02T07:46:10.922742Z","end":"2026-06-02T07:46:11.481742Z","steps":["trace[2064353065] 'process raft request' (duration: 508.308771ms)","trace[2064353065] 'compare' (duration: 49.885442ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:11.481917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:10.922712Z","time spent":"559.093922ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:11.48196Z","caller":"traceutil/trace.go:171","msg":"trace[262648651] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"383.967889ms","start":"2026-06-02T07:46:11.097945Z","end":"2026-06-02T07:46:11.481913Z","steps":["trace[262648651] 'process raft request' (duration: 383.340766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.483506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.121104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-02T07:46:11.483565Z","caller":"traceutil/trace.go:171","msg":"trace[814266176] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25627; }","duration":"660.188645ms","start":"2026-06-02T07:46:10.823359Z","end":"2026-06-02T07:46:11.483547Z","steps":["trace[814266176] 'agreement among raft nodes before linearized reading' (duration: 660.001311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.4836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:10.823351Z","time spent":"660.242066ms","remote":"127.0.0.1:38774","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-06-02T07:46:11.48387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.269103ms","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-06-02T07:46:11.483984Z","caller":"traceutil/trace.go:171","msg":"trace[2005904130] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25627; }","duration":"328.430896ms","start":"2026-06-02T07:46:11.155528Z","end":"2026-06-02T07:46:11.483959Z","steps":["trace[2005904130] 'agreement among raft nodes before linearized reading' (duration: 326.26458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.484047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:11.155509Z","time spent":"328.527468ms","remote":"127.0.0.1:38592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-06-02T07:46:11.485597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:11.097911Z","time spent":"387.492025ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:46:11.495772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.749875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:11.49582Z","caller":"traceutil/trace.go:171","msg":"trace[2042715145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25628; }","duration":"134.825907ms","start":"2026-06-02T07:46:11.360983Z","end":"2026-06-02T07:46:11.495809Z","steps":["trace[2042715145] 'agreement among raft nodes before linearized reading' (duration: 134.738065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.754459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.173034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T07:46:11.754747Z","caller":"traceutil/trace.go:171","msg":"trace[1491953136] linearizableReadLoop","detail":"{readStateIndex:26481; appliedIndex:26480; }","duration":"259.045256ms","start":"2026-06-02T07:46:11.49566Z","end":"2026-06-02T07:46:11.754706Z","steps":["trace[1491953136] 'read index received' (duration: 103.532184ms)","trace[1491953136] 'applied index is now lower than readState.Index' (duration: 155.510642ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:11.754962Z","caller":"traceutil/trace.go:171","msg":"trace[90019675] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"265.258179ms","start":"2026-06-02T07:46:11.489683Z","end":"2026-06-02T07:46:11.754941Z","steps":["trace[90019675] 'process raft request' (duration: 109.484602ms)","trace[90019675] 'compare' (duration: 155.02578ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:11.75531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.725648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T07:46:11.755384Z","caller":"traceutil/trace.go:171","msg":"trace[1387109574] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25630; }","duration":"264.81403ms","start":"2026-06-02T07:46:11.490547Z","end":"2026-06-02T07:46:11.755361Z","steps":["trace[1387109574] 'agreement among raft nodes before linearized reading' (duration: 264.692237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.755376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.419039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:11.755457Z","caller":"traceutil/trace.go:171","msg":"trace[1749015277] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25630; }","duration":"229.608583ms","start":"2026-06-02T07:46:11.525828Z","end":"2026-06-02T07:46:11.755437Z","steps":["trace[1749015277] 'agreement among raft nodes before linearized reading' (duration: 229.424369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.755451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.163964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T07:46:11.755456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.790881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:11.75556Z","caller":"traceutil/trace.go:171","msg":"trace[1988776227] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25630; }","duration":"232.988125ms","start":"2026-06-02T07:46:11.522548Z","end":"2026-06-02T07:46:11.755537Z","steps":["trace[1988776227] 'agreement among raft nodes before linearized reading' (duration: 232.778181ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:11.75556Z","caller":"traceutil/trace.go:171","msg":"trace[1892708051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25630; }","duration":"256.320467ms","start":"2026-06-02T07:46:11.499208Z","end":"2026-06-02T07:46:11.755528Z","steps":["trace[1892708051] 'agreement among raft nodes before linearized reading' (duration: 256.120603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:11.755655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.649149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-02T07:46:11.75571Z","caller":"traceutil/trace.go:171","msg":"trace[31422283] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25630; }","duration":"259.743461ms","start":"2026-06-02T07:46:11.495948Z","end":"2026-06-02T07:46:11.755692Z","steps":["trace[31422283] 'agreement among raft nodes before linearized reading' (duration: 259.063936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:12.096982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.950779ms","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-06-02T07:46:12.097068Z","caller":"traceutil/trace.go:171","msg":"trace[33408128] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25630; }","duration":"148.098672ms","start":"2026-06-02T07:46:11.94895Z","end":"2026-06-02T07:46:12.097049Z","steps":["trace[33408128] 'range keys from in-memory index tree' (duration: 147.745744ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:12.252734Z","caller":"traceutil/trace.go:171","msg":"trace[1334393437] linearizableReadLoop","detail":"{readStateIndex:26484; appliedIndex:26483; }","duration":"150.972963ms","start":"2026-06-02T07:46:12.101725Z","end":"2026-06-02T07:46:12.252698Z","steps":["trace[1334393437] 'read index received' (duration: 150.122895ms)","trace[1334393437] 'applied index is now lower than readState.Index' (duration: 848.368µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:12.252809Z","caller":"traceutil/trace.go:171","msg":"trace[1418303289] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"222.420218ms","start":"2026-06-02T07:46:12.030348Z","end":"2026-06-02T07:46:12.252768Z","steps":["trace[1418303289] 'process raft request' (duration: 221.519319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:12.25304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.285311ms","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-06-02T07:46:12.253092Z","caller":"traceutil/trace.go:171","msg":"trace[1547870465] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25632; }","duration":"151.420624ms","start":"2026-06-02T07:46:12.101659Z","end":"2026-06-02T07:46:12.25308Z","steps":["trace[1547870465] 'agreement among raft nodes before linearized reading' (duration: 151.212129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:12.620225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.31203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-06-02T07:46:12.620382Z","caller":"traceutil/trace.go:171","msg":"trace[1368885658] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25633; }","duration":"136.527933ms","start":"2026-06-02T07:46:12.48382Z","end":"2026-06-02T07:46:12.620348Z","steps":["trace[1368885658] 'range keys from in-memory index tree' (duration: 135.976621ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:12.823929Z","caller":"traceutil/trace.go:171","msg":"trace[2105707675] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"107.581662ms","start":"2026-06-02T07:46:12.7163Z","end":"2026-06-02T07:46:12.823881Z","steps":["trace[2105707675] 'process raft request' (duration: 93.5453ms)","trace[2105707675] 'compare' (duration: 13.728465ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:13.43553Z","caller":"traceutil/trace.go:171","msg":"trace[429920974] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"252.998136ms","start":"2026-06-02T07:46:13.182495Z","end":"2026-06-02T07:46:13.435494Z","steps":["trace[429920974] 'process raft request' (duration: 252.7233ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:13.435868Z","caller":"traceutil/trace.go:171","msg":"trace[739111777] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"253.250352ms","start":"2026-06-02T07:46:13.18253Z","end":"2026-06-02T07:46:13.43578Z","steps":["trace[739111777] 'process raft request' (duration: 252.909785ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:13.465255Z","caller":"traceutil/trace.go:171","msg":"trace[1448306150] linearizableReadLoop","detail":"{readStateIndex:26494; appliedIndex:26490; }","duration":"104.152047ms","start":"2026-06-02T07:46:13.361072Z","end":"2026-06-02T07:46:13.465224Z","steps":["trace[1448306150] 'read index received' (duration: 74.205704ms)","trace[1448306150] 'applied index is now lower than readState.Index' (duration: 29.944723ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:13.465324Z","caller":"traceutil/trace.go:171","msg":"trace[279637064] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"282.602922ms","start":"2026-06-02T07:46:13.182675Z","end":"2026-06-02T07:46:13.465278Z","steps":["trace[279637064] 'process raft request' (duration: 282.215464ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:13.46536Z","caller":"traceutil/trace.go:171","msg":"trace[1839679107] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"282.556971ms","start":"2026-06-02T07:46:13.182755Z","end":"2026-06-02T07:46:13.465312Z","steps":["trace[1839679107] 'process raft request' (duration: 282.417578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:13.465486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.394133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:13.46553Z","caller":"traceutil/trace.go:171","msg":"trace[1608026871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25641; }","duration":"104.556016ms","start":"2026-06-02T07:46:13.360962Z","end":"2026-06-02T07:46:13.465518Z","steps":["trace[1608026871] 'agreement among raft nodes before linearized reading' (duration: 104.373282ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:13.630706Z","caller":"traceutil/trace.go:171","msg":"trace[572383729] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"139.895985ms","start":"2026-06-02T07:46:13.490763Z","end":"2026-06-02T07:46:13.630658Z","steps":["trace[572383729] 'process raft request' (duration: 115.591374ms)","trace[572383729] 'compare' (duration: 23.78424ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:13.955926Z","caller":"traceutil/trace.go:171","msg":"trace[520865424] transaction","detail":"{read_only:false; response_revision:25643; number_of_response:1; }","duration":"193.303823ms","start":"2026-06-02T07:46:13.7626Z","end":"2026-06-02T07:46:13.955904Z","steps":["trace[520865424] 'process raft request' (duration: 193.1198ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:13.967941Z","caller":"traceutil/trace.go:171","msg":"trace[2021777313] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"185.783602ms","start":"2026-06-02T07:46:13.782141Z","end":"2026-06-02T07:46:13.967925Z","steps":["trace[2021777313] 'process raft request' (duration: 185.68784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:14.424642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.164431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T07:46:14.424797Z","caller":"traceutil/trace.go:171","msg":"trace[280924402] linearizableReadLoop","detail":"{readStateIndex:26499; appliedIndex:26498; }","duration":"160.648103ms","start":"2026-06-02T07:46:14.264122Z","end":"2026-06-02T07:46:14.42477Z","steps":["trace[280924402] 'read index received' (duration: 27.059202ms)","trace[280924402] 'applied index is now lower than readState.Index' (duration: 133.586481ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:14.424918Z","caller":"traceutil/trace.go:171","msg":"trace[1776524372] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"165.648769ms","start":"2026-06-02T07:46:14.259227Z","end":"2026-06-02T07:46:14.424876Z","steps":["trace[1776524372] 'process raft request' (duration: 32.076139ms)","trace[1776524372] 'compare' (duration: 132.838254ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:14.424985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.863527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:14.42504Z","caller":"traceutil/trace.go:171","msg":"trace[162566976] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25646; }","duration":"160.961209ms","start":"2026-06-02T07:46:14.26406Z","end":"2026-06-02T07:46:14.425021Z","steps":["trace[162566976] 'agreement among raft nodes before linearized reading' (duration: 160.849537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:14.688465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.044034ms","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":"info","ts":"2026-06-02T07:46:14.688601Z","caller":"traceutil/trace.go:171","msg":"trace[1664184895] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25647; }","duration":"137.252008ms","start":"2026-06-02T07:46:14.551324Z","end":"2026-06-02T07:46:14.688576Z","steps":["trace[1664184895] 'count revisions from in-memory index tree' (duration: 136.854551ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:14.995836Z","caller":"traceutil/trace.go:171","msg":"trace[1566737022] linearizableReadLoop","detail":"{readStateIndex:26502; appliedIndex:26501; }","duration":"129.307509ms","start":"2026-06-02T07:46:14.866499Z","end":"2026-06-02T07:46:14.995807Z","steps":["trace[1566737022] 'read index received' (duration: 119.098769ms)","trace[1566737022] 'applied index is now lower than readState.Index' (duration: 10.207439ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:14.995945Z","caller":"traceutil/trace.go:171","msg":"trace[1169209294] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"368.112499ms","start":"2026-06-02T07:46:14.627776Z","end":"2026-06-02T07:46:14.995889Z","steps":["trace[1169209294] 'process raft request' (duration: 357.833788ms)","trace[1169209294] 'compare' (duration: 10.032176ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:14.996124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:14.627745Z","time spent":"368.278423ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:46:14.996121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.505003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"warn","ts":"2026-06-02T07:46:14.996149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.628515ms","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-06-02T07:46:14.99619Z","caller":"traceutil/trace.go:171","msg":"trace[1193894811] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25649; }","duration":"109.614624ms","start":"2026-06-02T07:46:14.886559Z","end":"2026-06-02T07:46:14.996174Z","steps":["trace[1193894811] 'agreement among raft nodes before linearized reading' (duration: 109.41743ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:14.996222Z","caller":"traceutil/trace.go:171","msg":"trace[1014376109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25649; }","duration":"129.739267ms","start":"2026-06-02T07:46:14.866441Z","end":"2026-06-02T07:46:14.996181Z","steps":["trace[1014376109] 'agreement among raft nodes before linearized reading' (duration: 129.510953ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:15.53376Z","caller":"traceutil/trace.go:171","msg":"trace[967014487] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"127.656203ms","start":"2026-06-02T07:46:15.406076Z","end":"2026-06-02T07:46:15.533732Z","steps":["trace[967014487] 'process raft request' (duration: 126.783774ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:15.688573Z","caller":"traceutil/trace.go:171","msg":"trace[2017795473] linearizableReadLoop","detail":"{readStateIndex:26505; appliedIndex:26504; }","duration":"150.084845ms","start":"2026-06-02T07:46:15.538455Z","end":"2026-06-02T07:46:15.68854Z","steps":["trace[2017795473] 'read index received' (duration: 136.85373ms)","trace[2017795473] 'applied index is now lower than readState.Index' (duration: 13.229785ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:15.688878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.398971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4fd219df-4fad-4516-a129-989be41f1812\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-06-02T07:46:15.688928Z","caller":"traceutil/trace.go:171","msg":"trace[750254026] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4fd219df-4fad-4516-a129-989be41f1812; range_end:; response_count:1; response_revision:25652; }","duration":"150.490324ms","start":"2026-06-02T07:46:15.538423Z","end":"2026-06-02T07:46:15.688913Z","steps":["trace[750254026] 'agreement among raft nodes before linearized reading' (duration: 150.257199ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:15.688959Z","caller":"traceutil/trace.go:171","msg":"trace[1125863647] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"150.525654ms","start":"2026-06-02T07:46:15.53839Z","end":"2026-06-02T07:46:15.688915Z","steps":["trace[1125863647] 'process raft request' (duration: 136.85034ms)","trace[1125863647] 'compare' (duration: 13.154843ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:15.86771Z","caller":"traceutil/trace.go:171","msg":"trace[189739275] linearizableReadLoop","detail":"{readStateIndex:26509; appliedIndex:26505; }","duration":"154.09549ms","start":"2026-06-02T07:46:15.713598Z","end":"2026-06-02T07:46:15.867694Z","steps":["trace[189739275] 'read index received' (duration: 134.322446ms)","trace[189739275] 'applied index is now lower than readState.Index' (duration: 19.772164ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:15.867755Z","caller":"traceutil/trace.go:171","msg":"trace[1844411979] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"328.401796ms","start":"2026-06-02T07:46:15.539319Z","end":"2026-06-02T07:46:15.867721Z","steps":["trace[1844411979] 'process raft request' (duration: 328.288503ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:15.867857Z","caller":"traceutil/trace.go:171","msg":"trace[2124758189] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"328.484467ms","start":"2026-06-02T07:46:15.539364Z","end":"2026-06-02T07:46:15.867848Z","steps":["trace[2124758189] 'process raft request' (duration: 328.273153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:15.867946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:15.539288Z","time spent":"328.57057ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:46:15.867962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:15.539354Z","time spent":"328.527948ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:15.868069Z","caller":"traceutil/trace.go:171","msg":"trace[1382602771] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"232.292371ms","start":"2026-06-02T07:46:15.635771Z","end":"2026-06-02T07:46:15.868064Z","steps":["trace[1382602771] 'process raft request' (duration: 231.885422ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:15.868095Z","caller":"traceutil/trace.go:171","msg":"trace[1576070948] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"329.146632ms","start":"2026-06-02T07:46:15.538936Z","end":"2026-06-02T07:46:15.868083Z","steps":["trace[1576070948] 'process raft request' (duration: 308.964079ms)","trace[1576070948] 'compare' (duration: 19.596111ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:15.868146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.550731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T07:46:15.868164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:15.538906Z","time spent":"329.220714ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:15.868177Z","caller":"traceutil/trace.go:171","msg":"trace[433894207] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25656; }","duration":"154.629242ms","start":"2026-06-02T07:46:15.71354Z","end":"2026-06-02T07:46:15.86817Z","steps":["trace[433894207] 'agreement among raft nodes before linearized reading' (duration: 154.566381ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.090132Z","caller":"traceutil/trace.go:171","msg":"trace[1652230614] linearizableReadLoop","detail":"{readStateIndex:26510; appliedIndex:26509; }","duration":"222.278325ms","start":"2026-06-02T07:46:15.867822Z","end":"2026-06-02T07:46:16.0901Z","steps":["trace[1652230614] 'read index received' (duration: 203.568874ms)","trace[1652230614] 'applied index is now lower than readState.Index' (duration: 18.708491ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:16.09036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.435643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-06-02T07:46:16.09031Z","caller":"traceutil/trace.go:171","msg":"trace[1794840938] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"361.869894ms","start":"2026-06-02T07:46:15.728382Z","end":"2026-06-02T07:46:16.090252Z","steps":["trace[1794840938] 'process raft request' (duration: 343.01732ms)","trace[1794840938] 'compare' (duration: 18.561968ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:16.090425Z","caller":"traceutil/trace.go:171","msg":"trace[617683369] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:25657; }","duration":"292.566995ms","start":"2026-06-02T07:46:15.797847Z","end":"2026-06-02T07:46:16.090414Z","steps":["trace[617683369] 'agreement among raft nodes before linearized reading' (duration: 292.399151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.090419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.849359ms","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":"info","ts":"2026-06-02T07:46:16.090528Z","caller":"traceutil/trace.go:171","msg":"trace[1409110844] 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:25657; }","duration":"310.986481ms","start":"2026-06-02T07:46:15.77951Z","end":"2026-06-02T07:46:16.090496Z","steps":["trace[1409110844] 'agreement among raft nodes before linearized reading' (duration: 310.777487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.090567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:15.728366Z","time spent":"362.064268ms","remote":"127.0.0.1:47084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:46:16.090581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:15.779425Z","time spent":"311.144435ms","remote":"127.0.0.1:40804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:46:16.103083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.486712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:16.103087Z","caller":"traceutil/trace.go:171","msg":"trace[413758143] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"140.5588ms","start":"2026-06-02T07:46:15.962489Z","end":"2026-06-02T07:46:16.103047Z","steps":["trace[413758143] 'process raft request' (duration: 140.205492ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.103175Z","caller":"traceutil/trace.go:171","msg":"trace[271589071] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25658; }","duration":"230.647925ms","start":"2026-06-02T07:46:15.872508Z","end":"2026-06-02T07:46:16.103156Z","steps":["trace[271589071] 'agreement among raft nodes before linearized reading' (duration: 230.450171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.103231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.161878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-02T07:46:16.103326Z","caller":"traceutil/trace.go:171","msg":"trace[1970136240] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25658; }","duration":"131.297211ms","start":"2026-06-02T07:46:15.972004Z","end":"2026-06-02T07:46:16.103301Z","steps":["trace[1970136240] 'agreement among raft nodes before linearized reading' (duration: 131.109107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.103424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.04059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-06-02T07:46:16.103496Z","caller":"traceutil/trace.go:171","msg":"trace[2000218466] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25658; }","duration":"235.147222ms","start":"2026-06-02T07:46:15.868331Z","end":"2026-06-02T07:46:16.103478Z","steps":["trace[2000218466] 'agreement among raft nodes before linearized reading' (duration: 234.762514ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.269951Z","caller":"traceutil/trace.go:171","msg":"trace[807383768] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"161.429308ms","start":"2026-06-02T07:46:16.108483Z","end":"2026-06-02T07:46:16.269913Z","steps":["trace[807383768] 'process raft request' (duration: 141.226055ms)","trace[807383768] 'compare' (duration: 20.04476ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:16.297359Z","caller":"traceutil/trace.go:171","msg":"trace[638865094] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"187.345035ms","start":"2026-06-02T07:46:16.109969Z","end":"2026-06-02T07:46:16.297314Z","steps":["trace[638865094] 'process raft request' (duration: 186.902526ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.2975Z","caller":"traceutil/trace.go:171","msg":"trace[581563815] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"185.993566ms","start":"2026-06-02T07:46:16.111362Z","end":"2026-06-02T07:46:16.297355Z","steps":["trace[581563815] 'process raft request' (duration: 185.734731ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.298032Z","caller":"traceutil/trace.go:171","msg":"trace[423289949] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"141.700474ms","start":"2026-06-02T07:46:16.156098Z","end":"2026-06-02T07:46:16.297798Z","steps":["trace[423289949] 'process raft request' (duration: 141.134662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.514355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.590244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T07:46:16.514598Z","caller":"traceutil/trace.go:171","msg":"trace[1388263111] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"210.607795ms","start":"2026-06-02T07:46:16.303938Z","end":"2026-06-02T07:46:16.514546Z","steps":["trace[1388263111] 'process raft request' (duration: 67.714285ms)","trace[1388263111] 'compare' (duration: 142.384009ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:16.514608Z","caller":"traceutil/trace.go:171","msg":"trace[817113811] linearizableReadLoop","detail":"{readStateIndex:26517; appliedIndex:26515; }","duration":"153.493228ms","start":"2026-06-02T07:46:16.361098Z","end":"2026-06-02T07:46:16.514591Z","steps":["trace[817113811] 'read index received' (duration: 10.549407ms)","trace[817113811] 'applied index is now lower than readState.Index' (duration: 142.943141ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:16.514733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.647521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:16.514756Z","caller":"traceutil/trace.go:171","msg":"trace[1588402594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25664; }","duration":"153.671391ms","start":"2026-06-02T07:46:16.361077Z","end":"2026-06-02T07:46:16.514749Z","steps":["trace[1588402594] 'agreement among raft nodes before linearized reading' (duration: 153.62177ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.515081Z","caller":"traceutil/trace.go:171","msg":"trace[1652508675] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"209.602154ms","start":"2026-06-02T07:46:16.305419Z","end":"2026-06-02T07:46:16.515021Z","steps":["trace[1652508675] 'process raft request' (duration: 209.039242ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.826199Z","caller":"traceutil/trace.go:171","msg":"trace[1292910825] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"394.096788ms","start":"2026-06-02T07:46:16.432063Z","end":"2026-06-02T07:46:16.82616Z","steps":["trace[1292910825] 'process raft request' (duration: 375.316254ms)","trace[1292910825] 'compare' (duration: 18.344315ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:16.826414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:16.43203Z","time spent":"394.274891ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:16.826492Z","caller":"traceutil/trace.go:171","msg":"trace[200759057] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"384.93275ms","start":"2026-06-02T07:46:16.441516Z","end":"2026-06-02T07:46:16.826449Z","steps":["trace[200759057] 'process raft request' (duration: 384.558832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.826741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:16.441478Z","time spent":"385.138494ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:16.865681Z","caller":"traceutil/trace.go:171","msg":"trace[1966999883] linearizableReadLoop","detail":"{readStateIndex:26520; appliedIndex:26517; }","duration":"349.05557ms","start":"2026-06-02T07:46:16.516592Z","end":"2026-06-02T07:46:16.865647Z","steps":["trace[1966999883] 'read index received' (duration: 290.803878ms)","trace[1966999883] 'applied index is now lower than readState.Index' (duration: 58.250172ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:16.865796Z","caller":"traceutil/trace.go:171","msg":"trace[474056353] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"170.179397ms","start":"2026-06-02T07:46:16.695589Z","end":"2026-06-02T07:46:16.865769Z","steps":["trace[474056353] 'process raft request' (duration: 169.997133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.86588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.260964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:16.865716Z","caller":"traceutil/trace.go:171","msg":"trace[1630780555] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"361.727112ms","start":"2026-06-02T07:46:16.503961Z","end":"2026-06-02T07:46:16.865689Z","steps":["trace[1630780555] 'process raft request' (duration: 361.458846ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.865937Z","caller":"traceutil/trace.go:171","msg":"trace[258222033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25668; }","duration":"349.389897ms","start":"2026-06-02T07:46:16.516532Z","end":"2026-06-02T07:46:16.865922Z","steps":["trace[258222033] 'agreement among raft nodes before linearized reading' (duration: 349.273915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.865983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:16.516509Z","time spent":"349.462428ms","remote":"127.0.0.1:38460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T07:46:16.866089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.481387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T07:46:16.866166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.038852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18b533f5575fff1b\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-06-02T07:46:16.866181Z","caller":"traceutil/trace.go:171","msg":"trace[1285040935] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25668; }","duration":"200.6404ms","start":"2026-06-02T07:46:16.665516Z","end":"2026-06-02T07:46:16.866156Z","steps":["trace[1285040935] 'agreement among raft nodes before linearized reading' (duration: 200.473616ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:16.866215Z","caller":"traceutil/trace.go:171","msg":"trace[1484587756] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18b533f5575fff1b; range_end:; response_count:1; response_revision:25668; }","duration":"344.091053ms","start":"2026-06-02T07:46:16.522108Z","end":"2026-06-02T07:46:16.866199Z","steps":["trace[1484587756] 'agreement among raft nodes before linearized reading' (duration: 343.857768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:16.86625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:16.522097Z","time spent":"344.144735ms","remote":"127.0.0.1:38552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":745,"request content":"key:\"/registry/events/openstack/container-infra-registry.18b533f5575fff1b\" "} {"level":"warn","ts":"2026-06-02T07:46:16.866029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:16.503918Z","time spent":"362.030319ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:17.010887Z","caller":"traceutil/trace.go:171","msg":"trace[1671119774] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"142.061773ms","start":"2026-06-02T07:46:16.8688Z","end":"2026-06-02T07:46:17.010862Z","steps":["trace[1671119774] 'process raft request' (duration: 126.058678ms)","trace[1671119774] 'compare' (duration: 15.475063ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:17.462588Z","caller":"traceutil/trace.go:171","msg":"trace[74468324] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"458.972831ms","start":"2026-06-02T07:46:17.003591Z","end":"2026-06-02T07:46:17.462564Z","steps":["trace[74468324] 'process raft request' (duration: 458.784707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:17.462762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:17.003567Z","time spent":"459.090264ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:46:17.609836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:17.012773Z","time spent":"597.053218ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T07:46:17.609844Z","caller":"traceutil/trace.go:171","msg":"trace[386356540] linearizableReadLoop","detail":"{readStateIndex:26524; appliedIndex:26522; }","duration":"574.477843ms","start":"2026-06-02T07:46:17.035323Z","end":"2026-06-02T07:46:17.609801Z","steps":["trace[386356540] 'read index received' (duration: 427.161827ms)","trace[386356540] 'applied index is now lower than readState.Index' (duration: 147.313446ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:17.610134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.782719ms","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-06-02T07:46:17.61021Z","caller":"traceutil/trace.go:171","msg":"trace[2121655721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25670; }","duration":"574.876631ms","start":"2026-06-02T07:46:17.035316Z","end":"2026-06-02T07:46:17.610192Z","steps":["trace[2121655721] 'agreement among raft nodes before linearized reading' (duration: 574.589195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:17.610264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:17.035289Z","time spent":"574.963034ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T07:46:17.610472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.467928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:17.610597Z","caller":"traceutil/trace.go:171","msg":"trace[854333814] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25670; }","duration":"281.649701ms","start":"2026-06-02T07:46:17.328926Z","end":"2026-06-02T07:46:17.610576Z","steps":["trace[854333814] 'agreement among raft nodes before linearized reading' (duration: 281.499888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:17.610716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.821947ms","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-06-02T07:46:17.610824Z","caller":"traceutil/trace.go:171","msg":"trace[707706770] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25670; }","duration":"248.98191ms","start":"2026-06-02T07:46:17.361798Z","end":"2026-06-02T07:46:17.61078Z","steps":["trace[707706770] 'agreement among raft nodes before linearized reading' (duration: 248.671823ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:23.06324Z","caller":"traceutil/trace.go:171","msg":"trace[1772848550] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"211.633436ms","start":"2026-06-02T07:46:22.851579Z","end":"2026-06-02T07:46:23.063212Z","steps":["trace[1772848550] 'process raft request' (duration: 211.431992ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:23.680423Z","caller":"traceutil/trace.go:171","msg":"trace[1535813156] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"825.62868ms","start":"2026-06-02T07:46:22.854742Z","end":"2026-06-02T07:46:23.680371Z","steps":["trace[1535813156] 'process raft request' (duration: 782.474322ms)","trace[1535813156] 'compare' (duration: 42.78483ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:23.68066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:22.85471Z","time spent":"825.826783ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:23.681967Z","caller":"traceutil/trace.go:171","msg":"trace[233620833] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"795.508902ms","start":"2026-06-02T07:46:22.886437Z","end":"2026-06-02T07:46:23.681946Z","steps":["trace[233620833] 'process raft request' (duration: 793.897488ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:23.681958Z","caller":"traceutil/trace.go:171","msg":"trace[1413538632] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"198.496244ms","start":"2026-06-02T07:46:23.483426Z","end":"2026-06-02T07:46:23.681922Z","steps":["trace[1413538632] 'process raft request' (duration: 198.340801ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:23.681994Z","caller":"traceutil/trace.go:171","msg":"trace[567922977] linearizableReadLoop","detail":"{readStateIndex:26568; appliedIndex:26565; }","duration":"721.228215ms","start":"2026-06-02T07:46:22.960735Z","end":"2026-06-02T07:46:23.681963Z","steps":["trace[567922977] 'read index received' (duration: 102.261927ms)","trace[567922977] 'applied index is now lower than readState.Index' (duration: 618.963258ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:23.682071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:22.886403Z","time spent":"795.617345ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:46:23.682245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.067201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:23.682291Z","caller":"traceutil/trace.go:171","msg":"trace[916032679] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25714; }","duration":"539.189504ms","start":"2026-06-02T07:46:23.143087Z","end":"2026-06-02T07:46:23.682276Z","steps":["trace[916032679] 'agreement among raft nodes before linearized reading' (duration: 539.040271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:23.682289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.527082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T07:46:23.682386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.747164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T07:46:23.682401Z","caller":"traceutil/trace.go:171","msg":"trace[2000013502] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25714; }","duration":"721.706466ms","start":"2026-06-02T07:46:22.96067Z","end":"2026-06-02T07:46:23.682376Z","steps":["trace[2000013502] 'agreement among raft nodes before linearized reading' (duration: 721.43014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:23.682401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.191639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:23.682439Z","caller":"traceutil/trace.go:171","msg":"trace[1279450738] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25714; }","duration":"140.847896ms","start":"2026-06-02T07:46:23.541577Z","end":"2026-06-02T07:46:23.682425Z","steps":["trace[1279450738] 'agreement among raft nodes before linearized reading' (duration: 140.747364ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:23.682455Z","caller":"traceutil/trace.go:171","msg":"trace[1704765798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25714; }","duration":"320.312731ms","start":"2026-06-02T07:46:23.362129Z","end":"2026-06-02T07:46:23.682442Z","steps":["trace[1704765798] 'agreement among raft nodes before linearized reading' (duration: 320.198869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:23.682333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:23.14306Z","time spent":"539.262476ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:46:23.682501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:23.362107Z","time spent":"320.386994ms","remote":"127.0.0.1:38460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T07:46:23.682459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:22.960645Z","time spent":"721.802598ms","remote":"127.0.0.1:56046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:46:23.916249Z","caller":"traceutil/trace.go:171","msg":"trace[999603437] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"229.677065ms","start":"2026-06-02T07:46:23.686535Z","end":"2026-06-02T07:46:23.916212Z","steps":["trace[999603437] 'process raft request' (duration: 141.160292ms)","trace[999603437] 'compare' (duration: 87.845878ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:23.916397Z","caller":"traceutil/trace.go:171","msg":"trace[829171054] linearizableReadLoop","detail":"{readStateIndex:26570; appliedIndex:26569; }","duration":"176.38511ms","start":"2026-06-02T07:46:23.739972Z","end":"2026-06-02T07:46:23.916357Z","steps":["trace[829171054] 'read index received' (duration: 87.671813ms)","trace[829171054] 'applied index is now lower than readState.Index' (duration: 88.706716ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:23.916598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.594375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T07:46:23.916709Z","caller":"traceutil/trace.go:171","msg":"trace[465599123] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25716; }","duration":"176.729637ms","start":"2026-06-02T07:46:23.739951Z","end":"2026-06-02T07:46:23.91668Z","steps":["trace[465599123] 'agreement among raft nodes before linearized reading' (duration: 176.535003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:23.916831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.78364ms","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-06-02T07:46:23.916983Z","caller":"traceutil/trace.go:171","msg":"trace[1707008310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25716; }","duration":"157.982794ms","start":"2026-06-02T07:46:23.758944Z","end":"2026-06-02T07:46:23.916927Z","steps":["trace[1707008310] 'agreement among raft nodes before linearized reading' (duration: 157.623836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:24.453324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.064844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T07:46:24.453429Z","caller":"traceutil/trace.go:171","msg":"trace[695349067] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"531.813676ms","start":"2026-06-02T07:46:23.921598Z","end":"2026-06-02T07:46:24.453412Z","steps":["trace[695349067] 'process raft request' (duration: 267.592599ms)","trace[695349067] 'compare' (duration: 263.679775ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:24.453476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:23.921567Z","time spent":"531.885667ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:24.454572Z","caller":"traceutil/trace.go:171","msg":"trace[1681028012] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"531.484019ms","start":"2026-06-02T07:46:23.923054Z","end":"2026-06-02T07:46:24.454538Z","steps":["trace[1681028012] 'process raft request' (duration: 531.164723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:24.454776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:23.923022Z","time spent":"531.639073ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:24.455013Z","caller":"traceutil/trace.go:171","msg":"trace[582353190] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"531.163361ms","start":"2026-06-02T07:46:23.923809Z","end":"2026-06-02T07:46:24.454972Z","steps":["trace[582353190] 'process raft request' (duration: 530.646061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:24.455222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:23.923794Z","time spent":"531.320234ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:24.455454Z","caller":"traceutil/trace.go:171","msg":"trace[1468109882] linearizableReadLoop","detail":"{readStateIndex:26575; appliedIndex:26571; }","duration":"508.254059ms","start":"2026-06-02T07:46:23.947185Z","end":"2026-06-02T07:46:24.455439Z","steps":["trace[1468109882] 'read index received' (duration: 242.012939ms)","trace[1468109882] 'applied index is now lower than readState.Index' (duration: 266.23982ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:46:24.455572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.388912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:46:24.45561Z","caller":"traceutil/trace.go:171","msg":"trace[674009409] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25721; }","duration":"508.474144ms","start":"2026-06-02T07:46:23.947123Z","end":"2026-06-02T07:46:24.455597Z","steps":["trace[674009409] 'agreement among raft nodes before linearized reading' (duration: 508.399052ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:24.455573Z","caller":"traceutil/trace.go:171","msg":"trace[2048949892] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"304.830948ms","start":"2026-06-02T07:46:24.1507Z","end":"2026-06-02T07:46:24.455531Z","steps":["trace[2048949892] 'process raft request' (duration: 304.657125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:24.455646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:23.947098Z","time spent":"508.540285ms","remote":"127.0.0.1:33942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:46:24.455792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:24.150667Z","time spent":"304.993932ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:46:24.455845Z","caller":"traceutil/trace.go:171","msg":"trace[2120329] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"131.651418ms","start":"2026-06-02T07:46:24.324176Z","end":"2026-06-02T07:46:24.455827Z","steps":["trace[2120329] 'process raft request' (duration: 131.437873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:24.455869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.754577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-02T07:46:24.455931Z","caller":"traceutil/trace.go:171","msg":"trace[1938043954] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25722; }","duration":"135.873159ms","start":"2026-06-02T07:46:24.320043Z","end":"2026-06-02T07:46:24.455916Z","steps":["trace[1938043954] 'agreement among raft nodes before linearized reading' (duration: 135.697165ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:24.455978Z","caller":"traceutil/trace.go:171","msg":"trace[941075713] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"531.647883ms","start":"2026-06-02T07:46:23.92429Z","end":"2026-06-02T07:46:24.455938Z","steps":["trace[941075713] 'process raft request' (duration: 530.567809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:24.456225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:46:23.92428Z","time spent":"531.811306ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:46:33.455991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.439376ms","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-06-02T07:46:33.456132Z","caller":"traceutil/trace.go:171","msg":"trace[914572610] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25844; }","duration":"112.65465ms","start":"2026-06-02T07:46:33.343448Z","end":"2026-06-02T07:46:33.456103Z","steps":["trace[914572610] 'count revisions from in-memory index tree' (duration: 112.286462ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:33.594501Z","caller":"traceutil/trace.go:171","msg":"trace[125301794] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"131.808292ms","start":"2026-06-02T07:46:33.462658Z","end":"2026-06-02T07:46:33.594466Z","steps":["trace[125301794] 'process raft request' (duration: 131.598348ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:33.844925Z","caller":"traceutil/trace.go:171","msg":"trace[1935630586] transaction","detail":"{read_only:false; response_revision:25848; number_of_response:1; }","duration":"118.334992ms","start":"2026-06-02T07:46:33.726562Z","end":"2026-06-02T07:46:33.844897Z","steps":["trace[1935630586] 'process raft request' (duration: 118.279561ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:33.845203Z","caller":"traceutil/trace.go:171","msg":"trace[658203206] transaction","detail":"{read_only:false; response_revision:25847; number_of_response:1; }","duration":"121.623262ms","start":"2026-06-02T07:46:33.723541Z","end":"2026-06-02T07:46:33.845164Z","steps":["trace[658203206] 'process raft request' (duration: 121.094731ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:34.110504Z","caller":"traceutil/trace.go:171","msg":"trace[217087343] transaction","detail":"{read_only:false; response_revision:25849; number_of_response:1; }","duration":"136.087315ms","start":"2026-06-02T07:46:33.974386Z","end":"2026-06-02T07:46:34.110473Z","steps":["trace[217087343] 'process raft request' (duration: 135.901741ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:34.114122Z","caller":"traceutil/trace.go:171","msg":"trace[1327272242] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"136.210576ms","start":"2026-06-02T07:46:33.977878Z","end":"2026-06-02T07:46:34.114088Z","steps":["trace[1327272242] 'process raft request' (duration: 136.026552ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:34.652179Z","caller":"traceutil/trace.go:171","msg":"trace[773423793] linearizableReadLoop","detail":"{readStateIndex:26709; appliedIndex:26708; }","duration":"144.280479ms","start":"2026-06-02T07:46:34.507866Z","end":"2026-06-02T07:46:34.652147Z","steps":["trace[773423793] 'read index received' (duration: 45.313043ms)","trace[773423793] 'applied index is now lower than readState.Index' (duration: 98.966066ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:34.65229Z","caller":"traceutil/trace.go:171","msg":"trace[474294699] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"149.066472ms","start":"2026-06-02T07:46:34.503159Z","end":"2026-06-02T07:46:34.652225Z","steps":["trace[474294699] 'process raft request' (duration: 146.805103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:34.652424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.531924ms","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-06-02T07:46:34.65255Z","caller":"traceutil/trace.go:171","msg":"trace[1431179954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25852; }","duration":"144.722318ms","start":"2026-06-02T07:46:34.507799Z","end":"2026-06-02T07:46:34.652522Z","steps":["trace[1431179954] 'agreement among raft nodes before linearized reading' (duration: 144.451063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:34.652592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.116209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-02T07:46:34.652654Z","caller":"traceutil/trace.go:171","msg":"trace[504225844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25852; }","duration":"139.19081ms","start":"2026-06-02T07:46:34.513452Z","end":"2026-06-02T07:46:34.652643Z","steps":["trace[504225844] 'agreement among raft nodes before linearized reading' (duration: 139.061507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:34.652681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.261295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T07:46:34.652726Z","caller":"traceutil/trace.go:171","msg":"trace[1981947337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25852; }","duration":"141.388227ms","start":"2026-06-02T07:46:34.511327Z","end":"2026-06-02T07:46:34.652715Z","steps":["trace[1981947337] 'agreement among raft nodes before linearized reading' (duration: 141.158413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:34.652763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.136033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-02T07:46:34.652827Z","caller":"traceutil/trace.go:171","msg":"trace[21433149] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25852; }","duration":"142.229877ms","start":"2026-06-02T07:46:34.510583Z","end":"2026-06-02T07:46:34.652813Z","steps":["trace[21433149] 'agreement among raft nodes before linearized reading' (duration: 141.98647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:34.652982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.482001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-02T07:46:34.653075Z","caller":"traceutil/trace.go:171","msg":"trace[1702289385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25852; }","duration":"142.630145ms","start":"2026-06-02T07:46:34.510425Z","end":"2026-06-02T07:46:34.653055Z","steps":["trace[1702289385] 'agreement among raft nodes before linearized reading' (duration: 141.97443ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:36.727239Z","caller":"traceutil/trace.go:171","msg":"trace[602559530] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"168.723365ms","start":"2026-06-02T07:46:36.558478Z","end":"2026-06-02T07:46:36.727201Z","steps":["trace[602559530] 'process raft request' (duration: 168.52061ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:36.76695Z","caller":"traceutil/trace.go:171","msg":"trace[323051800] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"108.165203ms","start":"2026-06-02T07:46:36.658732Z","end":"2026-06-02T07:46:36.766897Z","steps":["trace[323051800] 'process raft request' (duration: 107.847337ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:36.920601Z","caller":"traceutil/trace.go:171","msg":"trace[397990105] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"147.832716ms","start":"2026-06-02T07:46:36.772729Z","end":"2026-06-02T07:46:36.920562Z","steps":["trace[397990105] 'process raft request' (duration: 99.840475ms)","trace[397990105] 'compare' (duration: 47.552772ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:36.928471Z","caller":"traceutil/trace.go:171","msg":"trace[1684304908] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"155.065503ms","start":"2026-06-02T07:46:36.773369Z","end":"2026-06-02T07:46:36.928434Z","steps":["trace[1684304908] 'process raft request' (duration: 154.96912ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:36.928561Z","caller":"traceutil/trace.go:171","msg":"trace[810727469] transaction","detail":"{read_only:false; response_revision:25869; number_of_response:1; }","duration":"155.40949ms","start":"2026-06-02T07:46:36.773098Z","end":"2026-06-02T07:46:36.928507Z","steps":["trace[810727469] 'process raft request' (duration: 155.063533ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:36.929094Z","caller":"traceutil/trace.go:171","msg":"trace[1615076042] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"155.543452ms","start":"2026-06-02T07:46:36.773506Z","end":"2026-06-02T07:46:36.929049Z","steps":["trace[1615076042] 'process raft request' (duration: 154.881768ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:36.929269Z","caller":"traceutil/trace.go:171","msg":"trace[1303550039] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"155.268216ms","start":"2026-06-02T07:46:36.773984Z","end":"2026-06-02T07:46:36.929252Z","steps":["trace[1303550039] 'process raft request' (duration: 154.954229ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:40.779097Z","caller":"traceutil/trace.go:171","msg":"trace[120536273] linearizableReadLoop","detail":"{readStateIndex:26771; appliedIndex:26770; }","duration":"173.650651ms","start":"2026-06-02T07:46:40.605415Z","end":"2026-06-02T07:46:40.779066Z","steps":["trace[120536273] 'read index received' (duration: 173.435896ms)","trace[120536273] 'applied index is now lower than readState.Index' (duration: 213.245µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:40.779192Z","caller":"traceutil/trace.go:171","msg":"trace[2106307749] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"237.040752ms","start":"2026-06-02T07:46:40.542104Z","end":"2026-06-02T07:46:40.779144Z","steps":["trace[2106307749] 'process raft request' (duration: 236.770616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:40.779326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.894026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-02T07:46:40.77938Z","caller":"traceutil/trace.go:171","msg":"trace[1010766482] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25912; }","duration":"173.968838ms","start":"2026-06-02T07:46:40.605397Z","end":"2026-06-02T07:46:40.779366Z","steps":["trace[1010766482] 'agreement among raft nodes before linearized reading' (duration: 173.779674ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:40.875272Z","caller":"traceutil/trace.go:171","msg":"trace[379636072] transaction","detail":"{read_only:false; response_revision:25913; number_of_response:1; }","duration":"135.308898ms","start":"2026-06-02T07:46:40.739929Z","end":"2026-06-02T07:46:40.875238Z","steps":["trace[379636072] 'process raft request' (duration: 133.362725ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:42.823372Z","caller":"traceutil/trace.go:171","msg":"trace[2001008204] linearizableReadLoop","detail":"{readStateIndex:26796; appliedIndex:26795; }","duration":"194.938378ms","start":"2026-06-02T07:46:42.628408Z","end":"2026-06-02T07:46:42.823347Z","steps":["trace[2001008204] 'read index received' (duration: 194.626851ms)","trace[2001008204] 'applied index is now lower than readState.Index' (duration: 310.227µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:46:42.823512Z","caller":"traceutil/trace.go:171","msg":"trace[655235204] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"240.032186ms","start":"2026-06-02T07:46:42.583471Z","end":"2026-06-02T07:46:42.823503Z","steps":["trace[655235204] 'process raft request' (duration: 239.672308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:46:42.824315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.874797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-02T07:46:42.824418Z","caller":"traceutil/trace.go:171","msg":"trace[1613716112] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25936; }","duration":"196.028112ms","start":"2026-06-02T07:46:42.628359Z","end":"2026-06-02T07:46:42.824387Z","steps":["trace[1613716112] 'agreement among raft nodes before linearized reading' (duration: 195.754505ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:46:42.958037Z","caller":"traceutil/trace.go:171","msg":"trace[1996681436] transaction","detail":"{read_only:false; response_revision:25937; number_of_response:1; }","duration":"106.298633ms","start":"2026-06-02T07:46:42.851692Z","end":"2026-06-02T07:46:42.957991Z","steps":["trace[1996681436] 'process raft request' (duration: 105.988477ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:05.304461Z","caller":"traceutil/trace.go:171","msg":"trace[610716460] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"121.898158ms","start":"2026-06-02T07:47:05.182498Z","end":"2026-06-02T07:47:05.304396Z","steps":["trace[610716460] 'process raft request' (duration: 114.001139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:05.57767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.483126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T07:47:05.578035Z","caller":"traceutil/trace.go:171","msg":"trace[1777871865] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"394.898454ms","start":"2026-06-02T07:47:05.183102Z","end":"2026-06-02T07:47:05.578Z","steps":["trace[1777871865] 'process raft request' (duration: 275.989349ms)","trace[1777871865] 'compare' (duration: 118.020417ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:47:05.578215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:05.183068Z","time spent":"395.052438ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:47:05.578623Z","caller":"traceutil/trace.go:171","msg":"trace[1021792254] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"395.459387ms","start":"2026-06-02T07:47:05.183132Z","end":"2026-06-02T07:47:05.578592Z","steps":["trace[1021792254] 'process raft request' (duration: 394.762942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:05.57876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:05.183098Z","time spent":"395.598489ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:47:05.578919Z","caller":"traceutil/trace.go:171","msg":"trace[1528223371] linearizableReadLoop","detail":"{readStateIndex:26984; appliedIndex:26979; }","duration":"377.798887ms","start":"2026-06-02T07:47:05.200995Z","end":"2026-06-02T07:47:05.578794Z","steps":["trace[1528223371] 'read index received' (duration: 95.519822ms)","trace[1528223371] 'applied index is now lower than readState.Index' (duration: 282.277635ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:05.578968Z","caller":"traceutil/trace.go:171","msg":"trace[1599737914] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"395.524517ms","start":"2026-06-02T07:47:05.183282Z","end":"2026-06-02T07:47:05.578807Z","steps":["trace[1599737914] 'process raft request' (duration: 395.17475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:05.579151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:05.183236Z","time spent":"395.873615ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:47:05.578997Z","caller":"traceutil/trace.go:171","msg":"trace[180928544] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"395.111529ms","start":"2026-06-02T07:47:05.183711Z","end":"2026-06-02T07:47:05.578822Z","steps":["trace[180928544] 'process raft request' (duration: 394.926655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:05.579525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:05.183658Z","time spent":"395.685592ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:47:05.578981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.91481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:47:05.579893Z","caller":"traceutil/trace.go:171","msg":"trace[346423877] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:26119; }","duration":"378.822149ms","start":"2026-06-02T07:47:05.200951Z","end":"2026-06-02T07:47:05.579773Z","steps":["trace[346423877] 'agreement among raft nodes before linearized reading' (duration: 377.898699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:05.580132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:05.200935Z","time spent":"379.064085ms","remote":"127.0.0.1:48676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:47:05.669485Z","caller":"traceutil/trace.go:171","msg":"trace[620760964] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"350.643243ms","start":"2026-06-02T07:47:05.318805Z","end":"2026-06-02T07:47:05.669448Z","steps":["trace[620760964] 'process raft request' (duration: 350.454599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:05.669735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:05.318781Z","time spent":"350.814858ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:47:05.877379Z","caller":"traceutil/trace.go:171","msg":"trace[632831027] linearizableReadLoop","detail":"{readStateIndex:26986; appliedIndex:26985; }","duration":"114.068601ms","start":"2026-06-02T07:47:05.763279Z","end":"2026-06-02T07:47:05.877347Z","steps":["trace[632831027] 'read index received' (duration: 46.356326ms)","trace[632831027] 'applied index is now lower than readState.Index' (duration: 67.711065ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:05.877467Z","caller":"traceutil/trace.go:171","msg":"trace[15511839] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"397.083122ms","start":"2026-06-02T07:47:05.480355Z","end":"2026-06-02T07:47:05.877438Z","steps":["trace[15511839] 'process raft request' (duration: 329.360777ms)","trace[15511839] 'compare' (duration: 67.458479ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:47:05.877595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.290685ms","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":"warn","ts":"2026-06-02T07:47:05.877641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:05.480323Z","time spent":"397.188424ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:47:05.8777Z","caller":"traceutil/trace.go:171","msg":"trace[258078087] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26121; }","duration":"114.454629ms","start":"2026-06-02T07:47:05.763224Z","end":"2026-06-02T07:47:05.877678Z","steps":["trace[258078087] 'agreement among raft nodes before linearized reading' (duration: 114.282335ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:06.160738Z","caller":"traceutil/trace.go:171","msg":"trace[312587935] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"163.222917ms","start":"2026-06-02T07:47:05.997485Z","end":"2026-06-02T07:47:06.160708Z","steps":["trace[312587935] 'process raft request' (duration: 116.803079ms)","trace[312587935] 'compare' (duration: 46.239964ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:07.25831Z","caller":"traceutil/trace.go:171","msg":"trace[938022549] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"231.040455ms","start":"2026-06-02T07:47:07.027203Z","end":"2026-06-02T07:47:07.258244Z","steps":["trace[938022549] 'process raft request' (duration: 141.331747ms)","trace[938022549] 'compare' (duration: 89.35272ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:07.258611Z","caller":"traceutil/trace.go:171","msg":"trace[179467233] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"118.66466ms","start":"2026-06-02T07:47:07.139903Z","end":"2026-06-02T07:47:07.258568Z","steps":["trace[179467233] 'process raft request' (duration: 118.321283ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:07.258344Z","caller":"traceutil/trace.go:171","msg":"trace[350453000] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"220.250352ms","start":"2026-06-02T07:47:07.03806Z","end":"2026-06-02T07:47:07.25831Z","steps":["trace[350453000] 'process raft request' (duration: 220.074939ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:07.348459Z","caller":"traceutil/trace.go:171","msg":"trace[880190470] linearizableReadLoop","detail":"{readStateIndex:26996; appliedIndex:26992; }","duration":"101.864939ms","start":"2026-06-02T07:47:07.246561Z","end":"2026-06-02T07:47:07.348426Z","steps":["trace[880190470] 'read index received' (duration: 6.045051ms)","trace[880190470] 'applied index is now lower than readState.Index' (duration: 95.817848ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:47:07.348919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.320009ms","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-06-02T07:47:07.348926Z","caller":"traceutil/trace.go:171","msg":"trace[983926962] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"164.320721ms","start":"2026-06-02T07:47:07.184578Z","end":"2026-06-02T07:47:07.348899Z","steps":["trace[983926962] 'process raft request' (duration: 163.651777ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:07.348973Z","caller":"traceutil/trace.go:171","msg":"trace[2036920943] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26130; }","duration":"102.437571ms","start":"2026-06-02T07:47:07.24652Z","end":"2026-06-02T07:47:07.348958Z","steps":["trace[2036920943] 'agreement among raft nodes before linearized reading' (duration: 102.305899ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:07.784233Z","caller":"traceutil/trace.go:171","msg":"trace[420300924] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"126.783353ms","start":"2026-06-02T07:47:07.657415Z","end":"2026-06-02T07:47:07.784198Z","steps":["trace[420300924] 'process raft request' (duration: 126.500407ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:08.00693Z","caller":"traceutil/trace.go:171","msg":"trace[301478525] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"348.534409ms","start":"2026-06-02T07:47:07.658364Z","end":"2026-06-02T07:47:08.006899Z","steps":["trace[301478525] 'process raft request' (duration: 288.924318ms)","trace[301478525] 'compare' (duration: 59.303424ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:47:08.007266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:07.658337Z","time spent":"348.793405ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:47:08.007315Z","caller":"traceutil/trace.go:171","msg":"trace[1073925449] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"348.781554ms","start":"2026-06-02T07:47:07.658467Z","end":"2026-06-02T07:47:08.007249Z","steps":["trace[1073925449] 'process raft request' (duration: 348.352865ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:08.007407Z","caller":"traceutil/trace.go:171","msg":"trace[1292678751] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"347.121438ms","start":"2026-06-02T07:47:07.660248Z","end":"2026-06-02T07:47:08.00737Z","steps":["trace[1292678751] 'process raft request' (duration: 346.869432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:08.007528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:07.65845Z","time spent":"348.971728ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:47:08.007621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:07.660217Z","time spent":"347.299192ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:47:08.007737Z","caller":"traceutil/trace.go:171","msg":"trace[1817105094] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"333.079656ms","start":"2026-06-02T07:47:07.674597Z","end":"2026-06-02T07:47:08.007676Z","steps":["trace[1817105094] 'process raft request' (duration: 332.678668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:08.007938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:47:07.67457Z","time spent":"333.26209ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:47:13.528521Z","caller":"traceutil/trace.go:171","msg":"trace[793939029] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"156.400251ms","start":"2026-06-02T07:47:13.37207Z","end":"2026-06-02T07:47:13.528471Z","steps":["trace[793939029] 'process raft request' (duration: 112.484437ms)","trace[793939029] 'compare' (duration: 43.490755ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:13.529019Z","caller":"traceutil/trace.go:171","msg":"trace[722387345] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"143.068854ms","start":"2026-06-02T07:47:13.385938Z","end":"2026-06-02T07:47:13.529007Z","steps":["trace[722387345] 'process raft request' (duration: 142.730266ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:19.44133Z","caller":"traceutil/trace.go:171","msg":"trace[1206780228] linearizableReadLoop","detail":"{readStateIndex:27090; appliedIndex:27089; }","duration":"104.512626ms","start":"2026-06-02T07:47:19.336783Z","end":"2026-06-02T07:47:19.441296Z","steps":["trace[1206780228] 'read index received' (duration: 104.261709ms)","trace[1206780228] 'applied index is now lower than readState.Index' (duration: 249.577µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:19.441384Z","caller":"traceutil/trace.go:171","msg":"trace[137935404] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"139.516218ms","start":"2026-06-02T07:47:19.301822Z","end":"2026-06-02T07:47:19.441338Z","steps":["trace[137935404] 'process raft request' (duration: 139.230401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:19.441655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.861694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-96cgs\" ","response":"range_response_count:1 size:3716"} {"level":"info","ts":"2026-06-02T07:47:19.441704Z","caller":"traceutil/trace.go:171","msg":"trace[1219893392] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-96cgs; range_end:; response_count:1; response_revision:26222; }","duration":"104.981336ms","start":"2026-06-02T07:47:19.33671Z","end":"2026-06-02T07:47:19.441691Z","steps":["trace[1219893392] 'agreement among raft nodes before linearized reading' (duration: 104.769542ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:19.631687Z","caller":"traceutil/trace.go:171","msg":"trace[1907875365] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"151.806613ms","start":"2026-06-02T07:47:19.479835Z","end":"2026-06-02T07:47:19.631641Z","steps":["trace[1907875365] 'process raft request' (duration: 141.394328ms)","trace[1907875365] 'compare' (duration: 10.179919ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:19.728575Z","caller":"traceutil/trace.go:171","msg":"trace[675866984] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"248.1233ms","start":"2026-06-02T07:47:19.480425Z","end":"2026-06-02T07:47:19.728548Z","steps":["trace[675866984] 'process raft request' (duration: 247.989217ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:19.728609Z","caller":"traceutil/trace.go:171","msg":"trace[1736734425] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"248.382977ms","start":"2026-06-02T07:47:19.480148Z","end":"2026-06-02T07:47:19.728531Z","steps":["trace[1736734425] 'process raft request' (duration: 248.08225ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:19.728771Z","caller":"traceutil/trace.go:171","msg":"trace[1703046177] linearizableReadLoop","detail":"{readStateIndex:27095; appliedIndex:27091; }","duration":"164.798521ms","start":"2026-06-02T07:47:19.563928Z","end":"2026-06-02T07:47:19.728727Z","steps":["trace[1703046177] 'read index received' (duration: 57.22858ms)","trace[1703046177] 'applied index is now lower than readState.Index' (duration: 107.560261ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:19.728828Z","caller":"traceutil/trace.go:171","msg":"trace[177739263] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"176.374619ms","start":"2026-06-02T07:47:19.55242Z","end":"2026-06-02T07:47:19.728795Z","steps":["trace[177739263] 'process raft request' (duration: 176.049923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:19.72907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.112807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-02T07:47:19.729186Z","caller":"traceutil/trace.go:171","msg":"trace[960839262] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26227; }","duration":"165.24324ms","start":"2026-06-02T07:47:19.563867Z","end":"2026-06-02T07:47:19.72911Z","steps":["trace[960839262] 'agreement among raft nodes before linearized reading' (duration: 164.993945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:19.93652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.127862ms","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-06-02T07:47:19.936695Z","caller":"traceutil/trace.go:171","msg":"trace[424555493] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26227; }","duration":"202.320506ms","start":"2026-06-02T07:47:19.734351Z","end":"2026-06-02T07:47:19.936671Z","steps":["trace[424555493] 'range keys from in-memory index tree' (duration: 202.107652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:19.936899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.343667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T07:47:19.937248Z","caller":"traceutil/trace.go:171","msg":"trace[204086316] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"202.465221ms","start":"2026-06-02T07:47:19.734748Z","end":"2026-06-02T07:47:19.937213Z","steps":["trace[204086316] 'process raft request' (duration: 93.698634ms)","trace[204086316] 'compare' (duration: 108.193564ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:19.93745Z","caller":"traceutil/trace.go:171","msg":"trace[210561064] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"201.009639ms","start":"2026-06-02T07:47:19.736404Z","end":"2026-06-02T07:47:19.937413Z","steps":["trace[210561064] 'process raft request' (duration: 200.642581ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:29.607258Z","caller":"traceutil/trace.go:171","msg":"trace[1907235776] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"127.880497ms","start":"2026-06-02T07:47:29.479341Z","end":"2026-06-02T07:47:29.607221Z","steps":["trace[1907235776] 'process raft request' (duration: 127.643532ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.280425Z","caller":"traceutil/trace.go:171","msg":"trace[351359827] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"196.930161ms","start":"2026-06-02T07:47:30.083464Z","end":"2026-06-02T07:47:30.280394Z","steps":["trace[351359827] 'process raft request' (duration: 97.185658ms)","trace[351359827] 'compare' (duration: 99.12526ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:30.28041Z","caller":"traceutil/trace.go:171","msg":"trace[1298335357] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"109.829679ms","start":"2026-06-02T07:47:30.170514Z","end":"2026-06-02T07:47:30.280344Z","steps":["trace[1298335357] 'process raft request' (duration: 109.482102ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.280478Z","caller":"traceutil/trace.go:171","msg":"trace[2073603684] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"109.778338ms","start":"2026-06-02T07:47:30.170656Z","end":"2026-06-02T07:47:30.280435Z","steps":["trace[2073603684] 'process raft request' (duration: 109.419061ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.472637Z","caller":"traceutil/trace.go:171","msg":"trace[1145652529] linearizableReadLoop","detail":"{readStateIndex:27176; appliedIndex:27175; }","duration":"192.447344ms","start":"2026-06-02T07:47:30.280135Z","end":"2026-06-02T07:47:30.472583Z","steps":["trace[1145652529] 'read index received' (duration: 124.245799ms)","trace[1145652529] 'applied index is now lower than readState.Index' (duration: 68.200185ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:30.473097Z","caller":"traceutil/trace.go:171","msg":"trace[1089592899] transaction","detail":"{read_only:false; response_revision:26306; number_of_response:1; }","duration":"194.286635ms","start":"2026-06-02T07:47:30.278769Z","end":"2026-06-02T07:47:30.473055Z","steps":["trace[1089592899] 'process raft request' (duration: 125.512007ms)","trace[1089592899] 'compare' (duration: 68.118013ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:47:30.473168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.585104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-06-02T07:47:30.473115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.661296ms","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-06-02T07:47:30.473263Z","caller":"traceutil/trace.go:171","msg":"trace[1286793271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26306; }","duration":"290.739817ms","start":"2026-06-02T07:47:30.182501Z","end":"2026-06-02T07:47:30.473241Z","steps":["trace[1286793271] 'agreement among raft nodes before linearized reading' (duration: 290.42552ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.473291Z","caller":"traceutil/trace.go:171","msg":"trace[1811122357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26306; }","duration":"283.846049ms","start":"2026-06-02T07:47:30.189414Z","end":"2026-06-02T07:47:30.47326Z","steps":["trace[1811122357] 'agreement among raft nodes before linearized reading' (duration: 283.468781ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.495004Z","caller":"traceutil/trace.go:171","msg":"trace[1039602784] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"178.005263ms","start":"2026-06-02T07:47:30.316976Z","end":"2026-06-02T07:47:30.494981Z","steps":["trace[1039602784] 'process raft request' (duration: 177.932332ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.49502Z","caller":"traceutil/trace.go:171","msg":"trace[2007134726] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"208.344815ms","start":"2026-06-02T07:47:30.286648Z","end":"2026-06-02T07:47:30.494993Z","steps":["trace[2007134726] 'process raft request' (duration: 208.10528ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.666152Z","caller":"traceutil/trace.go:171","msg":"trace[71718060] linearizableReadLoop","detail":"{readStateIndex:27179; appliedIndex:27177; }","duration":"193.379125ms","start":"2026-06-02T07:47:30.47274Z","end":"2026-06-02T07:47:30.666119Z","steps":["trace[71718060] 'read index received' (duration: 22.202347ms)","trace[71718060] 'applied index is now lower than readState.Index' (duration: 171.175328ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:47:30.666269Z","caller":"traceutil/trace.go:171","msg":"trace[204110293] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"187.473728ms","start":"2026-06-02T07:47:30.478746Z","end":"2026-06-02T07:47:30.66622Z","steps":["trace[204110293] 'process raft request' (duration: 187.287213ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.666404Z","caller":"traceutil/trace.go:171","msg":"trace[771470104] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"187.492848ms","start":"2026-06-02T07:47:30.478789Z","end":"2026-06-02T07:47:30.666282Z","steps":["trace[771470104] 'process raft request' (duration: 187.287763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:30.666512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.978517ms","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-06-02T07:47:30.666184Z","caller":"traceutil/trace.go:171","msg":"trace[2055114070] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"246.392592ms","start":"2026-06-02T07:47:30.419749Z","end":"2026-06-02T07:47:30.666142Z","steps":["trace[2055114070] 'process raft request' (duration: 246.089366ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:30.666625Z","caller":"traceutil/trace.go:171","msg":"trace[230199465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26311; }","duration":"227.14337ms","start":"2026-06-02T07:47:30.439451Z","end":"2026-06-02T07:47:30.666594Z","steps":["trace[230199465] 'agreement among raft nodes before linearized reading' (duration: 226.800783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:30.66673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.531496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:47:30.6668Z","caller":"traceutil/trace.go:171","msg":"trace[660705120] 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:26311; }","duration":"171.662499ms","start":"2026-06-02T07:47:30.495122Z","end":"2026-06-02T07:47:30.666784Z","steps":["trace[660705120] 'agreement among raft nodes before linearized reading' (duration: 171.491675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:47:30.66704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.638962ms","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:6"} {"level":"info","ts":"2026-06-02T07:47:30.66715Z","caller":"traceutil/trace.go:171","msg":"trace[2033940582] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26311; }","duration":"182.048712ms","start":"2026-06-02T07:47:30.485086Z","end":"2026-06-02T07:47:30.667135Z","steps":["trace[2033940582] 'agreement among raft nodes before linearized reading' (duration: 181.328546ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:47:58.619723Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006baa.wal"} {"level":"info","ts":"2026-06-02T07:50:23.500314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25130} {"level":"info","ts":"2026-06-02T07:50:23.64798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25130,"took":"142.741271ms","hash":2527858149,"current-db-size-bytes":61722624,"current-db-size":"62 MB","current-db-size-in-use-bytes":38400000,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-06-02T07:50:23.648048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2527858149,"revision":25130,"compact-revision":21952} {"level":"info","ts":"2026-06-02T07:50:41.370275Z","caller":"traceutil/trace.go:171","msg":"trace[196614181] transaction","detail":"{read_only:false; response_revision:28253; number_of_response:1; }","duration":"112.803497ms","start":"2026-06-02T07:50:41.257449Z","end":"2026-06-02T07:50:41.370252Z","steps":["trace[196614181] 'process raft request' (duration: 112.430729ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:50:41.370427Z","caller":"traceutil/trace.go:171","msg":"trace[520947797] transaction","detail":"{read_only:false; response_revision:28254; number_of_response:1; }","duration":"106.424679ms","start":"2026-06-02T07:50:41.263994Z","end":"2026-06-02T07:50:41.370419Z","steps":["trace[520947797] 'process raft request' (duration: 105.96125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:51:20.756375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.25375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T07:51:20.756524Z","caller":"traceutil/trace.go:171","msg":"trace[1904264863] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:28652; }","duration":"122.494164ms","start":"2026-06-02T07:51:20.634004Z","end":"2026-06-02T07:51:20.756498Z","steps":["trace[1904264863] 'count revisions from in-memory index tree' (duration: 122.111717ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:52:14.378744Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T07:52:14.385002Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-02T07:52:14.385112Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-02T07:55:23.507857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28093} {"level":"info","ts":"2026-06-02T07:55:23.670745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28093,"took":"146.653074ms","hash":3868955874,"current-db-size-bytes":61722624,"current-db-size":"62 MB","current-db-size-in-use-bytes":32374784,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T07:55:23.670822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3868955874,"revision":28093,"compact-revision":25130} {"level":"info","ts":"2026-06-02T08:00:23.524767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30481} {"level":"info","ts":"2026-06-02T08:00:23.668607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30481,"took":"140.441361ms","hash":1615062240,"current-db-size-bytes":61722624,"current-db-size":"62 MB","current-db-size-in-use-bytes":30621696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T08:00:23.668696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1615062240,"revision":30481,"compact-revision":28093} {"level":"info","ts":"2026-06-02T08:05:23.5316Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32788} {"level":"info","ts":"2026-06-02T08:05:23.68128Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32788,"took":"144.54019ms","hash":500826385,"current-db-size-bytes":61722624,"current-db-size":"62 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T08:05:23.681445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":500826385,"revision":32788,"compact-revision":30481} {"level":"info","ts":"2026-06-02T08:10:23.540926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35058} {"level":"info","ts":"2026-06-02T08:10:23.689477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35058,"took":"142.547267ms","hash":3790148482,"current-db-size-bytes":61722624,"current-db-size":"62 MB","current-db-size-in-use-bytes":31563776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T08:10:23.689602Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3790148482,"revision":35058,"compact-revision":32788} {"level":"info","ts":"2026-06-02T08:13:33.421327Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T08:13:33.426497Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-02T08:13:33.426621Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-02T08:15:23.54816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37350} {"level":"info","ts":"2026-06-02T08:15:23.665966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37350,"took":"114.351661ms","hash":1239808792,"current-db-size-bytes":62996480,"current-db-size":"63 MB","current-db-size-in-use-bytes":32464896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T08:15:23.666058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1239808792,"revision":37350,"compact-revision":35058} {"level":"info","ts":"2026-06-02T08:20:23.553861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39626} {"level":"info","ts":"2026-06-02T08:20:23.700406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39626,"took":"141.404381ms","hash":1025655047,"current-db-size-bytes":62996480,"current-db-size":"63 MB","current-db-size-in-use-bytes":30818304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T08:20:23.700486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1025655047,"revision":39626,"compact-revision":37350}