{"level":"warn","ts":"2026-07-17T15:56:25.605262Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T15:56:25.60539Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.192:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.192:2380","--initial-cluster=instance=https://199.19.213.192:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.192:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.192:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T15:56:25.605489Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T15:56:25.605505Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-07-17T15:56:25.60553Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T15:56:25.606451Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"]} {"level":"info","ts":"2026-07-17T15:56:25.606567Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.192:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T15:56:25.609772Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.897034ms"} {"level":"info","ts":"2026-07-17T15:56:25.614818Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"341a3703c39c1f5b","cluster-id":"65341987fa712ba2"} {"level":"info","ts":"2026-07-17T15:56:25.614908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T15:56:25.614934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 0"} {"level":"info","ts":"2026-07-17T15:56:25.614945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 341a3703c39c1f5b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T15:56:25.614995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 1"} {"level":"info","ts":"2026-07-17T15:56:25.615066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"warn","ts":"2026-07-17T15:56:25.622646Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T15:56:25.624948Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T15:56:25.626211Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T15:56:25.628387Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"341a3703c39c1f5b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T15:56:25.62896Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T15:56:25.629106Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T15:56:25.629122Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T15:56:25.629261Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"341a3703c39c1f5b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T15:56:25.630489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"info","ts":"2026-07-17T15:56:25.630714Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","added-peer-id":"341a3703c39c1f5b","added-peer-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-07-17T15:56:25.633158Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T15:56:25.633316Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-07-17T15:56:25.633389Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-07-17T15:56:25.633444Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"341a3703c39c1f5b","initial-advertise-peer-urls":["https://199.19.213.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T15:56:25.633479Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T15:56:26.019301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T15:56:26.019375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T15:56:26.019399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgPreVoteResp from 341a3703c39c1f5b at term 1"} {"level":"info","ts":"2026-07-17T15:56:26.019421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became candidate at term 2"} {"level":"info","ts":"2026-07-17T15:56:26.019433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgVoteResp from 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-07-17T15:56:26.019456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became leader at term 2"} {"level":"info","ts":"2026-07-17T15:56:26.019475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 341a3703c39c1f5b elected leader 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-07-17T15:56:26.0265Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:56:26.02923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T15:56:26.029229Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"341a3703c39c1f5b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.192:2379]}","request-path":"/0/members/341a3703c39c1f5b/attributes","cluster-id":"65341987fa712ba2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T15:56:26.029698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T15:56:26.030204Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T15:56:26.030224Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T15:56:26.032656Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T15:56:26.032813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.192:2379"} {"level":"info","ts":"2026-07-17T15:56:26.035327Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:56:26.035434Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:56:26.035468Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:57:02.688754Z","caller":"traceutil/trace.go:171","msg":"trace[1417674439] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"102.263812ms","start":"2026-07-17T15:57:02.586457Z","end":"2026-07-17T15:57:02.688721Z","steps":["trace[1417674439] 'process raft request' (duration: 101.16408ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.698606Z","caller":"traceutil/trace.go:171","msg":"trace[1031698048] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"110.555805ms","start":"2026-07-17T15:57:02.588012Z","end":"2026-07-17T15:57:02.698568Z","steps":["trace[1031698048] 'process raft request' (duration: 110.328438ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.698893Z","caller":"traceutil/trace.go:171","msg":"trace[1828694428] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"112.389497ms","start":"2026-07-17T15:57:02.586492Z","end":"2026-07-17T15:57:02.698882Z","steps":["trace[1828694428] 'process raft request' (duration: 111.693827ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.699064Z","caller":"traceutil/trace.go:171","msg":"trace[1520391091] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"112.49233ms","start":"2026-07-17T15:57:02.586563Z","end":"2026-07-17T15:57:02.699056Z","steps":["trace[1520391091] 'process raft request' (duration: 111.746099ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.699188Z","caller":"traceutil/trace.go:171","msg":"trace[34774583] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"103.169029ms","start":"2026-07-17T15:57:02.596011Z","end":"2026-07-17T15:57:02.69918Z","steps":["trace[34774583] 'process raft request' (duration: 102.631873ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.699261Z","caller":"traceutil/trace.go:171","msg":"trace[873029477] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"101.502229ms","start":"2026-07-17T15:57:02.59771Z","end":"2026-07-17T15:57:02.699212Z","steps":["trace[873029477] 'process raft request' (duration: 100.960734ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.699478Z","caller":"traceutil/trace.go:171","msg":"trace[428546747] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"111.44792ms","start":"2026-07-17T15:57:02.588019Z","end":"2026-07-17T15:57:02.699467Z","steps":["trace[428546747] 'process raft request' (duration: 110.350088ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.699618Z","caller":"traceutil/trace.go:171","msg":"trace[661615668] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"103.657673ms","start":"2026-07-17T15:57:02.595951Z","end":"2026-07-17T15:57:02.699609Z","steps":["trace[661615668] 'process raft request' (duration: 102.654604ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.699737Z","caller":"traceutil/trace.go:171","msg":"trace[1450177729] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"108.250556ms","start":"2026-07-17T15:57:02.591465Z","end":"2026-07-17T15:57:02.699715Z","steps":["trace[1450177729] 'process raft request' (duration: 106.970449ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:02.699758Z","caller":"traceutil/trace.go:171","msg":"trace[704783720] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"103.879369ms","start":"2026-07-17T15:57:02.595871Z","end":"2026-07-17T15:57:02.69975Z","steps":["trace[704783720] 'process raft request' (duration: 102.666974ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:57:09.142708Z","caller":"traceutil/trace.go:171","msg":"trace[114359499] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"107.841424ms","start":"2026-07-17T15:57:09.034838Z","end":"2026-07-17T15:57:09.14268Z","steps":["trace[114359499] 'read index received' (duration: 90.298623ms)","trace[114359499] 'applied index is now lower than readState.Index' (duration: 17.541481ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:57:09.142743Z","caller":"traceutil/trace.go:171","msg":"trace[666179754] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"246.22983ms","start":"2026-07-17T15:57:08.896486Z","end":"2026-07-17T15:57:09.142716Z","steps":["trace[666179754] 'process raft request' (duration: 229.06744ms)","trace[666179754] 'compare' (duration: 16.627885ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:57:09.142872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.01954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bt5bb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-17T15:57:09.143002Z","caller":"traceutil/trace.go:171","msg":"trace[1736851475] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bt5bb; range_end:; response_count:1; response_revision:637; }","duration":"108.162054ms","start":"2026-07-17T15:57:09.03478Z","end":"2026-07-17T15:57:09.142943Z","steps":["trace[1736851475] 'agreement among raft nodes before linearized reading' (duration: 108.01692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:57:09.612721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.725222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:57:09.612831Z","caller":"traceutil/trace.go:171","msg":"trace[2109040660] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:639; }","duration":"369.878777ms","start":"2026-07-17T15:57:09.242926Z","end":"2026-07-17T15:57:09.612805Z","steps":["trace[2109040660] 'range keys from in-memory index tree' (duration: 369.6613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:57:09.612749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.518354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-17T15:57:09.612888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:57:09.24291Z","time spent":"369.963589ms","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-17T15:57:09.613016Z","caller":"traceutil/trace.go:171","msg":"trace[1314637907] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:639; }","duration":"312.787632ms","start":"2026-07-17T15:57:09.300168Z","end":"2026-07-17T15:57:09.612955Z","steps":["trace[1314637907] 'range keys from in-memory index tree' (duration: 312.400081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:57:09.613128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:57:09.300146Z","time spent":"312.945847ms","remote":"127.0.0.1:56056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.192\" "} {"level":"info","ts":"2026-07-17T15:57:51.241332Z","caller":"traceutil/trace.go:171","msg":"trace[1978250442] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"155.452822ms","start":"2026-07-17T15:57:51.085844Z","end":"2026-07-17T15:57:51.241296Z","steps":["trace[1978250442] 'process raft request' (duration: 155.229366ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:13.617271Z","caller":"traceutil/trace.go:171","msg":"trace[1175648628] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"128.900584ms","start":"2026-07-17T15:58:13.488336Z","end":"2026-07-17T15:58:13.617236Z","steps":["trace[1175648628] 'process raft request' (duration: 97.748412ms)","trace[1175648628] 'compare' (duration: 31.006958ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:39.588734Z","caller":"traceutil/trace.go:171","msg":"trace[294917553] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"178.232489ms","start":"2026-07-17T15:58:39.41047Z","end":"2026-07-17T15:58:39.588703Z","steps":["trace[294917553] 'read index received' (duration: 177.969831ms)","trace[294917553] 'applied index is now lower than readState.Index' (duration: 261.068µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:39.589178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.657251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9f140ae-3481-43f4-a43e-8327e5ee5772\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-17T15:58:39.589325Z","caller":"traceutil/trace.go:171","msg":"trace[428847701] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9f140ae-3481-43f4-a43e-8327e5ee5772; range_end:; response_count:1; response_revision:1869; }","duration":"178.871909ms","start":"2026-07-17T15:58:39.410424Z","end":"2026-07-17T15:58:39.589295Z","steps":["trace[428847701] 'agreement among raft nodes before linearized reading' (duration: 178.470166ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:39.730557Z","caller":"traceutil/trace.go:171","msg":"trace[919128614] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"139.347677ms","start":"2026-07-17T15:58:39.591177Z","end":"2026-07-17T15:58:39.730525Z","steps":["trace[919128614] 'process raft request' (duration: 139.159512ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:39.779535Z","caller":"traceutil/trace.go:171","msg":"trace[940386714] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"185.06793ms","start":"2026-07-17T15:58:39.594436Z","end":"2026-07-17T15:58:39.779504Z","steps":["trace[940386714] 'process raft request' (duration: 184.813783ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:39.780009Z","caller":"traceutil/trace.go:171","msg":"trace[743085928] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"182.035662ms","start":"2026-07-17T15:58:39.597959Z","end":"2026-07-17T15:58:39.779995Z","steps":["trace[743085928] 'process raft request' (duration: 181.474984ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:29.062608Z","caller":"traceutil/trace.go:171","msg":"trace[143606225] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"110.813729ms","start":"2026-07-17T15:59:28.951769Z","end":"2026-07-17T15:59:29.062583Z","steps":["trace[143606225] 'process raft request' (duration: 110.626553ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:29.37289Z","caller":"traceutil/trace.go:171","msg":"trace[757783826] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"124.429289ms","start":"2026-07-17T15:59:29.248417Z","end":"2026-07-17T15:59:29.372846Z","steps":["trace[757783826] 'process raft request' (duration: 53.217325ms)","trace[757783826] 'compare' (duration: 71.049959ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:59:29.670945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.56373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T15:59:29.67105Z","caller":"traceutil/trace.go:171","msg":"trace[90529734] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2196; }","duration":"196.703594ms","start":"2026-07-17T15:59:29.474327Z","end":"2026-07-17T15:59:29.671031Z","steps":["trace[90529734] 'count revisions from in-memory index tree' (duration: 196.416415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:29.671034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.283428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T15:59:29.671111Z","caller":"traceutil/trace.go:171","msg":"trace[911977424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2196; }","duration":"132.426793ms","start":"2026-07-17T15:59:29.538669Z","end":"2026-07-17T15:59:29.671096Z","steps":["trace[911977424] 'range keys from in-memory index tree' (duration: 132.109573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:35.339445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.212362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:59:35.339701Z","caller":"traceutil/trace.go:171","msg":"trace[1140235643] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2303; }","duration":"166.903128ms","start":"2026-07-17T15:59:35.172777Z","end":"2026-07-17T15:59:35.33968Z","steps":["trace[1140235643] 'read index received' (duration: 8.642884ms)","trace[1140235643] 'applied index is now lower than readState.Index' (duration: 158.258904ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:59:35.339784Z","caller":"traceutil/trace.go:171","msg":"trace[1406250936] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"250.059153ms","start":"2026-07-17T15:59:35.089645Z","end":"2026-07-17T15:59:35.339704Z","steps":["trace[1406250936] 'process raft request' (duration: 249.966309ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:35.339789Z","caller":"traceutil/trace.go:171","msg":"trace[1831124411] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"254.282606ms","start":"2026-07-17T15:59:35.085471Z","end":"2026-07-17T15:59:35.339754Z","steps":["trace[1831124411] 'process raft request' (duration: 95.94063ms)","trace[1831124411] 'compare' (duration: 157.056288ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:59:35.339913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.137224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T15:59:35.339955Z","caller":"traceutil/trace.go:171","msg":"trace[574401962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2226; }","duration":"167.218217ms","start":"2026-07-17T15:59:35.172726Z","end":"2026-07-17T15:59:35.339945Z","steps":["trace[574401962] 'agreement among raft nodes before linearized reading' (duration: 167.019921ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:35.39328Z","caller":"traceutil/trace.go:171","msg":"trace[763329572] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"185.01773ms","start":"2026-07-17T15:59:35.208238Z","end":"2026-07-17T15:59:35.393256Z","steps":["trace[763329572] 'process raft request' (duration: 184.871166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:35.682539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.454685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:59:35.682655Z","caller":"traceutil/trace.go:171","msg":"trace[527243259] linearizableReadLoop","detail":"{readStateIndex:2307; appliedIndex:2305; }","duration":"333.417273ms","start":"2026-07-17T15:59:35.349219Z","end":"2026-07-17T15:59:35.682636Z","steps":["trace[527243259] 'read index received' (duration: 43.939502ms)","trace[527243259] 'applied index is now lower than readState.Index' (duration: 289.47601ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:59:35.682716Z","caller":"traceutil/trace.go:171","msg":"trace[442940907] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"338.359359ms","start":"2026-07-17T15:59:35.344319Z","end":"2026-07-17T15:59:35.682678Z","steps":["trace[442940907] 'process raft request' (duration: 171.661907ms)","trace[442940907] 'compare' (duration: 165.93454ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:59:35.68289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.180623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-17T15:59:35.682885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:35.34429Z","time spent":"338.505262ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:59:35.682936Z","caller":"traceutil/trace.go:171","msg":"trace[289622566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2228; }","duration":"287.270966ms","start":"2026-07-17T15:59:35.395652Z","end":"2026-07-17T15:59:35.682923Z","steps":["trace[289622566] 'agreement among raft nodes before linearized reading' (duration: 287.105851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:35.682857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.593247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T15:59:35.683205Z","caller":"traceutil/trace.go:171","msg":"trace[377223669] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2228; }","duration":"333.799774ms","start":"2026-07-17T15:59:35.349183Z","end":"2026-07-17T15:59:35.682983Z","steps":["trace[377223669] 'agreement among raft nodes before linearized reading' (duration: 333.537756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:35.683339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:35.349166Z","time spent":"334.151184ms","remote":"127.0.0.1:56572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":3,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-17T16:00:11.323166Z","caller":"traceutil/trace.go:171","msg":"trace[1503428559] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"224.046757ms","start":"2026-07-17T16:00:11.099087Z","end":"2026-07-17T16:00:11.323134Z","steps":["trace[1503428559] 'process raft request' (duration: 223.668176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:45.362669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.76957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-17T16:00:45.362756Z","caller":"traceutil/trace.go:171","msg":"trace[1600461810] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2801; }","duration":"105.878263ms","start":"2026-07-17T16:00:45.256861Z","end":"2026-07-17T16:00:45.362739Z","steps":["trace[1600461810] 'range keys from in-memory index tree' (duration: 105.532063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:45.36279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.918495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:00:45.362864Z","caller":"traceutil/trace.go:171","msg":"trace[2132077837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2801; }","duration":"124.06903ms","start":"2026-07-17T16:00:45.238778Z","end":"2026-07-17T16:00:45.362847Z","steps":["trace[2132077837] 'count revisions from in-memory index tree' (duration: 123.72265ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:05:12.833826Z","caller":"traceutil/trace.go:171","msg":"trace[417142309] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"174.592413ms","start":"2026-07-17T16:05:12.659214Z","end":"2026-07-17T16:05:12.833807Z","steps":["trace[417142309] 'process raft request' (duration: 174.462029ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:05:13.085483Z","caller":"traceutil/trace.go:171","msg":"trace[1510120134] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5523; }","duration":"323.891544ms","start":"2026-07-17T16:05:12.761563Z","end":"2026-07-17T16:05:13.085455Z","steps":["trace[1510120134] 'read index received' (duration: 72.058544ms)","trace[1510120134] 'applied index is now lower than readState.Index' (duration: 251.83213ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:05:13.085575Z","caller":"traceutil/trace.go:171","msg":"trace[428020418] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"418.939842ms","start":"2026-07-17T16:05:12.666613Z","end":"2026-07-17T16:05:13.085553Z","steps":["trace[428020418] 'process raft request' (duration: 418.805828ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:05:13.085563Z","caller":"traceutil/trace.go:171","msg":"trace[1519604904] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"421.2544ms","start":"2026-07-17T16:05:12.664269Z","end":"2026-07-17T16:05:13.085523Z","steps":["trace[1519604904] 'process raft request' (duration: 421.001553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:05:13.085676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.099111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-g4h59\" ","response":"range_response_count:1 size:7666"} {"level":"info","ts":"2026-07-17T16:05:13.085708Z","caller":"traceutil/trace.go:171","msg":"trace[1183859073] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-g4h59; range_end:; response_count:1; response_revision:5333; }","duration":"324.172492ms","start":"2026-07-17T16:05:12.761523Z","end":"2026-07-17T16:05:13.085696Z","steps":["trace[1183859073] 'agreement among raft nodes before linearized reading' (duration: 324.044428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:05:13.085714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:05:12.666598Z","time spent":"419.036045ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:05:13.085712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:05:12.664241Z","time spent":"421.397854ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:05:13.085734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:05:12.761508Z","time spent":"324.220954ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7688,"request content":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-g4h59\" "} {"level":"info","ts":"2026-07-17T16:05:13.092777Z","caller":"traceutil/trace.go:171","msg":"trace[519268929] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"173.13562ms","start":"2026-07-17T16:05:12.919628Z","end":"2026-07-17T16:05:13.092764Z","steps":["trace[519268929] 'process raft request' (duration: 172.839011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:05:13.092774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.604273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:05:13.092859Z","caller":"traceutil/trace.go:171","msg":"trace[1536416093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5334; }","duration":"236.750227ms","start":"2026-07-17T16:05:12.856089Z","end":"2026-07-17T16:05:13.092839Z","steps":["trace[1536416093] 'agreement among raft nodes before linearized reading' (duration: 236.571972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:05:13.092951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.748901ms","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-07-17T16:05:13.093036Z","caller":"traceutil/trace.go:171","msg":"trace[300589352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5334; }","duration":"139.854644ms","start":"2026-07-17T16:05:12.953163Z","end":"2026-07-17T16:05:13.093017Z","steps":["trace[300589352] 'agreement among raft nodes before linearized reading' (duration: 139.638668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:05:13.092939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.076306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-17T16:05:13.093104Z","caller":"traceutil/trace.go:171","msg":"trace[1526497841] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5334; }","duration":"117.288021ms","start":"2026-07-17T16:05:12.975802Z","end":"2026-07-17T16:05:13.09309Z","steps":["trace[1526497841] 'agreement among raft nodes before linearized reading' (duration: 116.939101ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:06:26.259781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3028} {"level":"info","ts":"2026-07-17T16:06:26.347138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3028,"took":"85.9631ms","hash":1114591191,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":31944704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T16:06:26.347213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1114591191,"revision":3028,"compact-revision":-1} {"level":"info","ts":"2026-07-17T16:11:26.265957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6100} {"level":"info","ts":"2026-07-17T16:11:26.372505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6100,"took":"97.493196ms","hash":2736956648,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":22028288,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-17T16:11:26.372592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2736956648,"revision":6100,"compact-revision":3028} {"level":"warn","ts":"2026-07-17T16:11:30.733707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.082709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:11:30.733877Z","caller":"traceutil/trace.go:171","msg":"trace[141821620] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"324.74925ms","start":"2026-07-17T16:11:30.409078Z","end":"2026-07-17T16:11:30.733827Z","steps":["trace[141821620] 'process raft request' (duration: 11.449935ms)","trace[141821620] 'compare' (duration: 312.853951ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:11:30.733918Z","caller":"traceutil/trace.go:171","msg":"trace[701459662] linearizableReadLoop","detail":"{readStateIndex:8635; appliedIndex:8634; }","duration":"318.24425ms","start":"2026-07-17T16:11:30.415652Z","end":"2026-07-17T16:11:30.733896Z","steps":["trace[701459662] 'read index received' (duration: 4.823981ms)","trace[701459662] 'applied index is now lower than readState.Index' (duration: 313.418719ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:11:30.733915Z","caller":"traceutil/trace.go:171","msg":"trace[549564870] transaction","detail":"{read_only:false; response_revision:8347; number_of_response:1; }","duration":"278.757687ms","start":"2026-07-17T16:11:30.45514Z","end":"2026-07-17T16:11:30.733898Z","steps":["trace[549564870] 'process raft request' (duration: 278.681785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:11:30.734052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.391785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:11:30.734085Z","caller":"traceutil/trace.go:171","msg":"trace[1495873190] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8347; }","duration":"318.473817ms","start":"2026-07-17T16:11:30.415601Z","end":"2026-07-17T16:11:30.734074Z","steps":["trace[1495873190] 'agreement among raft nodes before linearized reading' (duration: 318.399905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:11:30.734063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:11:30.409056Z","time spent":"324.909124ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:11:30.734148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:11:30.415581Z","time spent":"318.559549ms","remote":"127.0.0.1:56540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-17T16:11:30.877536Z","caller":"traceutil/trace.go:171","msg":"trace[875364961] linearizableReadLoop","detail":"{readStateIndex:8638; appliedIndex:8636; }","duration":"143.532479ms","start":"2026-07-17T16:11:30.73398Z","end":"2026-07-17T16:11:30.877512Z","steps":["trace[875364961] 'read index received' (duration: 141.698835ms)","trace[875364961] 'applied index is now lower than readState.Index' (duration: 1.832704ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:11:30.877619Z","caller":"traceutil/trace.go:171","msg":"trace[1094064625] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"233.910608ms","start":"2026-07-17T16:11:30.643684Z","end":"2026-07-17T16:11:30.877595Z","steps":["trace[1094064625] 'process raft request' (duration: 232.020523ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:11:30.877633Z","caller":"traceutil/trace.go:171","msg":"trace[1001071265] transaction","detail":"{read_only:false; response_revision:8349; number_of_response:1; }","duration":"158.349402ms","start":"2026-07-17T16:11:30.719242Z","end":"2026-07-17T16:11:30.877591Z","steps":["trace[1001071265] 'process raft request' (duration: 158.208648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:11:30.877984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.747978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:11:30.878058Z","caller":"traceutil/trace.go:171","msg":"trace[275612269] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8349; }","duration":"140.881622ms","start":"2026-07-17T16:11:30.737158Z","end":"2026-07-17T16:11:30.87804Z","steps":["trace[275612269] 'agreement among raft nodes before linearized reading' (duration: 140.556613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:11:30.878775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.049543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T16:11:30.878847Z","caller":"traceutil/trace.go:171","msg":"trace[1118591025] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:8349; }","duration":"369.364002ms","start":"2026-07-17T16:11:30.509468Z","end":"2026-07-17T16:11:30.878832Z","steps":["trace[1118591025] 'agreement among raft nodes before linearized reading' (duration: 368.182028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:11:30.878891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:11:30.509447Z","time spent":"369.433104ms","remote":"127.0.0.1:56218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":54,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-17T16:11:42.138048Z","caller":"traceutil/trace.go:171","msg":"trace[614106595] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"132.060434ms","start":"2026-07-17T16:11:42.00595Z","end":"2026-07-17T16:11:42.13801Z","steps":["trace[614106595] 'process raft request' (duration: 131.9162ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:11:42.40031Z","caller":"traceutil/trace.go:171","msg":"trace[897432563] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"105.53396ms","start":"2026-07-17T16:11:42.294741Z","end":"2026-07-17T16:11:42.400275Z","steps":["trace[897432563] 'process raft request' (duration: 28.715199ms)","trace[897432563] 'compare' (duration: 76.510612ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:11:42.535186Z","caller":"traceutil/trace.go:171","msg":"trace[79849693] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"101.024608ms","start":"2026-07-17T16:11:42.434129Z","end":"2026-07-17T16:11:42.535154Z","steps":["trace[79849693] 'process raft request' (duration: 95.006083ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:11:43.415512Z","caller":"traceutil/trace.go:171","msg":"trace[591491304] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"105.002905ms","start":"2026-07-17T16:11:43.310481Z","end":"2026-07-17T16:11:43.415484Z","steps":["trace[591491304] 'process raft request' (duration: 104.785568ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:11:43.558886Z","caller":"traceutil/trace.go:171","msg":"trace[1820862003] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"109.126736ms","start":"2026-07-17T16:11:43.449723Z","end":"2026-07-17T16:11:43.55885Z","steps":["trace[1820862003] 'process raft request' (duration: 35.627089ms)","trace[1820862003] 'compare' (duration: 73.28876ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:11:44.692869Z","caller":"traceutil/trace.go:171","msg":"trace[901400866] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"152.590924ms","start":"2026-07-17T16:11:44.540257Z","end":"2026-07-17T16:11:44.692848Z","steps":["trace[901400866] 'process raft request' (duration: 54.727767ms)","trace[901400866] 'compare' (duration: 97.772655ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:11:58.661899Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000225a.wal"} {"level":"info","ts":"2026-07-17T16:12:10.937579Z","caller":"traceutil/trace.go:171","msg":"trace[1491351392] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"161.849474ms","start":"2026-07-17T16:12:10.775706Z","end":"2026-07-17T16:12:10.937556Z","steps":["trace[1491351392] 'process raft request' (duration: 161.70944ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:12:10.943438Z","caller":"traceutil/trace.go:171","msg":"trace[871393992] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"134.664941ms","start":"2026-07-17T16:12:10.808752Z","end":"2026-07-17T16:12:10.943416Z","steps":["trace[871393992] 'process raft request' (duration: 134.496296ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:12:11.171167Z","caller":"traceutil/trace.go:171","msg":"trace[512811690] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"118.458998ms","start":"2026-07-17T16:12:11.052682Z","end":"2026-07-17T16:12:11.171141Z","steps":["trace[512811690] 'process raft request' (duration: 118.292453ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:12:15.066445Z","caller":"traceutil/trace.go:171","msg":"trace[741886926] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"118.076326ms","start":"2026-07-17T16:12:14.948348Z","end":"2026-07-17T16:12:15.066424Z","steps":["trace[741886926] 'process raft request' (duration: 117.902441ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:12:15.096033Z","caller":"traceutil/trace.go:171","msg":"trace[331403520] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"141.676495ms","start":"2026-07-17T16:12:14.954314Z","end":"2026-07-17T16:12:15.095991Z","steps":["trace[331403520] 'process raft request' (duration: 141.420577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:12:23.860897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.706404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T16:12:23.860978Z","caller":"traceutil/trace.go:171","msg":"trace[597957603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8724; }","duration":"118.832148ms","start":"2026-07-17T16:12:23.74213Z","end":"2026-07-17T16:12:23.860962Z","steps":["trace[597957603] 'range keys from in-memory index tree' (duration: 117.908162ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:12:24.520635Z","caller":"traceutil/trace.go:171","msg":"trace[1155086225] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"128.44569ms","start":"2026-07-17T16:12:24.392156Z","end":"2026-07-17T16:12:24.520602Z","steps":["trace[1155086225] 'process raft request' (duration: 79.816599ms)","trace[1155086225] 'compare' (duration: 47.994121ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:12:24.520526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.935332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-17T16:12:24.52075Z","caller":"traceutil/trace.go:171","msg":"trace[1394825415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8728; }","duration":"131.2064ms","start":"2026-07-17T16:12:24.389517Z","end":"2026-07-17T16:12:24.520724Z","steps":["trace[1394825415] 'agreement among raft nodes before linearized reading' (duration: 130.828268ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:12:24.832767Z","caller":"traceutil/trace.go:171","msg":"trace[284363610] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"181.002634ms","start":"2026-07-17T16:12:24.651742Z","end":"2026-07-17T16:12:24.832744Z","steps":["trace[284363610] 'process raft request' (duration: 180.85025ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:13:41.559342Z","caller":"traceutil/trace.go:171","msg":"trace[450389870] transaction","detail":"{read_only:false; response_revision:9477; number_of_response:1; }","duration":"192.227415ms","start":"2026-07-17T16:13:41.367091Z","end":"2026-07-17T16:13:41.559319Z","steps":["trace[450389870] 'process raft request' (duration: 176.27673ms)","trace[450389870] 'compare' (duration: 15.820281ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:13:41.635015Z","caller":"traceutil/trace.go:171","msg":"trace[572258953] transaction","detail":"{read_only:false; response_revision:9478; number_of_response:1; }","duration":"263.625191ms","start":"2026-07-17T16:13:41.371363Z","end":"2026-07-17T16:13:41.634988Z","steps":["trace[572258953] 'process raft request' (duration: 263.453857ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:13:41.635171Z","caller":"traceutil/trace.go:171","msg":"trace[384317776] transaction","detail":"{read_only:false; response_revision:9479; number_of_response:1; }","duration":"107.386646ms","start":"2026-07-17T16:13:41.527744Z","end":"2026-07-17T16:13:41.635131Z","steps":["trace[384317776] 'process raft request' (duration: 107.20149ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:14:06.378896Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T16:14:06.384162Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-17T16:14:06.384348Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-17T16:16:26.272044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8191} {"level":"info","ts":"2026-07-17T16:16:26.335282Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8191,"took":"60.710319ms","hash":125693247,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":29822976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-17T16:16:26.335393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":125693247,"revision":8191,"compact-revision":6100} {"level":"info","ts":"2026-07-17T16:17:32.165835Z","caller":"traceutil/trace.go:171","msg":"trace[685402959] transaction","detail":"{read_only:false; response_revision:11360; number_of_response:1; }","duration":"126.243642ms","start":"2026-07-17T16:17:32.039557Z","end":"2026-07-17T16:17:32.1658Z","steps":["trace[685402959] 'process raft request' (duration: 125.913502ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:21:26.279254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10787} {"level":"info","ts":"2026-07-17T16:21:26.362782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10787,"took":"79.798715ms","hash":864296411,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":26779648,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-17T16:21:26.362899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":864296411,"revision":10787,"compact-revision":8191} {"level":"info","ts":"2026-07-17T16:23:45.329009Z","caller":"traceutil/trace.go:171","msg":"trace[223035639] transaction","detail":"{read_only:false; response_revision:13877; number_of_response:1; }","duration":"130.026767ms","start":"2026-07-17T16:23:45.198959Z","end":"2026-07-17T16:23:45.328986Z","steps":["trace[223035639] 'process raft request' (duration: 129.813471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:24:00.562948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.738066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T16:24:00.563071Z","caller":"traceutil/trace.go:171","msg":"trace[1554997877] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:13973; }","duration":"110.924042ms","start":"2026-07-17T16:24:00.452125Z","end":"2026-07-17T16:24:00.563049Z","steps":["trace[1554997877] 'count revisions from in-memory index tree' (duration: 110.609572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:24:01.678566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.28108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T16:24:01.678746Z","caller":"traceutil/trace.go:171","msg":"trace[1559043893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13978; }","duration":"274.485315ms","start":"2026-07-17T16:24:01.404239Z","end":"2026-07-17T16:24:01.678725Z","steps":["trace[1559043893] 'range keys from in-memory index tree' (duration: 274.082523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:24:01.678743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.174313ms","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-07-17T16:24:01.678936Z","caller":"traceutil/trace.go:171","msg":"trace[379450315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13978; }","duration":"175.41317ms","start":"2026-07-17T16:24:01.503478Z","end":"2026-07-17T16:24:01.678892Z","steps":["trace[379450315] 'range keys from in-memory index tree' (duration: 174.906045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:24:01.679094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.300124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:24:01.679206Z","caller":"traceutil/trace.go:171","msg":"trace[274551680] transaction","detail":"{read_only:false; response_revision:13979; number_of_response:1; }","duration":"156.944392ms","start":"2026-07-17T16:24:01.522238Z","end":"2026-07-17T16:24:01.679182Z","steps":["trace[274551680] 'process raft request' (duration: 25.474922ms)","trace[274551680] 'compare' (duration: 131.105659ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:24:11.212644Z","caller":"traceutil/trace.go:171","msg":"trace[707870989] linearizableReadLoop","detail":"{readStateIndex:14625; appliedIndex:14624; }","duration":"101.509076ms","start":"2026-07-17T16:24:11.111109Z","end":"2026-07-17T16:24:11.212619Z","steps":["trace[707870989] 'read index received' (duration: 101.257129ms)","trace[707870989] 'applied index is now lower than readState.Index' (duration: 248.847µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:24:11.212739Z","caller":"traceutil/trace.go:171","msg":"trace[1126617256] transaction","detail":"{read_only:false; response_revision:14146; number_of_response:1; }","duration":"135.793575ms","start":"2026-07-17T16:24:11.076904Z","end":"2026-07-17T16:24:11.212698Z","steps":["trace[1126617256] 'process raft request' (duration: 135.518917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:24:11.212839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.704361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:24:11.21288Z","caller":"traceutil/trace.go:171","msg":"trace[833360387] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:14146; }","duration":"101.807214ms","start":"2026-07-17T16:24:11.11106Z","end":"2026-07-17T16:24:11.212868Z","steps":["trace[833360387] 'agreement among raft nodes before linearized reading' (duration: 101.706412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:25:50.414178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.712958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T16:25:50.414291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.401533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-07-17T16:25:50.414376Z","caller":"traceutil/trace.go:171","msg":"trace[1942392964] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15229; }","duration":"257.533938ms","start":"2026-07-17T16:25:50.15682Z","end":"2026-07-17T16:25:50.414354Z","steps":["trace[1942392964] 'range keys from in-memory index tree' (duration: 257.212648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:25:50.414401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.668254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:25:50.414398Z","caller":"traceutil/trace.go:171","msg":"trace[737322440] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:15229; }","duration":"261.931305ms","start":"2026-07-17T16:25:50.152399Z","end":"2026-07-17T16:25:50.41433Z","steps":["trace[737322440] 'count revisions from in-memory index tree' (duration: 261.630136ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:25:50.414548Z","caller":"traceutil/trace.go:171","msg":"trace[1620958737] transaction","detail":"{read_only:false; response_revision:15231; number_of_response:1; }","duration":"130.881366ms","start":"2026-07-17T16:25:50.283651Z","end":"2026-07-17T16:25:50.414532Z","steps":["trace[1620958737] 'process raft request' (duration: 130.838944ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:25:50.414588Z","caller":"traceutil/trace.go:171","msg":"trace[2074683467] transaction","detail":"{read_only:false; response_revision:15230; number_of_response:1; }","duration":"246.247879ms","start":"2026-07-17T16:25:50.168311Z","end":"2026-07-17T16:25:50.414559Z","steps":["trace[2074683467] 'process raft request' (duration: 41.356719ms)","trace[2074683467] 'compare' (duration: 204.478609ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:25:50.414705Z","caller":"traceutil/trace.go:171","msg":"trace[2095053908] linearizableReadLoop","detail":"{readStateIndex:15743; appliedIndex:15742; }","duration":"160.017989ms","start":"2026-07-17T16:25:50.254677Z","end":"2026-07-17T16:25:50.414695Z","steps":["trace[2095053908] 'read index received' (duration: 44.361µs)","trace[2095053908] 'applied index is now lower than readState.Index' (duration: 159.972468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:25:50.414869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.197964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T16:25:50.414893Z","caller":"traceutil/trace.go:171","msg":"trace[750260565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15231; }","duration":"160.224106ms","start":"2026-07-17T16:25:50.254663Z","end":"2026-07-17T16:25:50.414887Z","steps":["trace[750260565] 'agreement among raft nodes before linearized reading' (duration: 160.107262ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:25:50.559162Z","caller":"traceutil/trace.go:171","msg":"trace[61823266] linearizableReadLoop","detail":"{readStateIndex:15745; appliedIndex:15744; }","duration":"144.401257ms","start":"2026-07-17T16:25:50.414729Z","end":"2026-07-17T16:25:50.55913Z","steps":["trace[61823266] 'read index received' (duration: 124.417237ms)","trace[61823266] 'applied index is now lower than readState.Index' (duration: 19.98266ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:25:50.559294Z","caller":"traceutil/trace.go:171","msg":"trace[987753055] transaction","detail":"{read_only:false; response_revision:15233; number_of_response:1; }","duration":"139.544636ms","start":"2026-07-17T16:25:50.41973Z","end":"2026-07-17T16:25:50.559275Z","steps":["trace[987753055] 'process raft request' (duration: 139.359551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:25:50.559377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.963412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:25:50.559427Z","caller":"traceutil/trace.go:171","msg":"trace[1597748383] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:15233; }","duration":"285.080105ms","start":"2026-07-17T16:25:50.274332Z","end":"2026-07-17T16:25:50.559412Z","steps":["trace[1597748383] 'agreement among raft nodes before linearized reading' (duration: 284.966102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:25:50.559488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.762157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T16:25:50.559525Z","caller":"traceutil/trace.go:171","msg":"trace[1762787600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15233; }","duration":"244.815978ms","start":"2026-07-17T16:25:50.314699Z","end":"2026-07-17T16:25:50.559515Z","steps":["trace[1762787600] 'agreement among raft nodes before linearized reading' (duration: 244.461308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:25:50.559512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.273484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-26dr9\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-07-17T16:25:50.559584Z","caller":"traceutil/trace.go:171","msg":"trace[914070531] range","detail":"{range_begin:/registry/pods/openstack/keepalived-26dr9; range_end:; response_count:1; response_revision:15233; }","duration":"262.361897ms","start":"2026-07-17T16:25:50.297204Z","end":"2026-07-17T16:25:50.559566Z","steps":["trace[914070531] 'agreement among raft nodes before linearized reading' (duration: 262.004957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:25:50.559388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.963968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T16:25:50.559598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.428839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-07-17T16:25:50.559178Z","caller":"traceutil/trace.go:171","msg":"trace[1170991934] transaction","detail":"{read_only:false; response_revision:15232; number_of_response:1; }","duration":"275.441637ms","start":"2026-07-17T16:25:50.283707Z","end":"2026-07-17T16:25:50.559149Z","steps":["trace[1170991934] 'process raft request' (duration: 255.457617ms)","trace[1170991934] 'compare' (duration: 19.765723ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:25:50.559681Z","caller":"traceutil/trace.go:171","msg":"trace[385789700] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:15233; }","duration":"240.268137ms","start":"2026-07-17T16:25:50.319375Z","end":"2026-07-17T16:25:50.559643Z","steps":["trace[385789700] 'agreement among raft nodes before linearized reading' (duration: 239.819454ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:25:50.559689Z","caller":"traceutil/trace.go:171","msg":"trace[1660993603] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15233; }","duration":"133.523882ms","start":"2026-07-17T16:25:50.42614Z","end":"2026-07-17T16:25:50.559664Z","steps":["trace[1660993603] 'agreement among raft nodes before linearized reading' (duration: 133.318306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:25:50.55956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.650382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T16:25:50.559768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.562979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-07-17T16:25:50.559789Z","caller":"traceutil/trace.go:171","msg":"trace[1705057337] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15233; }","duration":"136.94411ms","start":"2026-07-17T16:25:50.422836Z","end":"2026-07-17T16:25:50.55978Z","steps":["trace[1705057337] 'agreement among raft nodes before linearized reading' (duration: 136.615751ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:25:50.559828Z","caller":"traceutil/trace.go:171","msg":"trace[472148992] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15233; }","duration":"136.634302ms","start":"2026-07-17T16:25:50.42317Z","end":"2026-07-17T16:25:50.559804Z","steps":["trace[472148992] 'agreement among raft nodes before linearized reading' (duration: 136.447306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:25:50.559988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.186707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7695"} {"level":"info","ts":"2026-07-17T16:25:50.560151Z","caller":"traceutil/trace.go:171","msg":"trace[1069253107] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:15233; }","duration":"267.328611ms","start":"2026-07-17T16:25:50.292778Z","end":"2026-07-17T16:25:50.560107Z","steps":["trace[1069253107] 'agreement among raft nodes before linearized reading' (duration: 266.59229ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:26:26.285453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12881} {"level":"info","ts":"2026-07-17T16:26:26.361479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12881,"took":"73.253224ms","hash":3076791477,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":30662656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T16:26:26.361529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3076791477,"revision":12881,"compact-revision":10787} {"level":"warn","ts":"2026-07-17T16:31:17.002482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.441819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:31:17.002554Z","caller":"traceutil/trace.go:171","msg":"trace[1722815846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17648; }","duration":"611.565163ms","start":"2026-07-17T16:31:16.390972Z","end":"2026-07-17T16:31:17.002537Z","steps":["trace[1722815846] 'range keys from in-memory index tree' (duration: 611.308575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:31:17.002589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:31:16.390952Z","time spent":"611.628425ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T16:31:17.002964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.45817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fdqw6\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-07-17T16:31:17.003105Z","caller":"traceutil/trace.go:171","msg":"trace[1906855517] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fdqw6; range_end:; response_count:1; response_revision:17648; }","duration":"711.648826ms","start":"2026-07-17T16:31:16.291427Z","end":"2026-07-17T16:31:17.003076Z","steps":["trace[1906855517] 'range keys from in-memory index tree' (duration: 711.219314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:31:17.003178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:31:16.291411Z","time spent":"711.747269ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22037,"request content":"key:\"/registry/pods/openstack/nova-compute-default-fdqw6\" "} {"level":"warn","ts":"2026-07-17T16:31:17.003684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.051601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fdqw6\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-07-17T16:31:17.003732Z","caller":"traceutil/trace.go:171","msg":"trace[186626671] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fdqw6; range_end:; response_count:1; response_revision:17648; }","duration":"671.131033ms","start":"2026-07-17T16:31:16.332591Z","end":"2026-07-17T16:31:17.003722Z","steps":["trace[186626671] 'range keys from in-memory index tree' (duration: 670.937388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:31:17.003757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:31:16.332574Z","time spent":"671.175244ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22037,"request content":"key:\"/registry/pods/openstack/nova-compute-default-fdqw6\" "} {"level":"info","ts":"2026-07-17T16:31:17.003755Z","caller":"traceutil/trace.go:171","msg":"trace[1876829410] linearizableReadLoop","detail":"{readStateIndex:18241; appliedIndex:18240; }","duration":"140.663959ms","start":"2026-07-17T16:31:16.863072Z","end":"2026-07-17T16:31:17.003736Z","steps":["trace[1876829410] 'read index received' (duration: 139.162476ms)","trace[1876829410] 'applied index is now lower than readState.Index' (duration: 1.500582ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:31:17.004096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.019149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:31:17.004121Z","caller":"traceutil/trace.go:171","msg":"trace[1915850471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17649; }","duration":"141.085001ms","start":"2026-07-17T16:31:16.863026Z","end":"2026-07-17T16:31:17.004111Z","steps":["trace[1915850471] 'agreement among raft nodes before linearized reading' (duration: 141.03289ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:31:17.004107Z","caller":"traceutil/trace.go:171","msg":"trace[1070856906] transaction","detail":"{read_only:false; response_revision:17649; number_of_response:1; }","duration":"557.496649ms","start":"2026-07-17T16:31:16.446534Z","end":"2026-07-17T16:31:17.00403Z","steps":["trace[1070856906] 'process raft request' (duration: 555.564753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:31:17.004305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:31:16.446505Z","time spent":"557.675444ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:31:17.489329Z","caller":"traceutil/trace.go:171","msg":"trace[1181248701] linearizableReadLoop","detail":"{readStateIndex:18244; appliedIndex:18243; }","duration":"216.27868ms","start":"2026-07-17T16:31:17.27303Z","end":"2026-07-17T16:31:17.489309Z","steps":["trace[1181248701] 'read index received' (duration: 216.083625ms)","trace[1181248701] 'applied index is now lower than readState.Index' (duration: 194.315µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:31:17.489428Z","caller":"traceutil/trace.go:171","msg":"trace[1407517395] transaction","detail":"{read_only:false; response_revision:17652; number_of_response:1; }","duration":"216.654222ms","start":"2026-07-17T16:31:17.272765Z","end":"2026-07-17T16:31:17.489419Z","steps":["trace[1407517395] 'process raft request' (duration: 216.408715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:31:17.489715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.01509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:31:17.489747Z","caller":"traceutil/trace.go:171","msg":"trace[1788479325] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:17652; }","duration":"188.093063ms","start":"2026-07-17T16:31:17.301646Z","end":"2026-07-17T16:31:17.489739Z","steps":["trace[1788479325] 'agreement among raft nodes before linearized reading' (duration: 188.022701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:31:17.489902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.013732ms","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-07-17T16:31:17.489922Z","caller":"traceutil/trace.go:171","msg":"trace[106577453] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:17652; }","duration":"147.043944ms","start":"2026-07-17T16:31:17.342871Z","end":"2026-07-17T16:31:17.489915Z","steps":["trace[106577453] 'agreement among raft nodes before linearized reading' (duration: 146.979262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:31:17.49041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.381173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:934011"} {"level":"info","ts":"2026-07-17T16:31:17.490479Z","caller":"traceutil/trace.go:171","msg":"trace[841058257] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17652; }","duration":"217.483135ms","start":"2026-07-17T16:31:17.272989Z","end":"2026-07-17T16:31:17.490472Z","steps":["trace[841058257] 'agreement among raft nodes before linearized reading' (duration: 216.533367ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:31:26.292504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15455} {"level":"info","ts":"2026-07-17T16:31:26.393394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15455,"took":"97.799088ms","hash":365744350,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":32198656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T16:31:26.393457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":365744350,"revision":15455,"compact-revision":12881} {"level":"info","ts":"2026-07-17T16:31:29.87606Z","caller":"traceutil/trace.go:171","msg":"trace[1255062941] transaction","detail":"{read_only:false; response_revision:17831; number_of_response:1; }","duration":"135.412828ms","start":"2026-07-17T16:31:29.740616Z","end":"2026-07-17T16:31:29.876029Z","steps":["trace[1255062941] 'process raft request' (duration: 133.712059ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:34:44.370611Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T16:34:44.375425Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-17T16:34:44.375512Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-17T16:36:26.29908Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17768} {"level":"info","ts":"2026-07-17T16:36:26.398543Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17768,"took":"95.557597ms","hash":3074679463,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":34541568,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-17T16:36:26.398602Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3074679463,"revision":17768,"compact-revision":15455} {"level":"info","ts":"2026-07-17T16:37:11.015772Z","caller":"traceutil/trace.go:171","msg":"trace[146449160] transaction","detail":"{read_only:false; response_revision:20407; number_of_response:1; }","duration":"135.883571ms","start":"2026-07-17T16:37:10.87987Z","end":"2026-07-17T16:37:11.015754Z","steps":["trace[146449160] 'process raft request' (duration: 133.522113ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:37:11.015939Z","caller":"traceutil/trace.go:171","msg":"trace[1878682546] transaction","detail":"{read_only:false; response_revision:20408; number_of_response:1; }","duration":"131.531686ms","start":"2026-07-17T16:37:10.88437Z","end":"2026-07-17T16:37:11.015902Z","steps":["trace[1878682546] 'process raft request' (duration: 131.344721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:37:17.098164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.140967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:37:17.098253Z","caller":"traceutil/trace.go:171","msg":"trace[45199167] transaction","detail":"{read_only:false; response_revision:20437; number_of_response:1; }","duration":"191.743283ms","start":"2026-07-17T16:37:16.906492Z","end":"2026-07-17T16:37:17.098236Z","steps":["trace[45199167] 'process raft request' (duration: 53.440582ms)","trace[45199167] 'compare' (duration: 137.866978ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:37:17.181478Z","caller":"traceutil/trace.go:171","msg":"trace[1828392610] transaction","detail":"{read_only:false; response_revision:20439; number_of_response:1; }","duration":"144.985395ms","start":"2026-07-17T16:37:17.036461Z","end":"2026-07-17T16:37:17.181446Z","steps":["trace[1828392610] 'process raft request' (duration: 144.780819ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:37:17.354654Z","caller":"traceutil/trace.go:171","msg":"trace[955411122] transaction","detail":"{read_only:false; response_revision:20440; number_of_response:1; }","duration":"177.997188ms","start":"2026-07-17T16:37:17.176625Z","end":"2026-07-17T16:37:17.354622Z","steps":["trace[955411122] 'process raft request' (duration: 136.779058ms)","trace[955411122] 'compare' (duration: 40.982033ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:37:17.354697Z","caller":"traceutil/trace.go:171","msg":"trace[89092122] transaction","detail":"{read_only:false; response_revision:20441; number_of_response:1; }","duration":"169.058018ms","start":"2026-07-17T16:37:17.185609Z","end":"2026-07-17T16:37:17.354667Z","steps":["trace[89092122] 'process raft request' (duration: 168.928784ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:41:07.408638Z","caller":"traceutil/trace.go:171","msg":"trace[1285923549] transaction","detail":"{read_only:false; response_revision:22863; number_of_response:1; }","duration":"130.532797ms","start":"2026-07-17T16:41:07.278078Z","end":"2026-07-17T16:41:07.408611Z","steps":["trace[1285923549] 'process raft request' (duration: 130.048763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:41:07.729203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.525071ms","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-07-17T16:41:07.729282Z","caller":"traceutil/trace.go:171","msg":"trace[1357337351] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:22864; }","duration":"179.660425ms","start":"2026-07-17T16:41:07.549604Z","end":"2026-07-17T16:41:07.729264Z","steps":["trace[1357337351] 'count revisions from in-memory index tree' (duration: 179.433958ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:41:26.306806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20175} {"level":"info","ts":"2026-07-17T16:41:26.429044Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20175,"took":"118.204941ms","hash":585342901,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":42569728,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-17T16:41:26.429123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":585342901,"revision":20175,"compact-revision":17768} {"level":"info","ts":"2026-07-17T16:44:35.193794Z","caller":"traceutil/trace.go:171","msg":"trace[1058519745] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"121.938532ms","start":"2026-07-17T16:44:35.071831Z","end":"2026-07-17T16:44:35.193769Z","steps":["trace[1058519745] 'process raft request' (duration: 121.770617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:35.499294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.005152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:44:35.499413Z","caller":"traceutil/trace.go:171","msg":"trace[1836893402] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"137.592373ms","start":"2026-07-17T16:44:35.361798Z","end":"2026-07-17T16:44:35.49939Z","steps":["trace[1836893402] 'process raft request' (duration: 29.372814ms)","trace[1836893402] 'compare' (duration: 107.769745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:40.752139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.893798ms","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-07-17T16:44:40.752207Z","caller":"traceutil/trace.go:171","msg":"trace[759381386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24989; }","duration":"328.008691ms","start":"2026-07-17T16:44:40.424184Z","end":"2026-07-17T16:44:40.752193Z","steps":["trace[759381386] 'range keys from in-memory index tree' (duration: 327.736784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:40.752236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:40.424167Z","time spent":"328.062292ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T16:44:40.752305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.167475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-17T16:44:40.752378Z","caller":"traceutil/trace.go:171","msg":"trace[1386242223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24989; }","duration":"329.287808ms","start":"2026-07-17T16:44:40.423073Z","end":"2026-07-17T16:44:40.752361Z","steps":["trace[1386242223] 'range keys from in-memory index tree' (duration: 329.00494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:40.752416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:40.423053Z","time spent":"329.35572ms","remote":"127.0.0.1:56312","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":"info","ts":"2026-07-17T16:44:40.752938Z","caller":"traceutil/trace.go:171","msg":"trace[1912837589] linearizableReadLoop","detail":"{readStateIndex:25801; appliedIndex:25800; }","duration":"267.120962ms","start":"2026-07-17T16:44:40.485791Z","end":"2026-07-17T16:44:40.752912Z","steps":["trace[1912837589] 'read index received' (duration: 265.579227ms)","trace[1912837589] 'applied index is now lower than readState.Index' (duration: 1.540695ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:40.753194Z","caller":"traceutil/trace.go:171","msg":"trace[193717031] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"280.140526ms","start":"2026-07-17T16:44:40.47304Z","end":"2026-07-17T16:44:40.753181Z","steps":["trace[193717031] 'process raft request' (duration: 278.301313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:40.753235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.326133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:40.753262Z","caller":"traceutil/trace.go:171","msg":"trace[590337680] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24990; }","duration":"138.368554ms","start":"2026-07-17T16:44:40.614886Z","end":"2026-07-17T16:44:40.753255Z","steps":["trace[590337680] 'agreement among raft nodes before linearized reading' (duration: 138.305233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:40.753365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.573115ms","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-07-17T16:44:40.753382Z","caller":"traceutil/trace.go:171","msg":"trace[602976009] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24990; }","duration":"267.635577ms","start":"2026-07-17T16:44:40.485742Z","end":"2026-07-17T16:44:40.753377Z","steps":["trace[602976009] 'agreement among raft nodes before linearized reading' (duration: 267.598186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.162068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.64045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:44:41.162452Z","caller":"traceutil/trace.go:171","msg":"trace[870470764] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"406.031342ms","start":"2026-07-17T16:44:40.756385Z","end":"2026-07-17T16:44:41.162416Z","steps":["trace[870470764] 'process raft request' (duration: 241.96225ms)","trace[870470764] 'compare' (duration: 163.096385ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:41.162567Z","caller":"traceutil/trace.go:171","msg":"trace[1876025830] linearizableReadLoop","detail":"{readStateIndex:25806; appliedIndex:25801; }","duration":"264.833146ms","start":"2026-07-17T16:44:40.897677Z","end":"2026-07-17T16:44:41.16251Z","steps":["trace[1876025830] 'read index received' (duration: 100.769494ms)","trace[1876025830] 'applied index is now lower than readState.Index' (duration: 164.062862ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:41.162619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:40.756367Z","time spent":"406.169286ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:41.162782Z","caller":"traceutil/trace.go:171","msg":"trace[899811545] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"406.139695ms","start":"2026-07-17T16:44:40.756633Z","end":"2026-07-17T16:44:41.162773Z","steps":["trace[899811545] 'process raft request' (duration: 405.688553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.162861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:40.756626Z","time spent":"406.208267ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:41.163067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.383611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:41.163123Z","caller":"traceutil/trace.go:171","msg":"trace[1789298316] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24996; }","duration":"265.476014ms","start":"2026-07-17T16:44:40.897633Z","end":"2026-07-17T16:44:41.163109Z","steps":["trace[1789298316] 'agreement among raft nodes before linearized reading' (duration: 265.396202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.163183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.020206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:41.163244Z","caller":"traceutil/trace.go:171","msg":"trace[1130927576] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24996; }","duration":"160.105338ms","start":"2026-07-17T16:44:41.003111Z","end":"2026-07-17T16:44:41.163217Z","steps":["trace[1130927576] 'agreement among raft nodes before linearized reading' (duration: 159.981805ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:41.163351Z","caller":"traceutil/trace.go:171","msg":"trace[878464151] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"400.797661ms","start":"2026-07-17T16:44:40.76254Z","end":"2026-07-17T16:44:41.163337Z","steps":["trace[878464151] 'process raft request' (duration: 399.857975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.163423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:40.762531Z","time spent":"400.855603ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:41.163657Z","caller":"traceutil/trace.go:171","msg":"trace[1562492923] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"395.382347ms","start":"2026-07-17T16:44:40.768263Z","end":"2026-07-17T16:44:41.163646Z","steps":["trace[1562492923] 'process raft request' (duration: 394.164552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.163726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:40.768237Z","time spent":"395.45471ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:41.16381Z","caller":"traceutil/trace.go:171","msg":"trace[780199935] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"394.929414ms","start":"2026-07-17T16:44:40.768868Z","end":"2026-07-17T16:44:41.163797Z","steps":["trace[780199935] 'process raft request' (duration: 393.589344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.16386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:40.768846Z","time spent":"394.989895ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:41.16393Z","caller":"traceutil/trace.go:171","msg":"trace[874021327] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"206.070519ms","start":"2026-07-17T16:44:40.957852Z","end":"2026-07-17T16:44:41.163923Z","steps":["trace[874021327] 'process raft request' (duration: 204.628797ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:41.676322Z","caller":"traceutil/trace.go:171","msg":"trace[1942441650] linearizableReadLoop","detail":"{readStateIndex:25808; appliedIndex:25807; }","duration":"339.894563ms","start":"2026-07-17T16:44:41.336384Z","end":"2026-07-17T16:44:41.676278Z","steps":["trace[1942441650] 'read index received' (duration: 339.744938ms)","trace[1942441650] 'applied index is now lower than readState.Index' (duration: 148.365µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:41.676463Z","caller":"traceutil/trace.go:171","msg":"trace[688498177] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"456.678837ms","start":"2026-07-17T16:44:41.219717Z","end":"2026-07-17T16:44:41.676396Z","steps":["trace[688498177] 'process raft request' (duration: 456.337816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.67666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.317189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:41.676726Z","caller":"traceutil/trace.go:171","msg":"trace[219044790] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24997; }","duration":"236.444911ms","start":"2026-07-17T16:44:41.440258Z","end":"2026-07-17T16:44:41.676703Z","steps":["trace[219044790] 'agreement among raft nodes before linearized reading' (duration: 236.270436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.676717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:41.2197Z","time spent":"456.887893ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:41.676903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.49693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:44:41.676993Z","caller":"traceutil/trace.go:171","msg":"trace[953388592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24997; }","duration":"340.638084ms","start":"2026-07-17T16:44:41.336331Z","end":"2026-07-17T16:44:41.676969Z","steps":["trace[953388592] 'agreement among raft nodes before linearized reading' (duration: 340.172631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:41.677042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:41.336311Z","time spent":"340.721077ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T16:44:42.126305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.439239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:44:42.126417Z","caller":"traceutil/trace.go:171","msg":"trace[560377051] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"444.890308ms","start":"2026-07-17T16:44:41.681511Z","end":"2026-07-17T16:44:42.126402Z","steps":["trace[560377051] 'process raft request' (duration: 175.258444ms)","trace[560377051] 'compare' (duration: 269.155981ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:42.126415Z","caller":"traceutil/trace.go:171","msg":"trace[305527579] linearizableReadLoop","detail":"{readStateIndex:25810; appliedIndex:25809; }","duration":"269.235033ms","start":"2026-07-17T16:44:41.85716Z","end":"2026-07-17T16:44:42.126395Z","steps":["trace[305527579] 'read index received' (duration: 56.162µs)","trace[305527579] 'applied index is now lower than readState.Index' (duration: 269.177091ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:42.126467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:41.681488Z","time spent":"444.956341ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:42.126513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.356996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:42.126561Z","caller":"traceutil/trace.go:171","msg":"trace[1190874595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24999; }","duration":"269.440538ms","start":"2026-07-17T16:44:41.857107Z","end":"2026-07-17T16:44:42.126547Z","steps":["trace[1190874595] 'agreement among raft nodes before linearized reading' (duration: 269.367046ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:42.136906Z","caller":"traceutil/trace.go:171","msg":"trace[1338968768] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"258.891096ms","start":"2026-07-17T16:44:41.877994Z","end":"2026-07-17T16:44:42.136886Z","steps":["trace[1338968768] 'process raft request' (duration: 258.766422ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:42.299709Z","caller":"traceutil/trace.go:171","msg":"trace[2021809523] linearizableReadLoop","detail":"{readStateIndex:25812; appliedIndex:25810; }","duration":"173.239136ms","start":"2026-07-17T16:44:42.126446Z","end":"2026-07-17T16:44:42.299685Z","steps":["trace[2021809523] 'read index received' (duration: 10.399248ms)","trace[2021809523] 'applied index is now lower than readState.Index' (duration: 162.838688ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:42.299876Z","caller":"traceutil/trace.go:171","msg":"trace[829698492] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"373.516028ms","start":"2026-07-17T16:44:41.926321Z","end":"2026-07-17T16:44:42.299837Z","steps":["trace[829698492] 'process raft request' (duration: 355.986475ms)","trace[829698492] 'compare' (duration: 17.272106ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:42.299909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.104746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:42.300072Z","caller":"traceutil/trace.go:171","msg":"trace[929944955] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25001; }","duration":"334.309502ms","start":"2026-07-17T16:44:41.965746Z","end":"2026-07-17T16:44:42.300056Z","steps":["trace[929944955] 'agreement among raft nodes before linearized reading' (duration: 334.077676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:42.300073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:41.926295Z","time spent":"373.681343ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:42.300119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:41.965729Z","time spent":"334.377014ms","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-07-17T16:44:42.577904Z","caller":"traceutil/trace.go:171","msg":"trace[903218694] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"439.72242ms","start":"2026-07-17T16:44:42.138145Z","end":"2026-07-17T16:44:42.577868Z","steps":["trace[903218694] 'process raft request' (duration: 394.037728ms)","trace[903218694] 'compare' (duration: 45.303851ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:42.577938Z","caller":"traceutil/trace.go:171","msg":"trace[966157711] linearizableReadLoop","detail":"{readStateIndex:25813; appliedIndex:25812; }","duration":"278.095337ms","start":"2026-07-17T16:44:42.299795Z","end":"2026-07-17T16:44:42.57789Z","steps":["trace[966157711] 'read index received' (duration: 232.400715ms)","trace[966157711] 'applied index is now lower than readState.Index' (duration: 45.689682ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:42.578023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:42.138131Z","time spent":"439.827873ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:42.578007Z","caller":"traceutil/trace.go:171","msg":"trace[1030566004] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"154.072795ms","start":"2026-07-17T16:44:42.423909Z","end":"2026-07-17T16:44:42.577982Z","steps":["trace[1030566004] 'process raft request' (duration: 153.962302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:42.578084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.759485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:42.578123Z","caller":"traceutil/trace.go:171","msg":"trace[989835931] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:25004; }","duration":"388.854178ms","start":"2026-07-17T16:44:42.189256Z","end":"2026-07-17T16:44:42.578111Z","steps":["trace[989835931] 'agreement among raft nodes before linearized reading' (duration: 388.748325ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:42.578115Z","caller":"traceutil/trace.go:171","msg":"trace[2127437654] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"270.330753ms","start":"2026-07-17T16:44:42.30776Z","end":"2026-07-17T16:44:42.578091Z","steps":["trace[2127437654] 'process raft request' (duration: 270.050495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:42.578159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:42.189236Z","time spent":"388.914539ms","remote":"127.0.0.1:41586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-07-17T16:44:42.917155Z","caller":"traceutil/trace.go:171","msg":"trace[1786804979] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"159.442578ms","start":"2026-07-17T16:44:42.757683Z","end":"2026-07-17T16:44:42.917125Z","steps":["trace[1786804979] 'process raft request' (duration: 159.235142ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:42.935499Z","caller":"traceutil/trace.go:171","msg":"trace[66492393] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"123.009193ms","start":"2026-07-17T16:44:42.812467Z","end":"2026-07-17T16:44:42.935476Z","steps":["trace[66492393] 'process raft request' (duration: 122.843438ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:43.158328Z","caller":"traceutil/trace.go:171","msg":"trace[1456149938] linearizableReadLoop","detail":"{readStateIndex:25818; appliedIndex:25817; }","duration":"140.299301ms","start":"2026-07-17T16:44:43.018007Z","end":"2026-07-17T16:44:43.158307Z","steps":["trace[1456149938] 'read index received' (duration: 140.062333ms)","trace[1456149938] 'applied index is now lower than readState.Index' (duration: 236.228µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:43.158457Z","caller":"traceutil/trace.go:171","msg":"trace[1552816084] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"154.456077ms","start":"2026-07-17T16:44:43.003968Z","end":"2026-07-17T16:44:43.158424Z","steps":["trace[1552816084] 'process raft request' (duration: 154.205719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:43.158517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.493916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-17T16:44:43.158545Z","caller":"traceutil/trace.go:171","msg":"trace[692229877] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25007; }","duration":"140.569768ms","start":"2026-07-17T16:44:43.017965Z","end":"2026-07-17T16:44:43.158534Z","steps":["trace[692229877] 'agreement among raft nodes before linearized reading' (duration: 140.422484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:43.455637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.675449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:44:43.455798Z","caller":"traceutil/trace.go:171","msg":"trace[2078268160] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"287.714174ms","start":"2026-07-17T16:44:43.168062Z","end":"2026-07-17T16:44:43.455776Z","steps":["trace[2078268160] 'process raft request' (duration: 129.821209ms)","trace[2078268160] 'compare' (duration: 157.319399ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:43.455921Z","caller":"traceutil/trace.go:171","msg":"trace[179043] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"286.441617ms","start":"2026-07-17T16:44:43.169468Z","end":"2026-07-17T16:44:43.45591Z","steps":["trace[179043] 'process raft request' (duration: 286.403136ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:43.455867Z","caller":"traceutil/trace.go:171","msg":"trace[954998714] linearizableReadLoop","detail":"{readStateIndex:25819; appliedIndex:25818; }","duration":"287.2281ms","start":"2026-07-17T16:44:43.168572Z","end":"2026-07-17T16:44:43.4558Z","steps":["trace[954998714] 'read index received' (duration: 129.321354ms)","trace[954998714] 'applied index is now lower than readState.Index' (duration: 157.903476ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:43.456013Z","caller":"traceutil/trace.go:171","msg":"trace[2077522228] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"287.303873ms","start":"2026-07-17T16:44:43.1687Z","end":"2026-07-17T16:44:43.456004Z","steps":["trace[2077522228] 'process raft request' (duration: 287.103457ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:43.456049Z","caller":"traceutil/trace.go:171","msg":"trace[1634990680] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"287.386725ms","start":"2026-07-17T16:44:43.168623Z","end":"2026-07-17T16:44:43.456009Z","steps":["trace[1634990680] 'process raft request' (duration: 287.110597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:43.456304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.716395ms","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-07-17T16:44:43.456364Z","caller":"traceutil/trace.go:171","msg":"trace[946157143] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"286.662865ms","start":"2026-07-17T16:44:43.169374Z","end":"2026-07-17T16:44:43.456037Z","steps":["trace[946157143] 'process raft request' (duration: 286.464739ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:43.456403Z","caller":"traceutil/trace.go:171","msg":"trace[1543579007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25012; }","duration":"287.833278ms","start":"2026-07-17T16:44:43.16855Z","end":"2026-07-17T16:44:43.456384Z","steps":["trace[1543579007] 'agreement among raft nodes before linearized reading' (duration: 287.57915ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:43.787446Z","caller":"traceutil/trace.go:171","msg":"trace[897662949] linearizableReadLoop","detail":"{readStateIndex:25824; appliedIndex:25823; }","duration":"259.40085ms","start":"2026-07-17T16:44:43.528026Z","end":"2026-07-17T16:44:43.787427Z","steps":["trace[897662949] 'read index received' (duration: 234.860385ms)","trace[897662949] 'applied index is now lower than readState.Index' (duration: 24.539315ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:43.787538Z","caller":"traceutil/trace.go:171","msg":"trace[2025089757] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"325.748936ms","start":"2026-07-17T16:44:43.461777Z","end":"2026-07-17T16:44:43.787526Z","steps":["trace[2025089757] 'process raft request' (duration: 301.142939ms)","trace[2025089757] 'compare' (duration: 24.422371ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:43.78761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:43.461753Z","time spent":"325.803787ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:43.787695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.86771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-17T16:44:43.787702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.660058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:43.787761Z","caller":"traceutil/trace.go:171","msg":"trace[1707348055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25013; }","duration":"106.970552ms","start":"2026-07-17T16:44:43.680772Z","end":"2026-07-17T16:44:43.787743Z","steps":["trace[1707348055] 'agreement among raft nodes before linearized reading' (duration: 106.798007ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:43.787782Z","caller":"traceutil/trace.go:171","msg":"trace[861342793] 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:25013; }","duration":"259.787122ms","start":"2026-07-17T16:44:43.527974Z","end":"2026-07-17T16:44:43.787761Z","steps":["trace[861342793] 'agreement among raft nodes before linearized reading' (duration: 259.647438ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:44.418253Z","caller":"traceutil/trace.go:171","msg":"trace[247355005] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"112.760669ms","start":"2026-07-17T16:44:44.305464Z","end":"2026-07-17T16:44:44.418225Z","steps":["trace[247355005] 'process raft request' (duration: 105.230903ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:44.705119Z","caller":"traceutil/trace.go:171","msg":"trace[1322627200] linearizableReadLoop","detail":"{readStateIndex:25829; appliedIndex:25828; }","duration":"150.163654ms","start":"2026-07-17T16:44:44.554918Z","end":"2026-07-17T16:44:44.705082Z","steps":["trace[1322627200] 'read index received' (duration: 129.714196ms)","trace[1322627200] 'applied index is now lower than readState.Index' (duration: 20.442247ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:44.705338Z","caller":"traceutil/trace.go:171","msg":"trace[1882236424] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"293.720176ms","start":"2026-07-17T16:44:44.411597Z","end":"2026-07-17T16:44:44.705317Z","steps":["trace[1882236424] 'process raft request' (duration: 272.860997ms)","trace[1882236424] 'compare' (duration: 20.276713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:44.705548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.603266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-17T16:44:44.705617Z","caller":"traceutil/trace.go:171","msg":"trace[709850973] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25018; }","duration":"150.709629ms","start":"2026-07-17T16:44:44.554889Z","end":"2026-07-17T16:44:44.705598Z","steps":["trace[709850973] 'agreement among raft nodes before linearized reading' (duration: 150.325398ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:44.705708Z","caller":"traceutil/trace.go:171","msg":"trace[313000008] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"122.518319ms","start":"2026-07-17T16:44:44.583163Z","end":"2026-07-17T16:44:44.705681Z","steps":["trace[313000008] 'process raft request' (duration: 122.478288ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:44.705766Z","caller":"traceutil/trace.go:171","msg":"trace[1683608423] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"122.89168ms","start":"2026-07-17T16:44:44.58283Z","end":"2026-07-17T16:44:44.705722Z","steps":["trace[1683608423] 'process raft request' (duration: 122.716775ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:45.039325Z","caller":"traceutil/trace.go:171","msg":"trace[40371241] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"116.660261ms","start":"2026-07-17T16:44:44.922641Z","end":"2026-07-17T16:44:45.039301Z","steps":["trace[40371241] 'process raft request' (duration: 116.463675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:45.36887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.647291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:276055"} {"level":"info","ts":"2026-07-17T16:44:45.368971Z","caller":"traceutil/trace.go:171","msg":"trace[1885662095] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25021; }","duration":"162.759765ms","start":"2026-07-17T16:44:45.206188Z","end":"2026-07-17T16:44:45.368948Z","steps":["trace[1885662095] 'range keys from in-memory index tree' (duration: 162.111965ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:45.574864Z","caller":"traceutil/trace.go:171","msg":"trace[769017602] linearizableReadLoop","detail":"{readStateIndex:25834; appliedIndex:25833; }","duration":"111.549803ms","start":"2026-07-17T16:44:45.46329Z","end":"2026-07-17T16:44:45.574839Z","steps":["trace[769017602] 'read index received' (duration: 111.41899ms)","trace[769017602] 'applied index is now lower than readState.Index' (duration: 129.563µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:45.574974Z","caller":"traceutil/trace.go:171","msg":"trace[945784186] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"113.522959ms","start":"2026-07-17T16:44:45.461419Z","end":"2026-07-17T16:44:45.574942Z","steps":["trace[945784186] 'process raft request' (duration: 113.224651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:45.575076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.75945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-17T16:44:45.575117Z","caller":"traceutil/trace.go:171","msg":"trace[792215021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25022; }","duration":"111.820962ms","start":"2026-07-17T16:44:45.463284Z","end":"2026-07-17T16:44:45.575105Z","steps":["trace[792215021] 'agreement among raft nodes before linearized reading' (duration: 111.649317ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:45.878768Z","caller":"traceutil/trace.go:171","msg":"trace[266365764] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"415.391162ms","start":"2026-07-17T16:44:45.463353Z","end":"2026-07-17T16:44:45.878744Z","steps":["trace[266365764] 'process raft request' (duration: 365.159499ms)","trace[266365764] 'compare' (duration: 50.087639ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:45.878882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:45.463343Z","time spent":"415.481664ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:45.878996Z","caller":"traceutil/trace.go:171","msg":"trace[1175618426] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"414.677021ms","start":"2026-07-17T16:44:45.464298Z","end":"2026-07-17T16:44:45.878975Z","steps":["trace[1175618426] 'process raft request' (duration: 414.594068ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:45.879069Z","caller":"traceutil/trace.go:171","msg":"trace[2030363750] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"415.582777ms","start":"2026-07-17T16:44:45.463462Z","end":"2026-07-17T16:44:45.879045Z","steps":["trace[2030363750] 'process raft request' (duration: 415.247277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:45.879118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:45.464274Z","time spent":"414.784723ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:45.879172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:45.463456Z","time spent":"415.66292ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:45.988239Z","caller":"traceutil/trace.go:171","msg":"trace[276575177] linearizableReadLoop","detail":"{readStateIndex:25838; appliedIndex:25834; }","duration":"196.082762ms","start":"2026-07-17T16:44:45.792129Z","end":"2026-07-17T16:44:45.988212Z","steps":["trace[276575177] 'read index received' (duration: 36.356584ms)","trace[276575177] 'applied index is now lower than readState.Index' (duration: 159.724948ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:45.988447Z","caller":"traceutil/trace.go:171","msg":"trace[1341424691] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"409.830072ms","start":"2026-07-17T16:44:45.578599Z","end":"2026-07-17T16:44:45.988429Z","steps":["trace[1341424691] 'process raft request' (duration: 408.415861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:45.988605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.276143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-17T16:44:45.988671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:45.578581Z","time spent":"409.960656ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:45.988684Z","caller":"traceutil/trace.go:171","msg":"trace[331549447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25026; }","duration":"184.400646ms","start":"2026-07-17T16:44:45.804267Z","end":"2026-07-17T16:44:45.988668Z","steps":["trace[331549447] 'agreement among raft nodes before linearized reading' (duration: 184.18341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:45.988923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.789432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-17T16:44:45.988962Z","caller":"traceutil/trace.go:171","msg":"trace[376349266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25026; }","duration":"196.856954ms","start":"2026-07-17T16:44:45.792096Z","end":"2026-07-17T16:44:45.988953Z","steps":["trace[376349266] 'agreement among raft nodes before linearized reading' (duration: 196.762271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:45.989062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.504207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:44:45.989095Z","caller":"traceutil/trace.go:171","msg":"trace[69959264] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25026; }","duration":"139.539898ms","start":"2026-07-17T16:44:45.849546Z","end":"2026-07-17T16:44:45.989086Z","steps":["trace[69959264] 'agreement among raft nodes before linearized reading' (duration: 139.485056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:45.989323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.620173ms","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-07-17T16:44:45.989387Z","caller":"traceutil/trace.go:171","msg":"trace[491821893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25026; }","duration":"183.722106ms","start":"2026-07-17T16:44:45.805651Z","end":"2026-07-17T16:44:45.989373Z","steps":["trace[491821893] 'agreement among raft nodes before linearized reading' (duration: 183.529231ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:46.354584Z","caller":"traceutil/trace.go:171","msg":"trace[1203454177] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"358.868608ms","start":"2026-07-17T16:44:45.995689Z","end":"2026-07-17T16:44:46.354557Z","steps":["trace[1203454177] 'process raft request' (duration: 327.490637ms)","trace[1203454177] 'compare' (duration: 31.154904ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:46.354722Z","caller":"traceutil/trace.go:171","msg":"trace[672037071] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25839; }","duration":"137.801159ms","start":"2026-07-17T16:44:46.216899Z","end":"2026-07-17T16:44:46.354701Z","steps":["trace[672037071] 'read index received' (duration: 106.255302ms)","trace[672037071] 'applied index is now lower than readState.Index' (duration: 31.544937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:46.354756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:45.995665Z","time spent":"358.989792ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:46.355027Z","caller":"traceutil/trace.go:171","msg":"trace[43370929] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"357.54504ms","start":"2026-07-17T16:44:45.997447Z","end":"2026-07-17T16:44:46.354992Z","steps":["trace[43370929] 'process raft request' (duration: 357.069455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:46.355162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.268691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-17T16:44:46.355209Z","caller":"traceutil/trace.go:171","msg":"trace[423926847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25029; }","duration":"138.345214ms","start":"2026-07-17T16:44:46.216851Z","end":"2026-07-17T16:44:46.355196Z","steps":["trace[423926847] 'agreement among raft nodes before linearized reading' (duration: 138.245571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:46.355216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:45.997435Z","time spent":"357.682193ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:46.664365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.995445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:44:46.664526Z","caller":"traceutil/trace.go:171","msg":"trace[101439797] linearizableReadLoop","detail":"{readStateIndex:25843; appliedIndex:25841; }","duration":"124.566898ms","start":"2026-07-17T16:44:46.539942Z","end":"2026-07-17T16:44:46.664509Z","steps":["trace[101439797] 'read index received' (duration: 108.753163ms)","trace[101439797] 'applied index is now lower than readState.Index' (duration: 15.812675ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:46.664574Z","caller":"traceutil/trace.go:171","msg":"trace[592372441] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"304.987121ms","start":"2026-07-17T16:44:46.359553Z","end":"2026-07-17T16:44:46.66454Z","steps":["trace[592372441] 'process raft request' (duration: 151.740238ms)","trace[592372441] 'compare' (duration: 152.888812ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:46.664704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.773194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:44:46.664653Z","caller":"traceutil/trace.go:171","msg":"trace[1109856472] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"240.403846ms","start":"2026-07-17T16:44:46.424186Z","end":"2026-07-17T16:44:46.66459Z","steps":["trace[1109856472] 'process raft request' (duration: 240.269912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:46.664718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:46.35953Z","time spent":"305.108513ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:46.664739Z","caller":"traceutil/trace.go:171","msg":"trace[1502028780] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:25031; }","duration":"124.852676ms","start":"2026-07-17T16:44:46.539875Z","end":"2026-07-17T16:44:46.664727Z","steps":["trace[1502028780] 'agreement among raft nodes before linearized reading' (duration: 124.705332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:46.97232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.393354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:44:46.972431Z","caller":"traceutil/trace.go:171","msg":"trace[1741036040] linearizableReadLoop","detail":"{readStateIndex:25845; appliedIndex:25844; }","duration":"119.547494ms","start":"2026-07-17T16:44:46.852866Z","end":"2026-07-17T16:44:46.972414Z","steps":["trace[1741036040] 'read index received' (duration: 44.061µs)","trace[1741036040] 'applied index is now lower than readState.Index' (duration: 119.501953ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:46.97252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.663187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:46.972545Z","caller":"traceutil/trace.go:171","msg":"trace[632892637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25033; }","duration":"119.728939ms","start":"2026-07-17T16:44:46.852807Z","end":"2026-07-17T16:44:46.972536Z","steps":["trace[632892637] 'agreement among raft nodes before linearized reading' (duration: 119.654237ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:46.972776Z","caller":"traceutil/trace.go:171","msg":"trace[279043708] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"262.147739ms","start":"2026-07-17T16:44:46.710585Z","end":"2026-07-17T16:44:46.972733Z","steps":["trace[279043708] 'process raft request' (duration: 114.252671ms)","trace[279043708] 'compare' (duration: 147.273441ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:47.3263Z","caller":"traceutil/trace.go:171","msg":"trace[363986216] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"281.771483ms","start":"2026-07-17T16:44:47.04451Z","end":"2026-07-17T16:44:47.326281Z","steps":["trace[363986216] 'process raft request' (duration: 281.634099ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:47.346496Z","caller":"traceutil/trace.go:171","msg":"trace[750342529] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"202.622091ms","start":"2026-07-17T16:44:47.143851Z","end":"2026-07-17T16:44:47.346473Z","steps":["trace[750342529] 'process raft request' (duration: 202.480437ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:47.949148Z","caller":"traceutil/trace.go:171","msg":"trace[1570109667] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"294.957712ms","start":"2026-07-17T16:44:47.654167Z","end":"2026-07-17T16:44:47.949125Z","steps":["trace[1570109667] 'process raft request' (duration: 294.795158ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:48.13487Z","caller":"traceutil/trace.go:171","msg":"trace[2008900681] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"138.922261ms","start":"2026-07-17T16:44:47.995909Z","end":"2026-07-17T16:44:48.134831Z","steps":["trace[2008900681] 'process raft request' (duration: 125.356651ms)","trace[2008900681] 'compare' (duration: 12.88472ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:56.407035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.881087ms","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-07-17T16:44:56.40705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.247219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T16:44:56.407115Z","caller":"traceutil/trace.go:171","msg":"trace[1495473589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25098; }","duration":"241.99658ms","start":"2026-07-17T16:44:56.165093Z","end":"2026-07-17T16:44:56.407089Z","steps":["trace[1495473589] 'range keys from in-memory index tree' (duration: 241.771814ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:56.407122Z","caller":"traceutil/trace.go:171","msg":"trace[469311704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25098; }","duration":"194.356723ms","start":"2026-07-17T16:44:56.212748Z","end":"2026-07-17T16:44:56.407105Z","steps":["trace[469311704] 'range keys from in-memory index tree' (duration: 194.107765ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:56.522028Z","caller":"traceutil/trace.go:171","msg":"trace[684974861] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"110.657038ms","start":"2026-07-17T16:44:56.41134Z","end":"2026-07-17T16:44:56.521997Z","steps":["trace[684974861] 'process raft request' (duration: 110.484924ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:56.920477Z","caller":"traceutil/trace.go:171","msg":"trace[1610022823] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"508.762922ms","start":"2026-07-17T16:44:56.411653Z","end":"2026-07-17T16:44:56.920416Z","steps":["trace[1610022823] 'process raft request' (duration: 430.37347ms)","trace[1610022823] 'compare' (duration: 77.797945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:56.920649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.411644Z","time spent":"508.919847ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:56.920842Z","caller":"traceutil/trace.go:171","msg":"trace[454101523] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"509.067521ms","start":"2026-07-17T16:44:56.411735Z","end":"2026-07-17T16:44:56.920802Z","steps":["trace[454101523] 'process raft request' (duration: 508.544516ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:56.920885Z","caller":"traceutil/trace.go:171","msg":"trace[745592802] linearizableReadLoop","detail":"{readStateIndex:25916; appliedIndex:25913; }","duration":"356.350235ms","start":"2026-07-17T16:44:56.564515Z","end":"2026-07-17T16:44:56.920865Z","steps":["trace[745592802] 'read index received' (duration: 277.557502ms)","trace[745592802] 'applied index is now lower than readState.Index' (duration: 78.790633ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:56.921036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.411719Z","time spent":"509.221945ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:56.921113Z","caller":"traceutil/trace.go:171","msg":"trace[2064683160] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"509.063551ms","start":"2026-07-17T16:44:56.412032Z","end":"2026-07-17T16:44:56.921095Z","steps":["trace[2064683160] 'process raft request' (duration: 508.65416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:56.921269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.412006Z","time spent":"509.179524ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:56.921281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.733846ms","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-07-17T16:44:56.921507Z","caller":"traceutil/trace.go:171","msg":"trace[123001279] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25102; }","duration":"357.014515ms","start":"2026-07-17T16:44:56.564465Z","end":"2026-07-17T16:44:56.921479Z","steps":["trace[123001279] 'agreement among raft nodes before linearized reading' (duration: 356.721516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:56.921593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.564445Z","time spent":"357.119237ms","remote":"127.0.0.1:33534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:44:57.421777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259574944848911193,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T16:44:57.526843Z","caller":"traceutil/trace.go:171","msg":"trace[1177426286] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"801.327636ms","start":"2026-07-17T16:44:56.725488Z","end":"2026-07-17T16:44:57.526816Z","steps":["trace[1177426286] 'process raft request' (duration: 788.665612ms)","trace[1177426286] 'compare' (duration: 11.486249ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:57.527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.725466Z","time spent":"801.442879ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:57.527341Z","caller":"traceutil/trace.go:171","msg":"trace[1165760288] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"735.252569ms","start":"2026-07-17T16:44:56.792075Z","end":"2026-07-17T16:44:57.527328Z","steps":["trace[1165760288] 'process raft request' (duration: 734.675813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.527554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.792057Z","time spent":"735.467005ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:44:57.529559Z","caller":"traceutil/trace.go:171","msg":"trace[1179700767] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"161.687894ms","start":"2026-07-17T16:44:57.367845Z","end":"2026-07-17T16:44:57.529533Z","steps":["trace[1179700767] 'process raft request' (duration: 161.480237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.52977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.34235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:44:57.529784Z","caller":"traceutil/trace.go:171","msg":"trace[228561873] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"169.434307ms","start":"2026-07-17T16:44:57.360337Z","end":"2026-07-17T16:44:57.529771Z","steps":["trace[228561873] 'process raft request' (duration: 168.89842ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:44:57.529764Z","caller":"traceutil/trace.go:171","msg":"trace[1859491589] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"519.970495ms","start":"2026-07-17T16:44:57.009759Z","end":"2026-07-17T16:44:57.529729Z","steps":["trace[1859491589] 'process raft request' (duration: 519.227733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.529772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.548765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:44:57.529814Z","caller":"traceutil/trace.go:171","msg":"trace[477147799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25107; }","duration":"783.405151ms","start":"2026-07-17T16:44:56.746396Z","end":"2026-07-17T16:44:57.529801Z","steps":["trace[477147799] 'agreement among raft nodes before linearized reading' (duration: 783.279848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.529844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.746386Z","time spent":"783.450433ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T16:44:57.52985Z","caller":"traceutil/trace.go:171","msg":"trace[2078614620] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25107; }","duration":"674.674638ms","start":"2026-07-17T16:44:56.855157Z","end":"2026-07-17T16:44:57.529832Z","steps":["trace[2078614620] 'agreement among raft nodes before linearized reading' (duration: 674.530944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.529914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.855137Z","time spent":"674.766321ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:44:57.529927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:57.009739Z","time spent":"520.097038ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:44:57.52993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.369244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:57.529986Z","caller":"traceutil/trace.go:171","msg":"trace[821887907] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25107; }","duration":"788.469787ms","start":"2026-07-17T16:44:56.741498Z","end":"2026-07-17T16:44:57.529968Z","steps":["trace[821887907] 'agreement among raft nodes before linearized reading' (duration: 788.371944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.530023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.741475Z","time spent":"788.538909ms","remote":"127.0.0.1:38072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:44:57.530024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.402613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:44:57.530064Z","caller":"traceutil/trace.go:171","msg":"trace[530922051] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25107; }","duration":"695.468047ms","start":"2026-07-17T16:44:56.834586Z","end":"2026-07-17T16:44:57.530054Z","steps":["trace[530922051] 'agreement among raft nodes before linearized reading' (duration: 695.329653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.530097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.834568Z","time spent":"695.520678ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":92,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:44:57.530136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.929855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:57.530321Z","caller":"traceutil/trace.go:171","msg":"trace[787571148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25107; }","duration":"675.07238ms","start":"2026-07-17T16:44:56.855157Z","end":"2026-07-17T16:44:57.530229Z","steps":["trace[787571148] 'agreement among raft nodes before linearized reading' (duration: 674.409901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.530397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:56.855144Z","time spent":"675.216383ms","remote":"127.0.0.1:56040","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-07-17T16:44:57.530772Z","caller":"traceutil/trace.go:171","msg":"trace[752854177] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25916; }","duration":"608.488718ms","start":"2026-07-17T16:44:56.921013Z","end":"2026-07-17T16:44:57.529501Z","steps":["trace[752854177] 'read index received' (duration: 593.127516ms)","trace[752854177] 'applied index is now lower than readState.Index' (duration: 15.358742ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:44:57.531009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.091716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:44:57.531038Z","caller":"traceutil/trace.go:171","msg":"trace[813632461] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25107; }","duration":"139.175198ms","start":"2026-07-17T16:44:57.391855Z","end":"2026-07-17T16:44:57.53103Z","steps":["trace[813632461] 'agreement among raft nodes before linearized reading' (duration: 139.107866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.531416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.042358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-07-17T16:44:57.531489Z","caller":"traceutil/trace.go:171","msg":"trace[667071575] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25107; }","duration":"495.139061ms","start":"2026-07-17T16:44:57.036333Z","end":"2026-07-17T16:44:57.531472Z","steps":["trace[667071575] 'agreement among raft nodes before linearized reading' (duration: 494.547794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.53194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:44:57.036315Z","time spent":"495.189933ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-07-17T16:44:57.70571Z","caller":"traceutil/trace.go:171","msg":"trace[1534799242] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"171.011662ms","start":"2026-07-17T16:44:57.534663Z","end":"2026-07-17T16:44:57.705675Z","steps":["trace[1534799242] 'process raft request' (duration: 107.448797ms)","trace[1534799242] 'compare' (duration: 63.223816ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:57.961544Z","caller":"traceutil/trace.go:171","msg":"trace[1862586428] linearizableReadLoop","detail":"{readStateIndex:25923; appliedIndex:25922; }","duration":"105.893792ms","start":"2026-07-17T16:44:57.85562Z","end":"2026-07-17T16:44:57.961514Z","steps":["trace[1862586428] 'read index received' (duration: 105.178792ms)","trace[1862586428] 'applied index is now lower than readState.Index' (duration: 713.31µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:44:57.961699Z","caller":"traceutil/trace.go:171","msg":"trace[92294059] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"280.208208ms","start":"2026-07-17T16:44:57.68144Z","end":"2026-07-17T16:44:57.961648Z","steps":["trace[92294059] 'process raft request' (duration: 279.429306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:44:57.961968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.329574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:44:57.962016Z","caller":"traceutil/trace.go:171","msg":"trace[335952643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25109; }","duration":"106.436407ms","start":"2026-07-17T16:44:57.855566Z","end":"2026-07-17T16:44:57.962003Z","steps":["trace[335952643] 'agreement among raft nodes before linearized reading' (duration: 106.085957ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:03.427305Z","caller":"traceutil/trace.go:171","msg":"trace[2074972546] linearizableReadLoop","detail":"{readStateIndex:26022; appliedIndex:26020; }","duration":"336.934377ms","start":"2026-07-17T16:45:03.090349Z","end":"2026-07-17T16:45:03.427283Z","steps":["trace[2074972546] 'read index received' (duration: 336.710471ms)","trace[2074972546] 'applied index is now lower than readState.Index' (duration: 220.506µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:03.427366Z","caller":"traceutil/trace.go:171","msg":"trace[1445582303] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"341.662374ms","start":"2026-07-17T16:45:03.085685Z","end":"2026-07-17T16:45:03.427347Z","steps":["trace[1445582303] 'process raft request' (duration: 341.51066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:03.427453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.085671Z","time spent":"341.726695ms","remote":"127.0.0.1:56544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:45:03.427486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.501741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-07-17T16:45:03.427471Z","caller":"traceutil/trace.go:171","msg":"trace[1803074090] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"346.724019ms","start":"2026-07-17T16:45:03.080695Z","end":"2026-07-17T16:45:03.427419Z","steps":["trace[1803074090] 'process raft request' (duration: 346.363869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:03.427649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.588393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"warn","ts":"2026-07-17T16:45:03.42766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.080686Z","time spent":"346.893663ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":864,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T16:45:03.427681Z","caller":"traceutil/trace.go:171","msg":"trace[1455710380] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:25207; }","duration":"340.645924ms","start":"2026-07-17T16:45:03.087028Z","end":"2026-07-17T16:45:03.427674Z","steps":["trace[1455710380] 'agreement among raft nodes before linearized reading' (duration: 340.399297ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:03.427522Z","caller":"traceutil/trace.go:171","msg":"trace[517673434] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25207; }","duration":"339.570144ms","start":"2026-07-17T16:45:03.087941Z","end":"2026-07-17T16:45:03.427511Z","steps":["trace[517673434] 'agreement among raft nodes before linearized reading' (duration: 339.45218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:03.427743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.087019Z","time spent":"340.716567ms","remote":"127.0.0.1:56234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1308,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-07-17T16:45:03.427763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.087922Z","time spent":"339.82483ms","remote":"127.0.0.1:56130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1334,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-07-17T16:45:03.462548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.600845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-17T16:45:03.46259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.786127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:45:03.462637Z","caller":"traceutil/trace.go:171","msg":"trace[1458843868] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:25208; }","duration":"369.730019ms","start":"2026-07-17T16:45:03.092888Z","end":"2026-07-17T16:45:03.462618Z","steps":["trace[1458843868] 'agreement among raft nodes before linearized reading' (duration: 369.557324ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:03.462674Z","caller":"traceutil/trace.go:171","msg":"trace[1127728015] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25208; }","duration":"317.91054ms","start":"2026-07-17T16:45:03.144742Z","end":"2026-07-17T16:45:03.462653Z","steps":["trace[1127728015] 'agreement among raft nodes before linearized reading' (duration: 317.762346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:03.462694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.092878Z","time spent":"369.808832ms","remote":"127.0.0.1:56114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" "} {"level":"warn","ts":"2026-07-17T16:45:03.462733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.144728Z","time spent":"317.992274ms","remote":"127.0.0.1:41658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:45:03.462734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.405765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224987"} {"level":"info","ts":"2026-07-17T16:45:03.462787Z","caller":"traceutil/trace.go:171","msg":"trace[1780900063] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:25208; }","duration":"217.497108ms","start":"2026-07-17T16:45:03.245277Z","end":"2026-07-17T16:45:03.462774Z","steps":["trace[1780900063] 'agreement among raft nodes before linearized reading' (duration: 216.727085ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:03.797705Z","caller":"traceutil/trace.go:171","msg":"trace[374188145] linearizableReadLoop","detail":"{readStateIndex:26025; appliedIndex:26024; }","duration":"291.709969ms","start":"2026-07-17T16:45:03.505979Z","end":"2026-07-17T16:45:03.797689Z","steps":["trace[374188145] 'read index received' (duration: 203.101543ms)","trace[374188145] 'applied index is now lower than readState.Index' (duration: 88.605846ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:03.79777Z","caller":"traceutil/trace.go:171","msg":"trace[1098328920] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"333.16985ms","start":"2026-07-17T16:45:03.464594Z","end":"2026-07-17T16:45:03.797764Z","steps":["trace[1098328920] 'process raft request' (duration: 244.358809ms)","trace[1098328920] 'compare' (duration: 88.545764ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:03.797821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.464579Z","time spent":"333.204731ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2560,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T16:45:03.797994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.96071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-07-17T16:45:03.798051Z","caller":"traceutil/trace.go:171","msg":"trace[941363988] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:25210; }","duration":"305.060783ms","start":"2026-07-17T16:45:03.492972Z","end":"2026-07-17T16:45:03.798033Z","steps":["trace[941363988] 'agreement among raft nodes before linearized reading' (duration: 304.869177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:03.79808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.492949Z","time spent":"305.123864ms","remote":"127.0.0.1:56130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1844,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-07-17T16:45:04.078807Z","caller":"traceutil/trace.go:171","msg":"trace[1554221163] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"567.430527ms","start":"2026-07-17T16:45:03.511353Z","end":"2026-07-17T16:45:04.078784Z","steps":["trace[1554221163] 'process raft request' (duration: 543.199982ms)","trace[1554221163] 'compare' (duration: 23.452833ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:04.078924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.511335Z","time spent":"567.53638ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T16:45:04.079199Z","caller":"traceutil/trace.go:171","msg":"trace[404611847] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"531.163186ms","start":"2026-07-17T16:45:03.548024Z","end":"2026-07-17T16:45:04.079187Z","steps":["trace[404611847] 'process raft request' (duration: 530.111656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.079322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.548003Z","time spent":"531.27568ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:04.079461Z","caller":"traceutil/trace.go:171","msg":"trace[1025074865] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"530.765736ms","start":"2026-07-17T16:45:03.548684Z","end":"2026-07-17T16:45:04.07945Z","steps":["trace[1025074865] 'process raft request' (duration: 529.517199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.079523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.548676Z","time spent":"530.816427ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:04.079651Z","caller":"traceutil/trace.go:171","msg":"trace[502724343] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"530.832167ms","start":"2026-07-17T16:45:03.54881Z","end":"2026-07-17T16:45:04.079642Z","steps":["trace[502724343] 'process raft request' (duration: 529.431287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.079711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.548784Z","time spent":"530.898119ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:04.079832Z","caller":"traceutil/trace.go:171","msg":"trace[1603192265] transaction","detail":"{read_only:false; response_revision:25215; number_of_response:1; }","duration":"530.960771ms","start":"2026-07-17T16:45:03.548862Z","end":"2026-07-17T16:45:04.079823Z","steps":["trace[1603192265] 'process raft request' (duration: 529.412377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.079893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.548838Z","time spent":"531.024553ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:04.08003Z","caller":"traceutil/trace.go:171","msg":"trace[1872515637] linearizableReadLoop","detail":"{readStateIndex:26030; appliedIndex:26025; }","duration":"282.274857ms","start":"2026-07-17T16:45:03.797746Z","end":"2026-07-17T16:45:04.080021Z","steps":["trace[1872515637] 'read index received' (duration: 256.824716ms)","trace[1872515637] 'applied index is now lower than readState.Index' (duration: 25.448801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:04.080656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.470885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:45:04.080702Z","caller":"traceutil/trace.go:171","msg":"trace[345490315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25219; }","duration":"351.553107ms","start":"2026-07-17T16:45:03.729138Z","end":"2026-07-17T16:45:04.080691Z","steps":["trace[345490315] 'agreement among raft nodes before linearized reading' (duration: 351.421983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.080739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.729118Z","time spent":"351.613849ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T16:45:04.080923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.704374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-07-17T16:45:04.08096Z","caller":"traceutil/trace.go:171","msg":"trace[1291645214] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25219; }","duration":"560.753545ms","start":"2026-07-17T16:45:03.520198Z","end":"2026-07-17T16:45:04.080951Z","steps":["trace[1291645214] 'agreement among raft nodes before linearized reading' (duration: 560.657023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.080994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.520192Z","time spent":"560.794947ms","remote":"127.0.0.1:56544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9626,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"warn","ts":"2026-07-17T16:45:04.081187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.221218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4727"} {"level":"info","ts":"2026-07-17T16:45:04.081223Z","caller":"traceutil/trace.go:171","msg":"trace[1027632064] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:25219; }","duration":"562.287209ms","start":"2026-07-17T16:45:03.518927Z","end":"2026-07-17T16:45:04.081214Z","steps":["trace[1027632064] 'agreement among raft nodes before linearized reading' (duration: 562.192876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.081257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.518911Z","time spent":"562.338631ms","remote":"127.0.0.1:39114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4749,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-07-17T16:45:04.081452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.807675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1448"} {"level":"info","ts":"2026-07-17T16:45:04.081486Z","caller":"traceutil/trace.go:171","msg":"trace[2096119010] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25219; }","duration":"569.862557ms","start":"2026-07-17T16:45:03.511616Z","end":"2026-07-17T16:45:04.081478Z","steps":["trace[2096119010] 'agreement among raft nodes before linearized reading' (duration: 569.775175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.081535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:03.511608Z","time spent":"569.921049ms","remote":"127.0.0.1:56234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":1470,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-17T16:45:04.081737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.29469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e007f3c-5195-456b-b6d3-dd793a5e8c51\" ","response":"range_response_count:1 size:2578"} {"level":"info","ts":"2026-07-17T16:45:04.081772Z","caller":"traceutil/trace.go:171","msg":"trace[1788323139] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e007f3c-5195-456b-b6d3-dd793a5e8c51; range_end:; response_count:1; response_revision:25219; }","duration":"280.350551ms","start":"2026-07-17T16:45:03.801413Z","end":"2026-07-17T16:45:04.081764Z","steps":["trace[1788323139] 'agreement among raft nodes before linearized reading' (duration: 280.255999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.082818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.449559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-07-17T16:45:04.082862Z","caller":"traceutil/trace.go:171","msg":"trace[1757076785] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:25219; }","duration":"254.53042ms","start":"2026-07-17T16:45:03.828321Z","end":"2026-07-17T16:45:04.082851Z","steps":["trace[1757076785] 'agreement among raft nodes before linearized reading' (duration: 254.423627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.083041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.789103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e007f3c-5195-456b-b6d3-dd793a5e8c51\" ","response":"range_response_count:1 size:2578"} {"level":"info","ts":"2026-07-17T16:45:04.083078Z","caller":"traceutil/trace.go:171","msg":"trace[438866024] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e007f3c-5195-456b-b6d3-dd793a5e8c51; range_end:; response_count:1; response_revision:25219; }","duration":"266.844774ms","start":"2026-07-17T16:45:03.816223Z","end":"2026-07-17T16:45:04.083068Z","steps":["trace[438866024] 'agreement among raft nodes before linearized reading' (duration: 266.760162ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:04.254874Z","caller":"traceutil/trace.go:171","msg":"trace[1766035943] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"167.225603ms","start":"2026-07-17T16:45:04.087576Z","end":"2026-07-17T16:45:04.254802Z","steps":["trace[1766035943] 'process raft request' (duration: 104.170552ms)","trace[1766035943] 'compare' (duration: 62.915407ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:04.311898Z","caller":"traceutil/trace.go:171","msg":"trace[1617487673] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"223.231693ms","start":"2026-07-17T16:45:04.088631Z","end":"2026-07-17T16:45:04.311863Z","steps":["trace[1617487673] 'process raft request' (duration: 223.025287ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:04.312008Z","caller":"traceutil/trace.go:171","msg":"trace[2023988976] linearizableReadLoop","detail":"{readStateIndex:26036; appliedIndex:26034; }","duration":"223.039377ms","start":"2026-07-17T16:45:04.088944Z","end":"2026-07-17T16:45:04.311983Z","steps":["trace[2023988976] 'read index received' (duration: 102.832524ms)","trace[2023988976] 'applied index is now lower than readState.Index' (duration: 120.204953ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:04.31208Z","caller":"traceutil/trace.go:171","msg":"trace[588044669] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"212.630428ms","start":"2026-07-17T16:45:04.099443Z","end":"2026-07-17T16:45:04.312073Z","steps":["trace[588044669] 'process raft request' (duration: 212.318439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.312182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.215802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:45:04.312211Z","caller":"traceutil/trace.go:171","msg":"trace[319959258] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25222; }","duration":"223.262343ms","start":"2026-07-17T16:45:04.08894Z","end":"2026-07-17T16:45:04.312202Z","steps":["trace[319959258] 'agreement among raft nodes before linearized reading' (duration: 223.163401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.312419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.743208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:45:04.312451Z","caller":"traceutil/trace.go:171","msg":"trace[213171848] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25222; }","duration":"105.81981ms","start":"2026-07-17T16:45:04.206621Z","end":"2026-07-17T16:45:04.312441Z","steps":["trace[213171848] 'agreement among raft nodes before linearized reading' (duration: 105.683116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.312456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.294836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"warn","ts":"2026-07-17T16:45:04.312464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.512115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-07-17T16:45:04.312508Z","caller":"traceutil/trace.go:171","msg":"trace[533579456] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25222; }","duration":"222.366388ms","start":"2026-07-17T16:45:04.090128Z","end":"2026-07-17T16:45:04.312494Z","steps":["trace[533579456] 'agreement among raft nodes before linearized reading' (duration: 222.183672ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:04.312516Z","caller":"traceutil/trace.go:171","msg":"trace[1613688753] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25222; }","duration":"211.581158ms","start":"2026-07-17T16:45:04.100922Z","end":"2026-07-17T16:45:04.312503Z","steps":["trace[1613688753] 'agreement among raft nodes before linearized reading' (duration: 211.363262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:04.312641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.168461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-07-17T16:45:04.31273Z","caller":"traceutil/trace.go:171","msg":"trace[1878714571] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:25222; }","duration":"223.283074ms","start":"2026-07-17T16:45:04.089427Z","end":"2026-07-17T16:45:04.31271Z","steps":["trace[1878714571] 'agreement among raft nodes before linearized reading' (duration: 222.853202ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:05.065898Z","caller":"traceutil/trace.go:171","msg":"trace[558172435] transaction","detail":"{read_only:false; response_revision:25241; number_of_response:1; }","duration":"108.057204ms","start":"2026-07-17T16:45:04.95781Z","end":"2026-07-17T16:45:05.065867Z","steps":["trace[558172435] 'process raft request' (duration: 107.977952ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:05.066043Z","caller":"traceutil/trace.go:171","msg":"trace[2059912457] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"108.978581ms","start":"2026-07-17T16:45:04.957044Z","end":"2026-07-17T16:45:05.066023Z","steps":["trace[2059912457] 'process raft request' (duration: 108.577929ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:10.704091Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000660c.wal"} {"level":"warn","ts":"2026-07-17T16:45:16.574896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.659887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:45:16.57509Z","caller":"traceutil/trace.go:171","msg":"trace[1598136768] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"447.179423ms","start":"2026-07-17T16:45:16.127888Z","end":"2026-07-17T16:45:16.575067Z","steps":["trace[1598136768] 'process raft request' (duration: 202.265389ms)","trace[1598136768] 'compare' (duration: 244.489762ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:16.575207Z","caller":"traceutil/trace.go:171","msg":"trace[625713132] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"443.450006ms","start":"2026-07-17T16:45:16.131739Z","end":"2026-07-17T16:45:16.575189Z","steps":["trace[625713132] 'process raft request' (duration: 443.354933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:16.575248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:16.127861Z","time spent":"447.287916ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:16.575233Z","caller":"traceutil/trace.go:171","msg":"trace[1091110979] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"445.390322ms","start":"2026-07-17T16:45:16.129803Z","end":"2026-07-17T16:45:16.575193Z","steps":["trace[1091110979] 'process raft request' (duration: 445.193706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:16.575304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:16.131725Z","time spent":"443.544159ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:45:16.57537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:16.129789Z","time spent":"445.511336ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:16.575485Z","caller":"traceutil/trace.go:171","msg":"trace[175678290] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"443.484207ms","start":"2026-07-17T16:45:16.13199Z","end":"2026-07-17T16:45:16.575474Z","steps":["trace[175678290] 'process raft request' (duration: 443.145827ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:16.575487Z","caller":"traceutil/trace.go:171","msg":"trace[269134703] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"443.122607ms","start":"2026-07-17T16:45:16.132341Z","end":"2026-07-17T16:45:16.575464Z","steps":["trace[269134703] 'process raft request' (duration: 442.971332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:16.575523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:16.13198Z","time spent":"443.521939ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:16.575809Z","caller":"traceutil/trace.go:171","msg":"trace[1118510378] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"442.560741ms","start":"2026-07-17T16:45:16.133238Z","end":"2026-07-17T16:45:16.575799Z","steps":["trace[1118510378] 'process raft request' (duration: 442.190151ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:16.575859Z","caller":"traceutil/trace.go:171","msg":"trace[1186943017] linearizableReadLoop","detail":"{readStateIndex:26182; appliedIndex:26175; }","duration":"300.036688ms","start":"2026-07-17T16:45:16.275809Z","end":"2026-07-17T16:45:16.575846Z","steps":["trace[1186943017] 'read index received' (duration: 54.266299ms)","trace[1186943017] 'applied index is now lower than readState.Index' (duration: 245.769379ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:16.575881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:16.132334Z","time spent":"443.471207ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:16.575878Z","caller":"traceutil/trace.go:171","msg":"trace[2119774548] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"442.626643ms","start":"2026-07-17T16:45:16.133232Z","end":"2026-07-17T16:45:16.575859Z","steps":["trace[2119774548] 'process raft request' (duration: 442.268793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:16.575874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:16.133229Z","time spent":"442.614833ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:45:16.576033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:16.133212Z","time spent":"442.717886ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:45:16.576062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.249964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-07-17T16:45:16.576091Z","caller":"traceutil/trace.go:171","msg":"trace[1006043079] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25363; }","duration":"300.307076ms","start":"2026-07-17T16:45:16.275777Z","end":"2026-07-17T16:45:16.576084Z","steps":["trace[1006043079] 'agreement among raft nodes before linearized reading' (duration: 300.12185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:16.576112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:16.275761Z","time spent":"300.345637ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7720,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-07-17T16:45:16.576136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.334784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:45:16.57619Z","caller":"traceutil/trace.go:171","msg":"trace[377457544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25363; }","duration":"223.423358ms","start":"2026-07-17T16:45:16.352739Z","end":"2026-07-17T16:45:16.576163Z","steps":["trace[377457544] 'agreement among raft nodes before linearized reading' (duration: 223.212061ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:16.748607Z","caller":"traceutil/trace.go:171","msg":"trace[1425181892] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"164.118073ms","start":"2026-07-17T16:45:16.584461Z","end":"2026-07-17T16:45:16.748579Z","steps":["trace[1425181892] 'process raft request' (duration: 131.408754ms)","trace[1425181892] 'compare' (duration: 32.357579ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:16.748602Z","caller":"traceutil/trace.go:171","msg":"trace[1699372095] linearizableReadLoop","detail":"{readStateIndex:26183; appliedIndex:26182; }","duration":"144.604103ms","start":"2026-07-17T16:45:16.603972Z","end":"2026-07-17T16:45:16.748576Z","steps":["trace[1699372095] 'read index received' (duration: 111.905334ms)","trace[1699372095] 'applied index is now lower than readState.Index' (duration: 32.697699ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:16.748772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.780099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-17T16:45:16.74883Z","caller":"traceutil/trace.go:171","msg":"trace[683762118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25364; }","duration":"144.876501ms","start":"2026-07-17T16:45:16.60394Z","end":"2026-07-17T16:45:16.748817Z","steps":["trace[683762118] 'agreement among raft nodes before linearized reading' (duration: 144.712276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:16.748919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.887318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:45:16.749Z","caller":"traceutil/trace.go:171","msg":"trace[397752501] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25364; }","duration":"101.078574ms","start":"2026-07-17T16:45:16.647899Z","end":"2026-07-17T16:45:16.748977Z","steps":["trace[397752501] 'agreement among raft nodes before linearized reading' (duration: 100.873748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:18.388709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.769741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:45:18.389042Z","caller":"traceutil/trace.go:171","msg":"trace[1439688804] linearizableReadLoop","detail":"{readStateIndex:26196; appliedIndex:26195; }","duration":"240.273701ms","start":"2026-07-17T16:45:18.14875Z","end":"2026-07-17T16:45:18.389024Z","steps":["trace[1439688804] 'read index received' (duration: 105.139069ms)","trace[1439688804] 'applied index is now lower than readState.Index' (duration: 135.133272ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:18.389248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.501158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-07-17T16:45:18.389278Z","caller":"traceutil/trace.go:171","msg":"trace[440072031] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25378; }","duration":"240.566699ms","start":"2026-07-17T16:45:18.148703Z","end":"2026-07-17T16:45:18.38927Z","steps":["trace[440072031] 'agreement among raft nodes before linearized reading' (duration: 240.374584ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:18.389562Z","caller":"traceutil/trace.go:171","msg":"trace[1379013715] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"181.500373ms","start":"2026-07-17T16:45:18.208051Z","end":"2026-07-17T16:45:18.389551Z","steps":["trace[1379013715] 'process raft request' (duration: 180.771453ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:18.389605Z","caller":"traceutil/trace.go:171","msg":"trace[729107754] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"249.585078ms","start":"2026-07-17T16:45:18.139973Z","end":"2026-07-17T16:45:18.389558Z","steps":["trace[729107754] 'process raft request' (duration: 113.901861ms)","trace[729107754] 'compare' (duration: 134.623797ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:20.661203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.528382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:45:20.66133Z","caller":"traceutil/trace.go:171","msg":"trace[1947864613] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25393; }","duration":"144.689797ms","start":"2026-07-17T16:45:20.516612Z","end":"2026-07-17T16:45:20.661302Z","steps":["trace[1947864613] 'range keys from in-memory index tree' (duration: 144.45801ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:20.729544Z","caller":"traceutil/trace.go:171","msg":"trace[2022988308] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"135.333517ms","start":"2026-07-17T16:45:20.594182Z","end":"2026-07-17T16:45:20.729516Z","steps":["trace[2022988308] 'process raft request' (duration: 135.253975ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:20.72958Z","caller":"traceutil/trace.go:171","msg":"trace[1063939978] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"135.879912ms","start":"2026-07-17T16:45:20.593658Z","end":"2026-07-17T16:45:20.729538Z","steps":["trace[1063939978] 'process raft request' (duration: 135.646365ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:20.729934Z","caller":"traceutil/trace.go:171","msg":"trace[433123769] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"135.303417ms","start":"2026-07-17T16:45:20.594536Z","end":"2026-07-17T16:45:20.72984Z","steps":["trace[433123769] 'process raft request' (duration: 134.942636ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:20.730025Z","caller":"traceutil/trace.go:171","msg":"trace[264459026] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"135.231734ms","start":"2026-07-17T16:45:20.594764Z","end":"2026-07-17T16:45:20.729996Z","steps":["trace[264459026] 'process raft request' (duration: 134.954066ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:20.730057Z","caller":"traceutil/trace.go:171","msg":"trace[1784315753] linearizableReadLoop","detail":"{readStateIndex:26220; appliedIndex:26213; }","duration":"109.486564ms","start":"2026-07-17T16:45:20.620553Z","end":"2026-07-17T16:45:20.73004Z","steps":["trace[1784315753] 'read index received' (duration: 66.093098ms)","trace[1784315753] 'applied index is now lower than readState.Index' (duration: 43.392196ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:20.729977Z","caller":"traceutil/trace.go:171","msg":"trace[975127165] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"134.336389ms","start":"2026-07-17T16:45:20.59562Z","end":"2026-07-17T16:45:20.729956Z","steps":["trace[975127165] 'process raft request' (duration: 134.207525ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:20.730174Z","caller":"traceutil/trace.go:171","msg":"trace[1262932908] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"133.987598ms","start":"2026-07-17T16:45:20.596149Z","end":"2026-07-17T16:45:20.730137Z","steps":["trace[1262932908] 'process raft request' (duration: 133.761702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:20.730216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.633849ms","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-07-17T16:45:20.730278Z","caller":"traceutil/trace.go:171","msg":"trace[1206569369] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25400; }","duration":"109.741352ms","start":"2026-07-17T16:45:20.620516Z","end":"2026-07-17T16:45:20.730258Z","steps":["trace[1206569369] 'agreement among raft nodes before linearized reading' (duration: 109.627838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:24.19512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.286187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T16:45:24.195248Z","caller":"traceutil/trace.go:171","msg":"trace[1706009831] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25422; }","duration":"157.451152ms","start":"2026-07-17T16:45:24.037749Z","end":"2026-07-17T16:45:24.1952Z","steps":["trace[1706009831] 'count revisions from in-memory index tree' (duration: 157.154953ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:26.872638Z","caller":"traceutil/trace.go:171","msg":"trace[1690547017] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"124.093364ms","start":"2026-07-17T16:45:26.748523Z","end":"2026-07-17T16:45:26.872616Z","steps":["trace[1690547017] 'process raft request' (duration: 123.865028ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:26.872865Z","caller":"traceutil/trace.go:171","msg":"trace[1452232030] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"120.863532ms","start":"2026-07-17T16:45:26.751992Z","end":"2026-07-17T16:45:26.872855Z","steps":["trace[1452232030] 'process raft request' (duration: 120.582294ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:26.873218Z","caller":"traceutil/trace.go:171","msg":"trace[257115090] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"120.706847ms","start":"2026-07-17T16:45:26.752499Z","end":"2026-07-17T16:45:26.873206Z","steps":["trace[257115090] 'process raft request' (duration: 120.555763ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:26.873222Z","caller":"traceutil/trace.go:171","msg":"trace[899125786] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"120.902262ms","start":"2026-07-17T16:45:26.752281Z","end":"2026-07-17T16:45:26.873183Z","steps":["trace[899125786] 'process raft request' (duration: 120.537982ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:26.873282Z","caller":"traceutil/trace.go:171","msg":"trace[2067791721] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"120.065689ms","start":"2026-07-17T16:45:26.753193Z","end":"2026-07-17T16:45:26.873258Z","steps":["trace[2067791721] 'process raft request' (duration: 119.949216ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:26.873416Z","caller":"traceutil/trace.go:171","msg":"trace[211060153] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"120.190342ms","start":"2026-07-17T16:45:26.753214Z","end":"2026-07-17T16:45:26.873404Z","steps":["trace[211060153] 'process raft request' (duration: 119.967386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:27.814475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.192827ms","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-07-17T16:45:27.814591Z","caller":"traceutil/trace.go:171","msg":"trace[769379521] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25449; }","duration":"116.319071ms","start":"2026-07-17T16:45:27.698254Z","end":"2026-07-17T16:45:27.814573Z","steps":["trace[769379521] 'range keys from in-memory index tree' (duration: 116.181016ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:29.759844Z","caller":"traceutil/trace.go:171","msg":"trace[1254867412] linearizableReadLoop","detail":"{readStateIndex:26295; appliedIndex:26294; }","duration":"147.443565ms","start":"2026-07-17T16:45:29.612356Z","end":"2026-07-17T16:45:29.7598Z","steps":["trace[1254867412] 'read index received' (duration: 147.324792ms)","trace[1254867412] 'applied index is now lower than readState.Index' (duration: 117.323µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:29.759954Z","caller":"traceutil/trace.go:171","msg":"trace[1606996430] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"149.496834ms","start":"2026-07-17T16:45:29.610417Z","end":"2026-07-17T16:45:29.759914Z","steps":["trace[1606996430] 'process raft request' (duration: 149.189696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:29.76009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.705582ms","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-07-17T16:45:29.760142Z","caller":"traceutil/trace.go:171","msg":"trace[386254409] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25472; }","duration":"147.800655ms","start":"2026-07-17T16:45:29.612326Z","end":"2026-07-17T16:45:29.760126Z","steps":["trace[386254409] 'agreement among raft nodes before linearized reading' (duration: 147.589099ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:35.039689Z","caller":"traceutil/trace.go:171","msg":"trace[59951544] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"113.333355ms","start":"2026-07-17T16:45:34.926324Z","end":"2026-07-17T16:45:35.039657Z","steps":["trace[59951544] 'process raft request' (duration: 76.282281ms)","trace[59951544] 'compare' (duration: 36.810757ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:35.055526Z","caller":"traceutil/trace.go:171","msg":"trace[1546706839] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"125.051372ms","start":"2026-07-17T16:45:34.930451Z","end":"2026-07-17T16:45:35.055503Z","steps":["trace[1546706839] 'process raft request' (duration: 124.830886ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:35.0558Z","caller":"traceutil/trace.go:171","msg":"trace[1031200532] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"124.783723ms","start":"2026-07-17T16:45:34.930997Z","end":"2026-07-17T16:45:35.055781Z","steps":["trace[1031200532] 'process raft request' (duration: 124.433863ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:35.056071Z","caller":"traceutil/trace.go:171","msg":"trace[247579270] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"124.756912ms","start":"2026-07-17T16:45:34.931278Z","end":"2026-07-17T16:45:35.056035Z","steps":["trace[247579270] 'process raft request' (duration: 124.367731ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:35.05613Z","caller":"traceutil/trace.go:171","msg":"trace[315992687] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"124.68148ms","start":"2026-07-17T16:45:34.931431Z","end":"2026-07-17T16:45:35.056113Z","steps":["trace[315992687] 'process raft request' (duration: 124.523386ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:35.056126Z","caller":"traceutil/trace.go:171","msg":"trace[2091222166] linearizableReadLoop","detail":"{readStateIndex:26341; appliedIndex:26335; }","duration":"122.166848ms","start":"2026-07-17T16:45:34.933937Z","end":"2026-07-17T16:45:35.056104Z","steps":["trace[2091222166] 'read index received' (duration: 68.670452ms)","trace[2091222166] 'applied index is now lower than readState.Index' (duration: 53.495546ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:35.056206Z","caller":"traceutil/trace.go:171","msg":"trace[1381149194] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"123.878028ms","start":"2026-07-17T16:45:34.932289Z","end":"2026-07-17T16:45:35.056167Z","steps":["trace[1381149194] 'process raft request' (duration: 123.751234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:35.056353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.405405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:45:35.056408Z","caller":"traceutil/trace.go:171","msg":"trace[1549569808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25517; }","duration":"122.498877ms","start":"2026-07-17T16:45:34.933896Z","end":"2026-07-17T16:45:35.056395Z","steps":["trace[1549569808] 'agreement among raft nodes before linearized reading' (duration: 122.337763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:35.056405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.880436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-17T16:45:35.056455Z","caller":"traceutil/trace.go:171","msg":"trace[1904651090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25517; }","duration":"117.955028ms","start":"2026-07-17T16:45:34.938484Z","end":"2026-07-17T16:45:35.056439Z","steps":["trace[1904651090] 'agreement among raft nodes before linearized reading' (duration: 117.786853ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:49.100771Z","caller":"traceutil/trace.go:171","msg":"trace[2055763043] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"132.254479ms","start":"2026-07-17T16:45:48.968496Z","end":"2026-07-17T16:45:49.100751Z","steps":["trace[2055763043] 'process raft request' (duration: 132.077854ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:49.252969Z","caller":"traceutil/trace.go:171","msg":"trace[313512405] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"154.719044ms","start":"2026-07-17T16:45:49.098231Z","end":"2026-07-17T16:45:49.25295Z","steps":["trace[313512405] 'process raft request' (duration: 154.674953ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:49.252995Z","caller":"traceutil/trace.go:171","msg":"trace[455550700] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"172.599858ms","start":"2026-07-17T16:45:49.080368Z","end":"2026-07-17T16:45:49.252967Z","steps":["trace[455550700] 'process raft request' (duration: 87.176125ms)","trace[455550700] 'compare' (duration: 85.234657ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:49.262596Z","caller":"traceutil/trace.go:171","msg":"trace[215814862] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"156.038241ms","start":"2026-07-17T16:45:49.106538Z","end":"2026-07-17T16:45:49.262577Z","steps":["trace[215814862] 'process raft request' (duration: 155.98334ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:49.262631Z","caller":"traceutil/trace.go:171","msg":"trace[774466770] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"156.635439ms","start":"2026-07-17T16:45:49.105979Z","end":"2026-07-17T16:45:49.262615Z","steps":["trace[774466770] 'process raft request' (duration: 156.509125ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:49.262714Z","caller":"traceutil/trace.go:171","msg":"trace[1003985461] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"149.826064ms","start":"2026-07-17T16:45:49.112874Z","end":"2026-07-17T16:45:49.2627Z","steps":["trace[1003985461] 'process raft request' (duration: 149.672118ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:49.26271Z","caller":"traceutil/trace.go:171","msg":"trace[1084221101] linearizableReadLoop","detail":"{readStateIndex:26444; appliedIndex:26438; }","duration":"147.231099ms","start":"2026-07-17T16:45:49.115457Z","end":"2026-07-17T16:45:49.262688Z","steps":["trace[1084221101] 'read index received' (duration: 52.244471ms)","trace[1084221101] 'applied index is now lower than readState.Index' (duration: 94.985118ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:49.262859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.390574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-17T16:45:49.262882Z","caller":"traceutil/trace.go:171","msg":"trace[779135395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25618; }","duration":"147.449996ms","start":"2026-07-17T16:45:49.115425Z","end":"2026-07-17T16:45:49.262875Z","steps":["trace[779135395] 'agreement among raft nodes before linearized reading' (duration: 147.357313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:49.2629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.592142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-17T16:45:49.262925Z","caller":"traceutil/trace.go:171","msg":"trace[882467921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25618; }","duration":"146.628793ms","start":"2026-07-17T16:45:49.116287Z","end":"2026-07-17T16:45:49.262916Z","steps":["trace[882467921] 'agreement among raft nodes before linearized reading' (duration: 146.50967ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:49.262627Z","caller":"traceutil/trace.go:171","msg":"trace[464514866] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"158.05766ms","start":"2026-07-17T16:45:49.104535Z","end":"2026-07-17T16:45:49.262593Z","steps":["trace[464514866] 'process raft request' (duration: 157.845834ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:58.432347Z","caller":"traceutil/trace.go:171","msg":"trace[1055488832] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"130.449997ms","start":"2026-07-17T16:45:58.301878Z","end":"2026-07-17T16:45:58.432328Z","steps":["trace[1055488832] 'process raft request' (duration: 130.298523ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:58.918996Z","caller":"traceutil/trace.go:171","msg":"trace[1942585847] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"173.761261ms","start":"2026-07-17T16:45:58.745212Z","end":"2026-07-17T16:45:58.918974Z","steps":["trace[1942585847] 'process raft request' (duration: 173.435322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.695868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.066121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T16:45:59.696052Z","caller":"traceutil/trace.go:171","msg":"trace[579483092] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"392.36129ms","start":"2026-07-17T16:45:59.303674Z","end":"2026-07-17T16:45:59.696035Z","steps":["trace[579483092] 'process raft request' (duration: 392.308688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.696104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.30365Z","time spent":"392.430482ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:59.69625Z","caller":"traceutil/trace.go:171","msg":"trace[1968760001] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"396.888361ms","start":"2026-07-17T16:45:59.299352Z","end":"2026-07-17T16:45:59.696241Z","steps":["trace[1968760001] 'process raft request' (duration: 89.423919ms)","trace[1968760001] 'compare' (duration: 306.908216ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:45:59.696285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.299324Z","time spent":"396.940261ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:45:59.696256Z","caller":"traceutil/trace.go:171","msg":"trace[123195685] linearizableReadLoop","detail":"{readStateIndex:26530; appliedIndex:26524; }","duration":"390.066393ms","start":"2026-07-17T16:45:59.306163Z","end":"2026-07-17T16:45:59.69623Z","steps":["trace[123195685] 'read index received' (duration: 82.593331ms)","trace[123195685] 'applied index is now lower than readState.Index' (duration: 307.472142ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:45:59.696421Z","caller":"traceutil/trace.go:171","msg":"trace[639687290] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"392.741331ms","start":"2026-07-17T16:45:59.303609Z","end":"2026-07-17T16:45:59.696351Z","steps":["trace[639687290] 'process raft request' (duration: 392.334159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.696602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.390543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:45:59.696699Z","caller":"traceutil/trace.go:171","msg":"trace[659123204] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25702; }","duration":"390.569518ms","start":"2026-07-17T16:45:59.306107Z","end":"2026-07-17T16:45:59.696677Z","steps":["trace[659123204] 'agreement among raft nodes before linearized reading' (duration: 390.307861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.69668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.303575Z","time spent":"392.989228ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:45:59.696746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.306094Z","time spent":"390.64213ms","remote":"127.0.0.1:59172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:45:59.696831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.118227ms","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-07-17T16:45:59.69663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.725386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T16:45:59.69687Z","caller":"traceutil/trace.go:171","msg":"trace[966075555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25702; }","duration":"388.176529ms","start":"2026-07-17T16:45:59.30868Z","end":"2026-07-17T16:45:59.696857Z","steps":["trace[966075555] 'agreement among raft nodes before linearized reading' (duration: 388.086667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.696904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.308666Z","time spent":"388.22917ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T16:45:59.696889Z","caller":"traceutil/trace.go:171","msg":"trace[1013982834] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"392.632538ms","start":"2026-07-17T16:45:59.304214Z","end":"2026-07-17T16:45:59.696846Z","steps":["trace[1013982834] 'process raft request' (duration: 391.972089ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:59.696914Z","caller":"traceutil/trace.go:171","msg":"trace[1542640236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25702; }","duration":"389.031184ms","start":"2026-07-17T16:45:59.307856Z","end":"2026-07-17T16:45:59.696887Z","steps":["trace[1542640236] 'agreement among raft nodes before linearized reading' (duration: 388.620822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.696978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.307846Z","time spent":"389.114617ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T16:45:59.696993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.692245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-17T16:45:59.697007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.406143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-17T16:45:59.697029Z","caller":"traceutil/trace.go:171","msg":"trace[177337440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25702; }","duration":"388.752897ms","start":"2026-07-17T16:45:59.308264Z","end":"2026-07-17T16:45:59.697017Z","steps":["trace[177337440] 'agreement among raft nodes before linearized reading' (duration: 388.622923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.696915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.815085ms","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-07-17T16:45:59.697004Z","caller":"traceutil/trace.go:171","msg":"trace[1244260360] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"392.963347ms","start":"2026-07-17T16:45:59.303996Z","end":"2026-07-17T16:45:59.696959Z","steps":["trace[1244260360] 'process raft request' (duration: 392.01064ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:59.697049Z","caller":"traceutil/trace.go:171","msg":"trace[2098981027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25702; }","duration":"382.451574ms","start":"2026-07-17T16:45:59.314582Z","end":"2026-07-17T16:45:59.697034Z","steps":["trace[2098981027] 'agreement among raft nodes before linearized reading' (duration: 382.368902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.697044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.304197Z","time spent":"392.761371ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:45:59.69706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.308242Z","time spent":"388.811198ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T16:45:59.697067Z","caller":"traceutil/trace.go:171","msg":"trace[868911438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25702; }","duration":"382.98953ms","start":"2026-07-17T16:45:59.314068Z","end":"2026-07-17T16:45:59.697057Z","steps":["trace[868911438] 'agreement among raft nodes before linearized reading' (duration: 382.686331ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:45:59.696903Z","caller":"traceutil/trace.go:171","msg":"trace[962665581] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"392.817533ms","start":"2026-07-17T16:45:59.304027Z","end":"2026-07-17T16:45:59.696844Z","steps":["trace[962665581] 'process raft request' (duration: 392.074532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.697083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.314578Z","time spent":"382.498546ms","remote":"127.0.0.1:56312","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-07-17T16:45:59.697101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.31406Z","time spent":"383.032621ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T16:45:59.697085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.546388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-17T16:45:59.697194Z","caller":"traceutil/trace.go:171","msg":"trace[1020255087] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:25702; }","duration":"225.705312ms","start":"2026-07-17T16:45:59.471465Z","end":"2026-07-17T16:45:59.69717Z","steps":["trace[1020255087] 'agreement among raft nodes before linearized reading' (duration: 225.386983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:45:59.697127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.303987Z","time spent":"393.08417ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:45:59.697231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.303997Z","time spent":"393.124202ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:45:59.996513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.241692ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-17T16:46:00.05512Z","caller":"traceutil/trace.go:171","msg":"trace[914609180] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"353.41666ms","start":"2026-07-17T16:45:59.701676Z","end":"2026-07-17T16:46:00.055092Z","steps":["trace[914609180] 'process raft request' (duration: 353.094181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:46:00.05526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.701658Z","time spent":"353.515124ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:46:00.055388Z","caller":"traceutil/trace.go:171","msg":"trace[1770971942] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"353.571345ms","start":"2026-07-17T16:45:59.701787Z","end":"2026-07-17T16:46:00.055358Z","steps":["trace[1770971942] 'process raft request' (duration: 353.274146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:46:00.055528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.701765Z","time spent":"353.695219ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:46:00.055543Z","caller":"traceutil/trace.go:171","msg":"trace[2009838017] linearizableReadLoop","detail":"{readStateIndex:26537; appliedIndex:26531; }","duration":"199.575092ms","start":"2026-07-17T16:45:59.855931Z","end":"2026-07-17T16:46:00.055506Z","steps":["trace[2009838017] 'read index received' (duration: 198.749869ms)","trace[2009838017] 'applied index is now lower than readState.Index' (duration: 823.603µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:46:00.05573Z","caller":"traceutil/trace.go:171","msg":"trace[1354446498] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"353.281927ms","start":"2026-07-17T16:45:59.702427Z","end":"2026-07-17T16:46:00.055709Z","steps":["trace[1354446498] 'process raft request' (duration: 352.923467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:46:00.055769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.839539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:46:00.05579Z","caller":"traceutil/trace.go:171","msg":"trace[2044034469] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"353.173934ms","start":"2026-07-17T16:45:59.702609Z","end":"2026-07-17T16:46:00.055783Z","steps":["trace[2044034469] 'process raft request' (duration: 352.790593ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:46:00.055805Z","caller":"traceutil/trace.go:171","msg":"trace[737516964] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:25708; }","duration":"199.897871ms","start":"2026-07-17T16:45:59.855892Z","end":"2026-07-17T16:46:00.055789Z","steps":["trace[737516964] 'agreement among raft nodes before linearized reading' (duration: 199.836409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:46:00.055884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.702601Z","time spent":"353.220105ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:46:00.055936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.702419Z","time spent":"353.381419ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:46:00.055731Z","caller":"traceutil/trace.go:171","msg":"trace[1802894891] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"269.409838ms","start":"2026-07-17T16:45:59.786297Z","end":"2026-07-17T16:46:00.055707Z","steps":["trace[1802894891] 'process raft request' (duration: 269.14781ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:46:00.05661Z","caller":"traceutil/trace.go:171","msg":"trace[1496744146] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"353.596406ms","start":"2026-07-17T16:45:59.701858Z","end":"2026-07-17T16:46:00.055455Z","steps":["trace[1496744146] 'process raft request' (duration: 353.41696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:46:00.05672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:45:59.701835Z","time spent":"354.828032ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:46:00.156096Z","caller":"traceutil/trace.go:171","msg":"trace[1034864818] linearizableReadLoop","detail":"{readStateIndex:26538; appliedIndex:26537; }","duration":"100.419605ms","start":"2026-07-17T16:46:00.05565Z","end":"2026-07-17T16:46:00.15607Z","steps":["trace[1034864818] 'read index received' (duration: 98.732846ms)","trace[1034864818] 'applied index is now lower than readState.Index' (duration: 1.685749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:46:00.156269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.42616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T16:46:00.156308Z","caller":"traceutil/trace.go:171","msg":"trace[1110997721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25709; }","duration":"299.531743ms","start":"2026-07-17T16:45:59.856766Z","end":"2026-07-17T16:46:00.156298Z","steps":["trace[1110997721] 'agreement among raft nodes before linearized reading' (duration: 299.401269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:46:00.156496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.039793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18557"} {"level":"info","ts":"2026-07-17T16:46:00.156665Z","caller":"traceutil/trace.go:171","msg":"trace[307969999] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25709; }","duration":"217.27316ms","start":"2026-07-17T16:45:59.93936Z","end":"2026-07-17T16:46:00.156634Z","steps":["trace[307969999] 'agreement among raft nodes before linearized reading' (duration: 216.770425ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:46:00.156919Z","caller":"traceutil/trace.go:171","msg":"trace[36489997] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"159.123399ms","start":"2026-07-17T16:45:59.997748Z","end":"2026-07-17T16:46:00.156872Z","steps":["trace[36489997] 'process raft request' (duration: 156.704681ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:46:26.318057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23059} {"level":"info","ts":"2026-07-17T16:46:26.447634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23059,"took":"125.716691ms","hash":2890809695,"current-db-size-bytes":59990016,"current-db-size":"60 MB","current-db-size-in-use-bytes":37289984,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-17T16:46:26.447698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2890809695,"revision":23059,"compact-revision":20175} {"level":"info","ts":"2026-07-17T16:51:26.324589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26048} {"level":"info","ts":"2026-07-17T16:51:26.490242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26048,"took":"161.121763ms","hash":994130049,"current-db-size-bytes":59990016,"current-db-size":"60 MB","current-db-size-in-use-bytes":35397632,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-17T16:51:26.490319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":994130049,"revision":26048,"compact-revision":23059} {"level":"info","ts":"2026-07-17T16:52:09.497923Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T16:52:09.503822Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-17T16:52:09.503948Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-17T16:56:26.332661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28766} {"level":"info","ts":"2026-07-17T16:56:26.48359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28766,"took":"146.285999ms","hash":1579284911,"current-db-size-bytes":59990016,"current-db-size":"60 MB","current-db-size-in-use-bytes":30928896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T16:56:26.483686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1579284911,"revision":28766,"compact-revision":26048} {"level":"info","ts":"2026-07-17T17:01:26.339951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30988} {"level":"info","ts":"2026-07-17T17:01:26.472197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30988,"took":"127.557279ms","hash":2194506969,"current-db-size-bytes":59990016,"current-db-size":"60 MB","current-db-size-in-use-bytes":30896128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T17:01:26.472269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2194506969,"revision":30988,"compact-revision":28766} {"level":"info","ts":"2026-07-17T17:06:26.347064Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33314} {"level":"info","ts":"2026-07-17T17:06:26.496083Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33314,"took":"144.111689ms","hash":2871544182,"current-db-size-bytes":61034496,"current-db-size":"61 MB","current-db-size-in-use-bytes":31916032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T17:06:26.496153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2871544182,"revision":33314,"compact-revision":30988} {"level":"info","ts":"2026-07-17T17:11:26.352688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35645} {"level":"info","ts":"2026-07-17T17:11:26.50168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35645,"took":"145.639188ms","hash":3330656216,"current-db-size-bytes":62771200,"current-db-size":"63 MB","current-db-size-in-use-bytes":32378880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T17:11:26.501758Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3330656216,"revision":35645,"compact-revision":33314} {"level":"info","ts":"2026-07-17T17:13:28.469194Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T17:13:28.482319Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-17T17:13:28.482427Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-17T17:16:26.358602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37870} {"level":"info","ts":"2026-07-17T17:16:26.476359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37870,"took":"114.420287ms","hash":2098676875,"current-db-size-bytes":63193088,"current-db-size":"63 MB","current-db-size-in-use-bytes":32337920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T17:16:26.476427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2098676875,"revision":37870,"compact-revision":35645}