{"level":"warn","ts":"2026-05-25T17:53:47.401112Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T17:53:47.401245Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-25T17:53:47.401324Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T17:53:47.401333Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-25T17:53:47.401355Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T17:53:47.402178Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-05-25T17:53:47.402271Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-25T17:53:47.405597Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.081366ms"} {"level":"info","ts":"2026-05-25T17:53:47.40969Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-05-25T17:53:47.410126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T17:53:47.410255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-05-25T17:53:47.41031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T17:53:47.410344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-05-25T17:53:47.410475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-05-25T17:53:47.42129Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T17:53:47.42353Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T17:53:47.424772Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-25T17:53:47.427114Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T17:53:47.427276Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T17:53:47.427403Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T17:53:47.427493Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T17:53:47.427504Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T17:53:47.431946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-05-25T17:53:47.432236Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-25T17:53:47.434596Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T17:53:47.434661Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-25T17:53:47.434699Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-25T17:53:47.435055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T17:53:47.435125Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T17:53:47.611693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T17:53:47.611843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T17:53:47.61189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-05-25T17:53:47.611921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-05-25T17:53:47.61193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-25T17:53:47.611945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-05-25T17:53:47.611958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-25T17:53:47.612912Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T17:53:47.612936Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T17:53:47.613047Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:53:47.613012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T17:53:47.613352Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T17:53:47.613453Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T17:53:47.614062Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:53:47.614204Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:53:47.614257Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:53:47.617158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-05-25T17:53:47.617418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T17:54:26.077368Z","caller":"traceutil/trace.go:171","msg":"trace[1436435866] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"102.482708ms","start":"2026-05-25T17:54:25.974848Z","end":"2026-05-25T17:54:26.077331Z","steps":["trace[1436435866] 'process raft request' (duration: 102.19014ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:32.25896Z","caller":"traceutil/trace.go:171","msg":"trace[751136424] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"155.149072ms","start":"2026-05-25T17:54:32.103777Z","end":"2026-05-25T17:54:32.258926Z","steps":["trace[751136424] 'read index received' (duration: 153.403284ms)","trace[751136424] 'applied index is now lower than readState.Index' (duration: 1.744298ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:54:32.259098Z","caller":"traceutil/trace.go:171","msg":"trace[1837936771] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"187.319329ms","start":"2026-05-25T17:54:32.071725Z","end":"2026-05-25T17:54:32.259045Z","steps":["trace[1837936771] 'process raft request' (duration: 186.23932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:32.25926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.874581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T17:54:32.259333Z","caller":"traceutil/trace.go:171","msg":"trace[1996072296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"151.997905ms","start":"2026-05-25T17:54:32.107322Z","end":"2026-05-25T17:54:32.25932Z","steps":["trace[1996072296] 'agreement among raft nodes before linearized reading' (duration: 151.82894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:32.259507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.527461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-25T17:54:32.259541Z","caller":"traceutil/trace.go:171","msg":"trace[911712580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"147.578253ms","start":"2026-05-25T17:54:32.111955Z","end":"2026-05-25T17:54:32.259534Z","steps":["trace[911712580] 'agreement among raft nodes before linearized reading' (duration: 147.524531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:32.259742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.964044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T17:54:32.259803Z","caller":"traceutil/trace.go:171","msg":"trace[825625655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"156.060937ms","start":"2026-05-25T17:54:32.103722Z","end":"2026-05-25T17:54:32.259782Z","steps":["trace[825625655] 'agreement among raft nodes before linearized reading' (duration: 155.354837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:32.528093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.211992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:54:32.528206Z","caller":"traceutil/trace.go:171","msg":"trace[1856375502] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:628; }","duration":"197.385067ms","start":"2026-05-25T17:54:32.330797Z","end":"2026-05-25T17:54:32.528182Z","steps":["trace[1856375502] 'range keys from in-memory index tree' (duration: 197.069549ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:05.124925Z","caller":"traceutil/trace.go:171","msg":"trace[873580402] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"233.753634ms","start":"2026-05-25T17:56:04.891123Z","end":"2026-05-25T17:56:05.124877Z","steps":["trace[873580402] 'process raft request' (duration: 233.582859ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:05.150515Z","caller":"traceutil/trace.go:171","msg":"trace[993437710] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"259.30316ms","start":"2026-05-25T17:56:04.891182Z","end":"2026-05-25T17:56:05.150485Z","steps":["trace[993437710] 'process raft request' (duration: 259.065823ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:05.15068Z","caller":"traceutil/trace.go:171","msg":"trace[294159137] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"258.883068ms","start":"2026-05-25T17:56:04.891771Z","end":"2026-05-25T17:56:05.150654Z","steps":["trace[294159137] 'process raft request' (duration: 258.639642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:54.529197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.460856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:56:54.52942Z","caller":"traceutil/trace.go:171","msg":"trace[104562691] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"158.13674ms","start":"2026-05-25T17:56:54.371238Z","end":"2026-05-25T17:56:54.529374Z","steps":["trace[104562691] 'process raft request' (duration: 26.571845ms)","trace[104562691] 'compare' (duration: 130.25519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:57:00.665525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.015093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-25T17:57:00.665664Z","caller":"traceutil/trace.go:171","msg":"trace[2093294778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2225; }","duration":"105.233098ms","start":"2026-05-25T17:57:00.560403Z","end":"2026-05-25T17:57:00.665636Z","steps":["trace[2093294778] 'range keys from in-memory index tree' (duration: 104.650823ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:57:00.793065Z","caller":"traceutil/trace.go:171","msg":"trace[1824611834] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"123.091095ms","start":"2026-05-25T17:57:00.669933Z","end":"2026-05-25T17:57:00.793024Z","steps":["trace[1824611834] 'process raft request' (duration: 122.91428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:57:01.091023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.213796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:57:01.091148Z","caller":"traceutil/trace.go:171","msg":"trace[967438047] linearizableReadLoop","detail":"{readStateIndex:2313; appliedIndex:2312; }","duration":"185.402423ms","start":"2026-05-25T17:57:00.905725Z","end":"2026-05-25T17:57:01.091127Z","steps":["trace[967438047] 'read index received' (duration: 82.809227ms)","trace[967438047] 'applied index is now lower than readState.Index' (duration: 102.591616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:57:01.091213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.499806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:57:01.091255Z","caller":"traceutil/trace.go:171","msg":"trace[1417901018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2227; }","duration":"185.587478ms","start":"2026-05-25T17:57:00.905648Z","end":"2026-05-25T17:57:01.091235Z","steps":["trace[1417901018] 'agreement among raft nodes before linearized reading' (duration: 185.532317ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:57:01.091283Z","caller":"traceutil/trace.go:171","msg":"trace[446852480] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"419.601196ms","start":"2026-05-25T17:57:00.671642Z","end":"2026-05-25T17:57:01.091243Z","steps":["trace[446852480] 'process raft request' (duration: 317.070892ms)","trace[446852480] 'compare' (duration: 102.01425ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:57:01.091465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:57:00.671608Z","time spent":"419.760861ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:58:09.257448Z","caller":"traceutil/trace.go:171","msg":"trace[1554870452] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"105.620419ms","start":"2026-05-25T17:58:09.151796Z","end":"2026-05-25T17:58:09.257416Z","steps":["trace[1554870452] 'process raft request' (duration: 105.409563ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:58:09.82701Z","caller":"traceutil/trace.go:171","msg":"trace[909370866] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"154.626104ms","start":"2026-05-25T17:58:09.672355Z","end":"2026-05-25T17:58:09.826981Z","steps":["trace[909370866] 'process raft request' (duration: 154.351957ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:00:48.472705Z","caller":"traceutil/trace.go:171","msg":"trace[2052396080] linearizableReadLoop","detail":"{readStateIndex:3980; appliedIndex:3979; }","duration":"196.269138ms","start":"2026-05-25T18:00:48.2764Z","end":"2026-05-25T18:00:48.472669Z","steps":["trace[2052396080] 'read index received' (duration: 196.074652ms)","trace[2052396080] 'applied index is now lower than readState.Index' (duration: 187.436µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:00:48.472957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.526195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T18:00:48.473048Z","caller":"traceutil/trace.go:171","msg":"trace[1741647710] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:3832; }","duration":"196.678099ms","start":"2026-05-25T18:00:48.276347Z","end":"2026-05-25T18:00:48.473025Z","steps":["trace[1741647710] 'agreement among raft nodes before linearized reading' (duration: 196.503134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:48.473456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.891086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T18:00:48.473525Z","caller":"traceutil/trace.go:171","msg":"trace[220796452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3832; }","duration":"167.02259ms","start":"2026-05-25T18:00:48.306487Z","end":"2026-05-25T18:00:48.473509Z","steps":["trace[220796452] 'agreement among raft nodes before linearized reading' (duration: 166.405053ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:00:48.47358Z","caller":"traceutil/trace.go:171","msg":"trace[1993840018] transaction","detail":"{read_only:false; response_revision:3832; number_of_response:1; }","duration":"375.315628ms","start":"2026-05-25T18:00:48.097486Z","end":"2026-05-25T18:00:48.472801Z","steps":["trace[1993840018] 'process raft request' (duration: 374.962629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:48.474676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:48.097468Z","time spent":"377.060806ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:00:48.985938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.16413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-25T18:00:48.986021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.328833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-25T18:00:48.986059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.564829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T18:00:48.986088Z","caller":"traceutil/trace.go:171","msg":"trace[1627165988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3833; }","duration":"352.392696ms","start":"2026-05-25T18:00:48.633656Z","end":"2026-05-25T18:00:48.986048Z","steps":["trace[1627165988] 'range keys from in-memory index tree' (duration: 351.8264ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:00:48.986141Z","caller":"traceutil/trace.go:171","msg":"trace[1784230506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3833; }","duration":"361.495887ms","start":"2026-05-25T18:00:48.624608Z","end":"2026-05-25T18:00:48.986103Z","steps":["trace[1784230506] 'range keys from in-memory index tree' (duration: 361.138468ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:00:48.986153Z","caller":"traceutil/trace.go:171","msg":"trace[1557860918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3833; }","duration":"357.706163ms","start":"2026-05-25T18:00:48.628423Z","end":"2026-05-25T18:00:48.986129Z","steps":["trace[1557860918] 'range keys from in-memory index tree' (duration: 357.365333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:48.986162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:48.63363Z","time spent":"352.52082ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T18:00:48.986198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:48.628399Z","time spent":"357.790275ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T18:00:48.986209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:48.624587Z","time spent":"361.597361ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T18:00:48.986431Z","caller":"traceutil/trace.go:171","msg":"trace[1018681897] transaction","detail":"{read_only:false; response_revision:3834; number_of_response:1; }","duration":"326.199113ms","start":"2026-05-25T18:00:48.660203Z","end":"2026-05-25T18:00:48.986402Z","steps":["trace[1018681897] 'process raft request' (duration: 325.108503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:48.986601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:48.660181Z","time spent":"326.325337ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:00:48.988517Z","caller":"traceutil/trace.go:171","msg":"trace[311216618] transaction","detail":"{read_only:false; response_revision:3835; number_of_response:1; }","duration":"322.529462ms","start":"2026-05-25T18:00:48.665951Z","end":"2026-05-25T18:00:48.98848Z","steps":["trace[311216618] 'process raft request' (duration: 322.404749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:48.988708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:48.665913Z","time spent":"322.693837ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:00:49.953981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881176775078859,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-25T18:00:50.232622Z","caller":"traceutil/trace.go:171","msg":"trace[57547775] linearizableReadLoop","detail":"{readStateIndex:3988; appliedIndex:3987; }","duration":"779.606957ms","start":"2026-05-25T18:00:49.452986Z","end":"2026-05-25T18:00:50.232593Z","steps":["trace[57547775] 'read index received' (duration: 779.383731ms)","trace[57547775] 'applied index is now lower than readState.Index' (duration: 222.226µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:00:50.232818Z","caller":"traceutil/trace.go:171","msg":"trace[2123427493] transaction","detail":"{read_only:false; response_revision:3840; number_of_response:1; }","duration":"790.250761ms","start":"2026-05-25T18:00:49.442523Z","end":"2026-05-25T18:00:50.232774Z","steps":["trace[2123427493] 'process raft request' (duration: 789.909941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:50.233101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.015179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-597gw\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-05-25T18:00:50.233111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.116124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:00:50.233182Z","caller":"traceutil/trace.go:171","msg":"trace[659502330] range","detail":"{range_begin:/registry/pods/openstack/keepalived-597gw; range_end:; response_count:1; response_revision:3840; }","duration":"780.255316ms","start":"2026-05-25T18:00:49.452909Z","end":"2026-05-25T18:00:50.233164Z","steps":["trace[659502330] 'agreement among raft nodes before linearized reading' (duration: 779.842684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:50.233036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:49.442492Z","time spent":"790.430836ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:00:50.233297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:49.452888Z","time spent":"780.387179ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-597gw\" "} {"level":"info","ts":"2026-05-25T18:00:50.23348Z","caller":"traceutil/trace.go:171","msg":"trace[1644844950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3840; }","duration":"683.336631ms","start":"2026-05-25T18:00:49.549902Z","end":"2026-05-25T18:00:50.233238Z","steps":["trace[1644844950] 'agreement among raft nodes before linearized reading' (duration: 683.119835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:50.233536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:00:49.549874Z","time spent":"683.65223ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T18:02:17.432862Z","caller":"traceutil/trace.go:171","msg":"trace[1276530863] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"133.515359ms","start":"2026-05-25T18:02:17.299318Z","end":"2026-05-25T18:02:17.432833Z","steps":["trace[1276530863] 'process raft request' (duration: 133.304223ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:17.511697Z","caller":"traceutil/trace.go:171","msg":"trace[555699127] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"191.902352ms","start":"2026-05-25T18:02:17.319763Z","end":"2026-05-25T18:02:17.511665Z","steps":["trace[555699127] 'process raft request' (duration: 191.788148ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:17.511725Z","caller":"traceutil/trace.go:171","msg":"trace[1884294992] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"195.08833ms","start":"2026-05-25T18:02:17.316605Z","end":"2026-05-25T18:02:17.511693Z","steps":["trace[1884294992] 'process raft request' (duration: 194.799352ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:17.511864Z","caller":"traceutil/trace.go:171","msg":"trace[1453738859] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5237; }","duration":"159.944349ms","start":"2026-05-25T18:02:17.351871Z","end":"2026-05-25T18:02:17.511816Z","steps":["trace[1453738859] 'read index received' (duration: 81.375688ms)","trace[1453738859] 'applied index is now lower than readState.Index' (duration: 78.567101ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:02:17.511908Z","caller":"traceutil/trace.go:171","msg":"trace[2118088263] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"161.023689ms","start":"2026-05-25T18:02:17.350856Z","end":"2026-05-25T18:02:17.511879Z","steps":["trace[2118088263] 'process raft request' (duration: 160.755562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:02:17.512092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.227266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-25T18:02:17.512158Z","caller":"traceutil/trace.go:171","msg":"trace[1013489935] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5045; }","duration":"160.378931ms","start":"2026-05-25T18:02:17.351763Z","end":"2026-05-25T18:02:17.512142Z","steps":["trace[1013489935] 'agreement among raft nodes before linearized reading' (duration: 160.165145ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:30.655164Z","caller":"traceutil/trace.go:171","msg":"trace[787671836] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"198.091163ms","start":"2026-05-25T18:02:30.457048Z","end":"2026-05-25T18:02:30.655139Z","steps":["trace[787671836] 'process raft request' (duration: 197.889627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:02:38.319522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.851508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T18:02:38.319697Z","caller":"traceutil/trace.go:171","msg":"trace[190671720] linearizableReadLoop","detail":"{readStateIndex:5646; appliedIndex:5644; }","duration":"183.541991ms","start":"2026-05-25T18:02:38.136139Z","end":"2026-05-25T18:02:38.319681Z","steps":["trace[190671720] 'read index received' (duration: 36.451227ms)","trace[190671720] 'applied index is now lower than readState.Index' (duration: 147.089444ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:02:38.319785Z","caller":"traceutil/trace.go:171","msg":"trace[1506583887] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"191.871031ms","start":"2026-05-25T18:02:38.127904Z","end":"2026-05-25T18:02:38.319775Z","steps":["trace[1506583887] 'process raft request' (duration: 44.684095ms)","trace[1506583887] 'compare' (duration: 146.706543ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:02:38.320081Z","caller":"traceutil/trace.go:171","msg":"trace[1573365080] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5439; }","duration":"191.934603ms","start":"2026-05-25T18:02:38.128112Z","end":"2026-05-25T18:02:38.320046Z","steps":["trace[1573365080] 'process raft request' (duration: 191.526771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:02:38.320457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.311772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-05-25T18:02:38.320539Z","caller":"traceutil/trace.go:171","msg":"trace[1270313485] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5439; }","duration":"184.445276ms","start":"2026-05-25T18:02:38.13607Z","end":"2026-05-25T18:02:38.320516Z","steps":["trace[1270313485] 'agreement among raft nodes before linearized reading' (duration: 183.897831ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:38.546778Z","caller":"traceutil/trace.go:171","msg":"trace[1132327454] linearizableReadLoop","detail":"{readStateIndex:5647; appliedIndex:5646; }","duration":"204.304125ms","start":"2026-05-25T18:02:38.342438Z","end":"2026-05-25T18:02:38.546742Z","steps":["trace[1132327454] 'read index received' (duration: 108.543099ms)","trace[1132327454] 'applied index is now lower than readState.Index' (duration: 95.758875ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:02:38.546999Z","caller":"traceutil/trace.go:171","msg":"trace[333439137] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"205.388394ms","start":"2026-05-25T18:02:38.341591Z","end":"2026-05-25T18:02:38.54698Z","steps":["trace[333439137] 'process raft request' (duration: 109.399682ms)","trace[333439137] 'compare' (duration: 95.522879ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:02:38.54727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.827009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-05-25T18:02:38.547152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.174631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-25T18:02:38.547347Z","caller":"traceutil/trace.go:171","msg":"trace[178925893] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5440; }","duration":"204.909041ms","start":"2026-05-25T18:02:38.342416Z","end":"2026-05-25T18:02:38.547325Z","steps":["trace[178925893] 'agreement among raft nodes before linearized reading' (duration: 204.784107ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:38.547364Z","caller":"traceutil/trace.go:171","msg":"trace[1439394217] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5440; }","duration":"191.432438ms","start":"2026-05-25T18:02:38.355906Z","end":"2026-05-25T18:02:38.547338Z","steps":["trace[1439394217] 'agreement among raft nodes before linearized reading' (duration: 191.122989ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:38.723929Z","caller":"traceutil/trace.go:171","msg":"trace[480701858] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"168.932287ms","start":"2026-05-25T18:02:38.554961Z","end":"2026-05-25T18:02:38.723893Z","steps":["trace[480701858] 'process raft request' (duration: 149.705815ms)","trace[480701858] 'compare' (duration: 19.040116ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:02:38.724192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.185627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-05-25T18:02:38.724267Z","caller":"traceutil/trace.go:171","msg":"trace[131975401] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5443; }","duration":"151.343631ms","start":"2026-05-25T18:02:38.572908Z","end":"2026-05-25T18:02:38.724252Z","steps":["trace[131975401] 'agreement among raft nodes before linearized reading' (duration: 151.003761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:02:38.724268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.509052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-25T18:02:38.724347Z","caller":"traceutil/trace.go:171","msg":"trace[1464827178] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5443; }","duration":"165.621916ms","start":"2026-05-25T18:02:38.558705Z","end":"2026-05-25T18:02:38.724327Z","steps":["trace[1464827178] 'agreement among raft nodes before linearized reading' (duration: 165.209164ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:52.89424Z","caller":"traceutil/trace.go:171","msg":"trace[1051606795] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"145.85611ms","start":"2026-05-25T18:02:52.748373Z","end":"2026-05-25T18:02:52.894229Z","steps":["trace[1051606795] 'process raft request' (duration: 145.576012ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:52.894298Z","caller":"traceutil/trace.go:171","msg":"trace[2027429118] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"145.496419ms","start":"2026-05-25T18:02:52.74875Z","end":"2026-05-25T18:02:52.894246Z","steps":["trace[2027429118] 'process raft request' (duration: 145.300994ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:52.894319Z","caller":"traceutil/trace.go:171","msg":"trace[691541315] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"115.09278ms","start":"2026-05-25T18:02:52.779175Z","end":"2026-05-25T18:02:52.894268Z","steps":["trace[691541315] 'process raft request' (duration: 114.939406ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:02:52.89407Z","caller":"traceutil/trace.go:171","msg":"trace[1806150742] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"145.87526ms","start":"2026-05-25T18:02:52.748169Z","end":"2026-05-25T18:02:52.894044Z","steps":["trace[1806150742] 'process raft request' (duration: 75.453374ms)","trace[1806150742] 'compare' (duration: 70.068616ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:03:47.963318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3008} {"level":"info","ts":"2026-05-25T18:03:48.054661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3008,"took":"89.797577ms","hash":1510388506,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":31817728,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T18:03:48.054747Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1510388506,"revision":3008,"compact-revision":-1} {"level":"info","ts":"2026-05-25T18:06:25.615738Z","caller":"traceutil/trace.go:171","msg":"trace[1917884841] transaction","detail":"{read_only:false; response_revision:7127; number_of_response:1; }","duration":"400.501705ms","start":"2026-05-25T18:06:25.215207Z","end":"2026-05-25T18:06:25.615709Z","steps":["trace[1917884841] 'process raft request' (duration: 400.179006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:06:25.615891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:06:25.215187Z","time spent":"400.624819ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3951,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:07:50.575565Z","caller":"traceutil/trace.go:171","msg":"trace[827725661] transaction","detail":"{read_only:false; response_revision:7762; number_of_response:1; }","duration":"676.96465ms","start":"2026-05-25T18:07:49.898573Z","end":"2026-05-25T18:07:50.575538Z","steps":["trace[827725661] 'process raft request' (duration: 676.765584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:07:50.575752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:07:49.898535Z","time spent":"677.126224ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:07:50.58046Z","caller":"traceutil/trace.go:171","msg":"trace[468895738] transaction","detail":"{read_only:false; response_revision:7763; number_of_response:1; }","duration":"536.32163ms","start":"2026-05-25T18:07:50.044115Z","end":"2026-05-25T18:07:50.580436Z","steps":["trace[468895738] 'process raft request' (duration: 536.055393ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:07:50.580448Z","caller":"traceutil/trace.go:171","msg":"trace[875598349] linearizableReadLoop","detail":"{readStateIndex:8043; appliedIndex:8040; }","duration":"182.718623ms","start":"2026-05-25T18:07:50.397694Z","end":"2026-05-25T18:07:50.580413Z","steps":["trace[875598349] 'read index received' (duration: 177.649873ms)","trace[875598349] 'applied index is now lower than readState.Index' (duration: 5.06714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:07:50.580568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:07:50.044075Z","time spent":"536.439544ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:07:50.580629Z","caller":"traceutil/trace.go:171","msg":"trace[2119391288] transaction","detail":"{read_only:false; response_revision:7764; number_of_response:1; }","duration":"534.776817ms","start":"2026-05-25T18:07:50.045837Z","end":"2026-05-25T18:07:50.580614Z","steps":["trace[2119391288] 'process raft request' (duration: 534.52087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:07:50.580655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.962439ms","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":"info","ts":"2026-05-25T18:07:50.580702Z","caller":"traceutil/trace.go:171","msg":"trace[2103487769] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:7764; }","duration":"183.060111ms","start":"2026-05-25T18:07:50.397629Z","end":"2026-05-25T18:07:50.58069Z","steps":["trace[2103487769] 'agreement among raft nodes before linearized reading' (duration: 182.958939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:07:50.580783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:07:50.045828Z","time spent":"534.84582ms","remote":"127.0.0.1:49858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6456,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:08:47.970023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-05-25T18:08:48.059705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"87.564591ms","hash":2233267958,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":21790720,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-25T18:08:48.059772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2233267958,"revision":6103,"compact-revision":3008} {"level":"info","ts":"2026-05-25T18:08:52.916675Z","caller":"traceutil/trace.go:171","msg":"trace[1000375991] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"104.264352ms","start":"2026-05-25T18:08:52.81238Z","end":"2026-05-25T18:08:52.916645Z","steps":["trace[1000375991] 'process raft request' (duration: 103.924083ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:08:53.062499Z","caller":"traceutil/trace.go:171","msg":"trace[419528207] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"148.522586ms","start":"2026-05-25T18:08:52.913943Z","end":"2026-05-25T18:08:53.062466Z","steps":["trace[419528207] 'process raft request' (duration: 148.469844ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:08:53.062604Z","caller":"traceutil/trace.go:171","msg":"trace[162002222] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"151.038356ms","start":"2026-05-25T18:08:52.911543Z","end":"2026-05-25T18:08:53.062581Z","steps":["trace[162002222] 'process raft request' (duration: 150.732827ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:08:53.064943Z","caller":"traceutil/trace.go:171","msg":"trace[1625626369] transaction","detail":"{read_only:false; response_revision:8326; number_of_response:1; }","duration":"142.933241ms","start":"2026-05-25T18:08:52.921976Z","end":"2026-05-25T18:08:53.064909Z","steps":["trace[1625626369] 'process raft request' (duration: 142.685124ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:05.269243Z","caller":"traceutil/trace.go:171","msg":"trace[1415054370] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"161.359761ms","start":"2026-05-25T18:09:05.107857Z","end":"2026-05-25T18:09:05.269216Z","steps":["trace[1415054370] 'process raft request' (duration: 161.228177ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:05.287586Z","caller":"traceutil/trace.go:171","msg":"trace[896782391] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"177.716663ms","start":"2026-05-25T18:09:05.109835Z","end":"2026-05-25T18:09:05.287552Z","steps":["trace[896782391] 'process raft request' (duration: 177.554229ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:05.287739Z","caller":"traceutil/trace.go:171","msg":"trace[1925319391] linearizableReadLoop","detail":"{readStateIndex:8689; appliedIndex:8688; }","duration":"176.021996ms","start":"2026-05-25T18:09:05.11169Z","end":"2026-05-25T18:09:05.287712Z","steps":["trace[1925319391] 'read index received' (duration: 158.097621ms)","trace[1925319391] 'applied index is now lower than readState.Index' (duration: 17.922325ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:09:05.287844Z","caller":"traceutil/trace.go:171","msg":"trace[1285038962] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"127.439553ms","start":"2026-05-25T18:09:05.160356Z","end":"2026-05-25T18:09:05.287796Z","steps":["trace[1285038962] 'process raft request' (duration: 127.152255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:05.288009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.296704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-25T18:09:05.288077Z","caller":"traceutil/trace.go:171","msg":"trace[1372966794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8389; }","duration":"176.408297ms","start":"2026-05-25T18:09:05.11165Z","end":"2026-05-25T18:09:05.288058Z","steps":["trace[1372966794] 'agreement among raft nodes before linearized reading' (duration: 176.202451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:05.288111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.85533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-597gw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T18:09:05.288164Z","caller":"traceutil/trace.go:171","msg":"trace[2045485908] range","detail":"{range_begin:/registry/pods/openstack/keepalived-597gw; range_end:; response_count:1; response_revision:8389; }","duration":"163.941212ms","start":"2026-05-25T18:09:05.124211Z","end":"2026-05-25T18:09:05.288153Z","steps":["trace[2045485908] 'agreement among raft nodes before linearized reading' (duration: 163.788057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:05.288669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.558609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-25T18:09:05.288758Z","caller":"traceutil/trace.go:171","msg":"trace[1268945351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8389; }","duration":"168.723874ms","start":"2026-05-25T18:09:05.120012Z","end":"2026-05-25T18:09:05.288736Z","steps":["trace[1268945351] 'agreement among raft nodes before linearized reading' (duration: 167.944442ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:33.143878Z","caller":"traceutil/trace.go:171","msg":"trace[1718608431] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"134.307722ms","start":"2026-05-25T18:09:33.009538Z","end":"2026-05-25T18:09:33.143846Z","steps":["trace[1718608431] 'process raft request' (duration: 134.079636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:33.319381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.125084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T18:09:33.319515Z","caller":"traceutil/trace.go:171","msg":"trace[920786254] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"236.856088ms","start":"2026-05-25T18:09:33.082634Z","end":"2026-05-25T18:09:33.31949Z","steps":["trace[920786254] 'process raft request' (duration: 75.524128ms)","trace[920786254] 'compare' (duration: 160.97015ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:09:36.776028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.185776ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"warn","ts":"2026-05-25T18:09:41.863836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881176775090949,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T18:09:42.364193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881176775090949,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T18:09:42.380486Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.036000788s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T18:09:42.380844Z","caller":"traceutil/trace.go:171","msg":"trace[1222731772] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"1.036443471s","start":"2026-05-25T18:09:41.344373Z","end":"2026-05-25T18:09:42.380816Z","steps":["trace[1222731772] 'process raft request' (duration: 1.036277376s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.380995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.344348Z","time spent":"1.036562714s","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:09:42.406242Z","caller":"traceutil/trace.go:171","msg":"trace[981335966] linearizableReadLoop","detail":"{readStateIndex:8950; appliedIndex:8946; }","duration":"1.042630301s","start":"2026-05-25T18:09:41.363595Z","end":"2026-05-25T18:09:42.406226Z","steps":["trace[981335966] 'read index received' (duration: 1.017122776s)","trace[981335966] 'applied index is now lower than readState.Index' (duration: 25.506585ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:09:42.406493Z","caller":"traceutil/trace.go:171","msg":"trace[2005179008] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"1.011323646s","start":"2026-05-25T18:09:41.395157Z","end":"2026-05-25T18:09:42.40648Z","steps":["trace[2005179008] 'process raft request' (duration: 1.010901484s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.406585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.395141Z","time spent":"1.011387937s","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:09:42.406784Z","caller":"traceutil/trace.go:171","msg":"trace[635679789] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"998.907672ms","start":"2026-05-25T18:09:41.407865Z","end":"2026-05-25T18:09:42.406773Z","steps":["trace[635679789] 'process raft request' (duration: 998.284435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.406845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.407839Z","time spent":"998.972864ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:09:42.407047Z","caller":"traceutil/trace.go:171","msg":"trace[1128442673] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"998.313616ms","start":"2026-05-25T18:09:41.408722Z","end":"2026-05-25T18:09:42.407036Z","steps":["trace[1128442673] 'process raft request' (duration: 997.467703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.407107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.408711Z","time spent":"998.359117ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:09:42.407274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043685871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/rook-ceph-cluster\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:09:42.407315Z","caller":"traceutil/trace.go:171","msg":"trace[2020253221] range","detail":"{range_begin:/registry/ingress/openstack/rook-ceph-cluster; range_end:; response_count:0; response_revision:8638; }","duration":"1.043758863s","start":"2026-05-25T18:09:41.363547Z","end":"2026-05-25T18:09:42.407306Z","steps":["trace[2020253221] 'agreement among raft nodes before linearized reading' (duration: 1.043695681s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.407344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.363533Z","time spent":"1.043804684s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":26,"request content":"key:\"/registry/ingress/openstack/rook-ceph-cluster\" "} {"level":"warn","ts":"2026-05-25T18:09:42.407583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.203548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T18:09:42.407622Z","caller":"traceutil/trace.go:171","msg":"trace[629426323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8638; }","duration":"941.27759ms","start":"2026-05-25T18:09:41.466335Z","end":"2026-05-25T18:09:42.407613Z","steps":["trace[629426323] 'agreement among raft nodes before linearized reading' (duration: 941.157327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.407648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.466312Z","time spent":"941.330332ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T18:09:42.407991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.570122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:09:42.408044Z","caller":"traceutil/trace.go:171","msg":"trace[778027838] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8638; }","duration":"362.672446ms","start":"2026-05-25T18:09:42.04536Z","end":"2026-05-25T18:09:42.408032Z","steps":["trace[778027838] 'agreement among raft nodes before linearized reading' (duration: 362.588824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.408083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:42.045337Z","time spent":"362.737977ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":26,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-05-25T18:09:42.408316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.255134ms","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-05-25T18:09:42.408359Z","caller":"traceutil/trace.go:171","msg":"trace[1014395907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8638; }","duration":"498.333916ms","start":"2026-05-25T18:09:41.910013Z","end":"2026-05-25T18:09:42.408347Z","steps":["trace[1014395907] 'agreement among raft nodes before linearized reading' (duration: 498.235293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.408388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.90999Z","time spent":"498.391338ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T18:09:42.408584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.215832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-25T18:09:42.408599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.691434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-25T18:09:42.408651Z","caller":"traceutil/trace.go:171","msg":"trace[1847149432] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8638; }","duration":"927.355716ms","start":"2026-05-25T18:09:41.481278Z","end":"2026-05-25T18:09:42.408634Z","steps":["trace[1847149432] 'agreement among raft nodes before linearized reading' (duration: 927.125909ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:42.408661Z","caller":"traceutil/trace.go:171","msg":"trace[166286533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8638; }","duration":"936.776076ms","start":"2026-05-25T18:09:41.471869Z","end":"2026-05-25T18:09:42.408645Z","steps":["trace[166286533] 'agreement among raft nodes before linearized reading' (duration: 936.638312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.408687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.481253Z","time spent":"927.426878ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-25T18:09:42.408706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.471858Z","time spent":"936.835958ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T18:09:42.408789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.813121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:09:42.408809Z","caller":"traceutil/trace.go:171","msg":"trace[1136222089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8638; }","duration":"858.859372ms","start":"2026-05-25T18:09:41.549945Z","end":"2026-05-25T18:09:42.408805Z","steps":["trace[1136222089] 'agreement among raft nodes before linearized reading' (duration: 858.825451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.408823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.549932Z","time spent":"858.888283ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T18:09:42.408833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.623106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-25T18:09:42.408887Z","caller":"traceutil/trace.go:171","msg":"trace[2011210305] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8638; }","duration":"807.713618ms","start":"2026-05-25T18:09:41.60116Z","end":"2026-05-25T18:09:42.408874Z","steps":["trace[2011210305] 'agreement among raft nodes before linearized reading' (duration: 807.586195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.408923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.601147Z","time spent":"807.769799ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T18:09:42.408965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.08079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-597gw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T18:09:42.409037Z","caller":"traceutil/trace.go:171","msg":"trace[1016192224] range","detail":"{range_begin:/registry/pods/openstack/keepalived-597gw; range_end:; response_count:1; response_revision:8638; }","duration":"892.188903ms","start":"2026-05-25T18:09:41.516832Z","end":"2026-05-25T18:09:42.409021Z","steps":["trace[1016192224] 'agreement among raft nodes before linearized reading' (duration: 892.042559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.409094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.914244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-25T18:09:42.40912Z","caller":"traceutil/trace.go:171","msg":"trace[1987763149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8638; }","duration":"637.979246ms","start":"2026-05-25T18:09:41.771135Z","end":"2026-05-25T18:09:42.409114Z","steps":["trace[1987763149] 'agreement among raft nodes before linearized reading' (duration: 637.912814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.409139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.771111Z","time spent":"638.023638ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T18:09:42.409133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.516815Z","time spent":"892.302047ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-597gw\" "} {"level":"warn","ts":"2026-05-25T18:09:42.40914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.104193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T18:09:42.409187Z","caller":"traceutil/trace.go:171","msg":"trace[1322934086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8638; }","duration":"505.198106ms","start":"2026-05-25T18:09:41.903975Z","end":"2026-05-25T18:09:42.409173Z","steps":["trace[1322934086] 'agreement among raft nodes before linearized reading' (duration: 505.073812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:42.40924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:41.90395Z","time spent":"505.278368ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T18:09:43.648005Z","caller":"traceutil/trace.go:171","msg":"trace[1234548856] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"600.12391ms","start":"2026-05-25T18:09:43.047855Z","end":"2026-05-25T18:09:43.647979Z","steps":["trace[1234548856] 'process raft request' (duration: 599.946035ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:43.648064Z","caller":"traceutil/trace.go:171","msg":"trace[1046569368] linearizableReadLoop","detail":"{readStateIndex:8987; appliedIndex:8987; }","duration":"314.176265ms","start":"2026-05-25T18:09:43.333858Z","end":"2026-05-25T18:09:43.648035Z","steps":["trace[1046569368] 'read index received' (duration: 314.083232ms)","trace[1046569368] 'applied index is now lower than readState.Index' (duration: 90.712µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:09:43.648189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:43.047839Z","time spent":"600.254483ms","remote":"127.0.0.1:58666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:09:43.648933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.238171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:09:43.648994Z","caller":"traceutil/trace.go:171","msg":"trace[1161555252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8674; }","duration":"100.324084ms","start":"2026-05-25T18:09:43.548654Z","end":"2026-05-25T18:09:43.648978Z","steps":["trace[1161555252] 'agreement among raft nodes before linearized reading' (duration: 100.20798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:43.649172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.289206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224963"} {"level":"info","ts":"2026-05-25T18:09:43.649242Z","caller":"traceutil/trace.go:171","msg":"trace[1908119863] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8673; }","duration":"315.41708ms","start":"2026-05-25T18:09:43.333811Z","end":"2026-05-25T18:09:43.649228Z","steps":["trace[1908119863] 'agreement among raft nodes before linearized reading' (duration: 314.341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:43.649287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:43.333787Z","time spent":"315.488621ms","remote":"127.0.0.1:49736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2224984,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-05-25T18:09:43.649353Z","caller":"traceutil/trace.go:171","msg":"trace[275894871] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"243.423559ms","start":"2026-05-25T18:09:43.405909Z","end":"2026-05-25T18:09:43.649332Z","steps":["trace[275894871] 'process raft request' (duration: 242.885465ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:46.836682Z","caller":"traceutil/trace.go:171","msg":"trace[1169192] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:9001; }","duration":"328.46787ms","start":"2026-05-25T18:09:46.508172Z","end":"2026-05-25T18:09:46.83664Z","steps":["trace[1169192] 'read index received' (duration: 325.519909ms)","trace[1169192] 'applied index is now lower than readState.Index' (duration: 2.946251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:09:46.83687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.668646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T18:09:46.836983Z","caller":"traceutil/trace.go:171","msg":"trace[577020067] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8687; }","duration":"328.8265ms","start":"2026-05-25T18:09:46.508135Z","end":"2026-05-25T18:09:46.836962Z","steps":["trace[577020067] 'agreement among raft nodes before linearized reading' (duration: 328.634785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.837051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.508113Z","time spent":"328.927293ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":28,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-05-25T18:09:46.838023Z","caller":"traceutil/trace.go:171","msg":"trace[879539566] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"329.488438ms","start":"2026-05-25T18:09:46.508496Z","end":"2026-05-25T18:09:46.837984Z","steps":["trace[879539566] 'process raft request' (duration: 328.679356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.838126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.508469Z","time spent":"329.596552ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:09:46.838319Z","caller":"traceutil/trace.go:171","msg":"trace[1168758883] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"329.475298ms","start":"2026-05-25T18:09:46.508801Z","end":"2026-05-25T18:09:46.838277Z","steps":["trace[1168758883] 'process raft request' (duration: 329.083078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.838493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.508779Z","time spent":"329.626562ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:09:46.839009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.26203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-597gw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T18:09:46.839116Z","caller":"traceutil/trace.go:171","msg":"trace[488452233] range","detail":"{range_begin:/registry/pods/openstack/keepalived-597gw; range_end:; response_count:1; response_revision:8691; }","duration":"330.433735ms","start":"2026-05-25T18:09:46.508656Z","end":"2026-05-25T18:09:46.83909Z","steps":["trace[488452233] 'agreement among raft nodes before linearized reading' (duration: 330.145177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.839174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.50864Z","time spent":"330.524697ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-597gw\" "} {"level":"warn","ts":"2026-05-25T18:09:46.839555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.372827ms","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-05-25T18:09:46.839585Z","caller":"traceutil/trace.go:171","msg":"trace[980385456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8691; }","duration":"320.421737ms","start":"2026-05-25T18:09:46.519155Z","end":"2026-05-25T18:09:46.839577Z","steps":["trace[980385456] 'agreement among raft nodes before linearized reading' (duration: 320.343555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.839604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.519141Z","time spent":"320.457399ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T18:09:46.839575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.984862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:09:46.839641Z","caller":"traceutil/trace.go:171","msg":"trace[746512642] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"330.744553ms","start":"2026-05-25T18:09:46.508888Z","end":"2026-05-25T18:09:46.839633Z","steps":["trace[746512642] 'process raft request' (duration: 329.102448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.839729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.508869Z","time spent":"330.804495ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:09:46.83975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.472176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-25T18:09:46.839775Z","caller":"traceutil/trace.go:171","msg":"trace[505690493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8691; }","duration":"330.496957ms","start":"2026-05-25T18:09:46.509272Z","end":"2026-05-25T18:09:46.839769Z","steps":["trace[505690493] 'agreement among raft nodes before linearized reading' (duration: 330.424785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.839794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.50922Z","time spent":"330.569068ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T18:09:46.839811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.454198ms","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":"warn","ts":"2026-05-25T18:09:46.839925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.731553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-25T18:09:46.839946Z","caller":"traceutil/trace.go:171","msg":"trace[539329702] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:8691; }","duration":"125.616922ms","start":"2026-05-25T18:09:46.714286Z","end":"2026-05-25T18:09:46.839903Z","steps":["trace[539329702] 'agreement among raft nodes before linearized reading' (duration: 125.384586ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:46.84002Z","caller":"traceutil/trace.go:171","msg":"trace[1709015009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8691; }","duration":"330.822585ms","start":"2026-05-25T18:09:46.509151Z","end":"2026-05-25T18:09:46.839974Z","steps":["trace[1709015009] 'agreement among raft nodes before linearized reading' (duration: 330.61108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.840075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.509134Z","time spent":"330.932738ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-25T18:09:46.840095Z","caller":"traceutil/trace.go:171","msg":"trace[2021288451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8691; }","duration":"292.559877ms","start":"2026-05-25T18:09:46.547517Z","end":"2026-05-25T18:09:46.840076Z","steps":["trace[2021288451] 'agreement among raft nodes before linearized reading' (duration: 291.961611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.840133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.589427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:09:46.840198Z","caller":"traceutil/trace.go:171","msg":"trace[1011189217] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8691; }","duration":"199.69922ms","start":"2026-05-25T18:09:46.640485Z","end":"2026-05-25T18:09:46.840184Z","steps":["trace[1011189217] 'agreement among raft nodes before linearized reading' (duration: 199.582167ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:46.839591Z","caller":"traceutil/trace.go:171","msg":"trace[1911136371] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"330.61028ms","start":"2026-05-25T18:09:46.508945Z","end":"2026-05-25T18:09:46.839555Z","steps":["trace[1911136371] 'process raft request' (duration: 329.799207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.840346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.508908Z","time spent":"331.364311ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:09:46.840397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.155285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-25T18:09:46.840154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.114107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:09:46.840427Z","caller":"traceutil/trace.go:171","msg":"trace[349823859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8691; }","duration":"331.196446ms","start":"2026-05-25T18:09:46.509224Z","end":"2026-05-25T18:09:46.84042Z","steps":["trace[349823859] 'agreement among raft nodes before linearized reading' (duration: 330.691622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.840457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.509218Z","time spent":"331.233787ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T18:09:46.840462Z","caller":"traceutil/trace.go:171","msg":"trace[372507024] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8691; }","duration":"185.964261ms","start":"2026-05-25T18:09:46.65448Z","end":"2026-05-25T18:09:46.840444Z","steps":["trace[372507024] 'agreement among raft nodes before linearized reading' (duration: 185.092827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.840461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.219377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T18:09:46.840529Z","caller":"traceutil/trace.go:171","msg":"trace[1805069594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8691; }","duration":"331.339509ms","start":"2026-05-25T18:09:46.509178Z","end":"2026-05-25T18:09:46.840517Z","steps":["trace[1805069594] 'agreement among raft nodes before linearized reading' (duration: 331.051831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:46.840559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:09:46.509155Z","time spent":"331.397951ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-25T18:09:48.975256Z","caller":"traceutil/trace.go:171","msg":"trace[1931176197] transaction","detail":"{read_only:false; response_revision:8714; number_of_response:1; }","duration":"129.48336ms","start":"2026-05-25T18:09:48.845739Z","end":"2026-05-25T18:09:48.975222Z","steps":["trace[1931176197] 'process raft request' (duration: 129.228003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:48.975555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.036314ms","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-05-25T18:09:48.975294Z","caller":"traceutil/trace.go:171","msg":"trace[915776112] linearizableReadLoop","detail":"{readStateIndex:9031; appliedIndex:9027; }","duration":"125.799358ms","start":"2026-05-25T18:09:48.849462Z","end":"2026-05-25T18:09:48.975261Z","steps":["trace[915776112] 'read index received' (duration: 86.116071ms)","trace[915776112] 'applied index is now lower than readState.Index' (duration: 39.681817ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:09:48.975719Z","caller":"traceutil/trace.go:171","msg":"trace[2083752397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8716; }","duration":"126.264501ms","start":"2026-05-25T18:09:48.849432Z","end":"2026-05-25T18:09:48.975696Z","steps":["trace[2083752397] 'agreement among raft nodes before linearized reading' (duration: 125.927741ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:48.975371Z","caller":"traceutil/trace.go:171","msg":"trace[647152770] transaction","detail":"{read_only:false; response_revision:8715; number_of_response:1; }","duration":"129.173561ms","start":"2026-05-25T18:09:48.846163Z","end":"2026-05-25T18:09:48.975337Z","steps":["trace[647152770] 'process raft request' (duration: 128.946415ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:48.975418Z","caller":"traceutil/trace.go:171","msg":"trace[852481209] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"129.132139ms","start":"2026-05-25T18:09:48.846261Z","end":"2026-05-25T18:09:48.975394Z","steps":["trace[852481209] 'process raft request' (duration: 128.913273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:48.976121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.404059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-25T18:09:48.976148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.148781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-597gw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T18:09:48.976194Z","caller":"traceutil/trace.go:171","msg":"trace[679788825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8716; }","duration":"124.46737ms","start":"2026-05-25T18:09:48.851686Z","end":"2026-05-25T18:09:48.976154Z","steps":["trace[679788825] 'agreement among raft nodes before linearized reading' (duration: 124.302346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:48.976216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.300503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-25T18:09:48.97629Z","caller":"traceutil/trace.go:171","msg":"trace[1425465844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8716; }","duration":"125.441728ms","start":"2026-05-25T18:09:48.850835Z","end":"2026-05-25T18:09:48.976277Z","steps":["trace[1425465844] 'agreement among raft nodes before linearized reading' (duration: 125.275883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:48.976294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.547089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-25T18:09:48.97622Z","caller":"traceutil/trace.go:171","msg":"trace[348703670] range","detail":"{range_begin:/registry/pods/openstack/keepalived-597gw; range_end:; response_count:1; response_revision:8716; }","duration":"120.231044ms","start":"2026-05-25T18:09:48.855971Z","end":"2026-05-25T18:09:48.976202Z","steps":["trace[348703670] 'agreement among raft nodes before linearized reading' (duration: 120.07885ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:48.976332Z","caller":"traceutil/trace.go:171","msg":"trace[361623452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8716; }","duration":"126.903838ms","start":"2026-05-25T18:09:48.84942Z","end":"2026-05-25T18:09:48.976324Z","steps":["trace[361623452] 'agreement among raft nodes before linearized reading' (duration: 126.470556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:48.976394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.895673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-25T18:09:48.97623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.720307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-05-25T18:09:48.9765Z","caller":"traceutil/trace.go:171","msg":"trace[2105294843] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8716; }","duration":"121.012016ms","start":"2026-05-25T18:09:48.855479Z","end":"2026-05-25T18:09:48.976491Z","steps":["trace[2105294843] 'agreement among raft nodes before linearized reading' (duration: 120.636625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:09:48.976659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.368582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-05-25T18:09:48.976433Z","caller":"traceutil/trace.go:171","msg":"trace[1186595795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8716; }","duration":"124.952594ms","start":"2026-05-25T18:09:48.851471Z","end":"2026-05-25T18:09:48.976424Z","steps":["trace[1186595795] 'agreement among raft nodes before linearized reading' (duration: 124.514502ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:09:48.976752Z","caller":"traceutil/trace.go:171","msg":"trace[470124558] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8716; }","duration":"122.491156ms","start":"2026-05-25T18:09:48.854242Z","end":"2026-05-25T18:09:48.976733Z","steps":["trace[470124558] 'agreement among raft nodes before linearized reading' (duration: 121.701755ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:10:01.892895Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c6.wal"} {"level":"info","ts":"2026-05-25T18:11:31.504997Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T18:11:31.511597Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-25T18:11:31.511732Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-25T18:13:47.97738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8170} {"level":"info","ts":"2026-05-25T18:13:48.040114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8170,"took":"60.101841ms","hash":340792789,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":29822976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T18:13:48.040196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":340792789,"revision":8170,"compact-revision":6103} {"level":"info","ts":"2026-05-25T18:14:26.135044Z","caller":"traceutil/trace.go:171","msg":"trace[531810241] transaction","detail":"{read_only:false; response_revision:11190; number_of_response:1; }","duration":"196.058688ms","start":"2026-05-25T18:14:25.938963Z","end":"2026-05-25T18:14:26.135022Z","steps":["trace[531810241] 'process raft request' (duration: 195.757309ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:14:26.134793Z","caller":"traceutil/trace.go:171","msg":"trace[1745405633] transaction","detail":"{read_only:false; response_revision:11189; number_of_response:1; }","duration":"195.80193ms","start":"2026-05-25T18:14:25.938963Z","end":"2026-05-25T18:14:26.134765Z","steps":["trace[1745405633] 'process raft request' (duration: 195.617745ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:14:26.135248Z","caller":"traceutil/trace.go:171","msg":"trace[465900293] transaction","detail":"{read_only:false; response_revision:11191; number_of_response:1; }","duration":"196.014676ms","start":"2026-05-25T18:14:25.939219Z","end":"2026-05-25T18:14:26.135234Z","steps":["trace[465900293] 'process raft request' (duration: 195.730839ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:14:43.147667Z","caller":"traceutil/trace.go:171","msg":"trace[766248541] linearizableReadLoop","detail":"{readStateIndex:11672; appliedIndex:11671; }","duration":"123.955295ms","start":"2026-05-25T18:14:43.02368Z","end":"2026-05-25T18:14:43.147635Z","steps":["trace[766248541] 'read index received' (duration: 123.77006ms)","trace[766248541] 'applied index is now lower than readState.Index' (duration: 183.955µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:14:43.147764Z","caller":"traceutil/trace.go:171","msg":"trace[2031009575] transaction","detail":"{read_only:false; response_revision:11287; number_of_response:1; }","duration":"136.237914ms","start":"2026-05-25T18:14:43.01147Z","end":"2026-05-25T18:14:43.147708Z","steps":["trace[2031009575] 'process raft request' (duration: 135.954507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:14:43.147918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.212272ms","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-05-25T18:14:43.148013Z","caller":"traceutil/trace.go:171","msg":"trace[344823326] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:11287; }","duration":"124.351936ms","start":"2026-05-25T18:14:43.023639Z","end":"2026-05-25T18:14:43.14799Z","steps":["trace[344823326] 'agreement among raft nodes before linearized reading' (duration: 124.169091ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:14:44.384599Z","caller":"traceutil/trace.go:171","msg":"trace[1654824055] transaction","detail":"{read_only:false; response_revision:11289; number_of_response:1; }","duration":"190.78637ms","start":"2026-05-25T18:14:44.193743Z","end":"2026-05-25T18:14:44.384529Z","steps":["trace[1654824055] 'process raft request' (duration: 190.594934ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:14:44.397576Z","caller":"traceutil/trace.go:171","msg":"trace[787689572] transaction","detail":"{read_only:false; response_revision:11290; number_of_response:1; }","duration":"193.957517ms","start":"2026-05-25T18:14:44.203592Z","end":"2026-05-25T18:14:44.397549Z","steps":["trace[787689572] 'process raft request' (duration: 193.791203ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:14:44.397671Z","caller":"traceutil/trace.go:171","msg":"trace[16423594] transaction","detail":"{read_only:false; response_revision:11291; number_of_response:1; }","duration":"191.804638ms","start":"2026-05-25T18:14:44.205843Z","end":"2026-05-25T18:14:44.397647Z","steps":["trace[16423594] 'process raft request' (duration: 191.635673ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:18:47.983633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10789} {"level":"info","ts":"2026-05-25T18:18:48.065501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10789,"took":"79.504046ms","hash":89018650,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":26968064,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-25T18:18:48.065554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":89018650,"revision":10789,"compact-revision":8170} {"level":"info","ts":"2026-05-25T18:21:20.120452Z","caller":"traceutil/trace.go:171","msg":"trace[171832450] transaction","detail":"{read_only:false; response_revision:13937; number_of_response:1; }","duration":"173.314783ms","start":"2026-05-25T18:21:19.947105Z","end":"2026-05-25T18:21:20.12042Z","steps":["trace[171832450] 'process raft request' (duration: 173.151728ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:21:20.284333Z","caller":"traceutil/trace.go:171","msg":"trace[444405517] linearizableReadLoop","detail":"{readStateIndex:14418; appliedIndex:14415; }","duration":"160.739005ms","start":"2026-05-25T18:21:20.123558Z","end":"2026-05-25T18:21:20.284297Z","steps":["trace[444405517] 'read index received' (duration: 158.180805ms)","trace[444405517] 'applied index is now lower than readState.Index' (duration: 2.55632ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:21:20.284769Z","caller":"traceutil/trace.go:171","msg":"trace[1340720575] transaction","detail":"{read_only:false; response_revision:13938; number_of_response:1; }","duration":"336.199347ms","start":"2026-05-25T18:21:19.948552Z","end":"2026-05-25T18:21:20.284751Z","steps":["trace[1340720575] 'process raft request' (duration: 333.4092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:21:20.284889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:21:19.948539Z","time spent":"336.266588ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:21:20.285079Z","caller":"traceutil/trace.go:171","msg":"trace[364065942] transaction","detail":"{read_only:false; response_revision:13939; number_of_response:1; }","duration":"335.777765ms","start":"2026-05-25T18:21:19.949291Z","end":"2026-05-25T18:21:20.285069Z","steps":["trace[364065942] 'process raft request' (duration: 334.111639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:21:20.285137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:21:19.949283Z","time spent":"335.824776ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T18:21:20.285323Z","caller":"traceutil/trace.go:171","msg":"trace[1869948995] transaction","detail":"{read_only:false; response_revision:13940; number_of_response:1; }","duration":"327.988769ms","start":"2026-05-25T18:21:19.95731Z","end":"2026-05-25T18:21:20.285299Z","steps":["trace[1869948995] 'process raft request' (duration: 326.151878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:21:20.285401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T18:21:19.957295Z","time spent":"328.065642ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T18:21:20.285468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.879066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-597gw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T18:21:20.285546Z","caller":"traceutil/trace.go:171","msg":"trace[2087240152] range","detail":"{range_begin:/registry/pods/openstack/keepalived-597gw; range_end:; response_count:1; response_revision:13940; }","duration":"162.02186ms","start":"2026-05-25T18:21:20.123507Z","end":"2026-05-25T18:21:20.285529Z","steps":["trace[2087240152] 'agreement among raft nodes before linearized reading' (duration: 161.791154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:21:29.152978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.907018ms","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:5"} {"level":"info","ts":"2026-05-25T18:21:29.153135Z","caller":"traceutil/trace.go:171","msg":"trace[114153089] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:14106; }","duration":"132.095043ms","start":"2026-05-25T18:21:29.020981Z","end":"2026-05-25T18:21:29.153076Z","steps":["trace[114153089] 'count revisions from in-memory index tree' (duration: 131.788964ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:21:29.448957Z","caller":"traceutil/trace.go:171","msg":"trace[2132696619] transaction","detail":"{read_only:false; response_revision:14107; number_of_response:1; }","duration":"226.889154ms","start":"2026-05-25T18:21:29.222039Z","end":"2026-05-25T18:21:29.448928Z","steps":["trace[2132696619] 'process raft request' (duration: 226.74084ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:21:29.457676Z","caller":"traceutil/trace.go:171","msg":"trace[1836720561] transaction","detail":"{read_only:false; response_revision:14108; number_of_response:1; }","duration":"234.635658ms","start":"2026-05-25T18:21:29.223003Z","end":"2026-05-25T18:21:29.457639Z","steps":["trace[1836720561] 'process raft request' (duration: 232.830968ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:23:08.778832Z","caller":"traceutil/trace.go:171","msg":"trace[395851415] transaction","detail":"{read_only:false; response_revision:15190; number_of_response:1; }","duration":"110.405193ms","start":"2026-05-25T18:23:08.668379Z","end":"2026-05-25T18:23:08.778784Z","steps":["trace[395851415] 'process raft request' (duration: 107.094242ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:23:08.801452Z","caller":"traceutil/trace.go:171","msg":"trace[861697939] transaction","detail":"{read_only:false; response_revision:15191; number_of_response:1; }","duration":"132.981998ms","start":"2026-05-25T18:23:08.668447Z","end":"2026-05-25T18:23:08.801429Z","steps":["trace[861697939] 'process raft request' (duration: 132.588647ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:23:47.993827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12865} {"level":"info","ts":"2026-05-25T18:23:48.072306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12865,"took":"75.57155ms","hash":1217651722,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":30715904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-25T18:23:48.072402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1217651722,"revision":12865,"compact-revision":10789} {"level":"info","ts":"2026-05-25T18:28:44.091032Z","caller":"traceutil/trace.go:171","msg":"trace[1694161056] transaction","detail":"{read_only:false; response_revision:17682; number_of_response:1; }","duration":"108.776531ms","start":"2026-05-25T18:28:43.982225Z","end":"2026-05-25T18:28:44.091002Z","steps":["trace[1694161056] 'process raft request' (duration: 108.515864ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:28:44.122743Z","caller":"traceutil/trace.go:171","msg":"trace[147634188] transaction","detail":"{read_only:false; response_revision:17683; number_of_response:1; }","duration":"139.713308ms","start":"2026-05-25T18:28:43.982988Z","end":"2026-05-25T18:28:44.122702Z","steps":["trace[147634188] 'process raft request' (duration: 138.604447ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:28:48.045756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15453} {"level":"warn","ts":"2026-05-25T18:28:48.40692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.712595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T18:28:48.407065Z","caller":"traceutil/trace.go:171","msg":"trace[743894573] transaction","detail":"{read_only:false; response_revision:17704; number_of_response:1; }","duration":"272.530654ms","start":"2026-05-25T18:28:48.134512Z","end":"2026-05-25T18:28:48.407043Z","steps":["trace[743894573] 'process raft request' (duration: 105.551552ms)","trace[743894573] 'compare' (duration: 166.478618ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:28:48.531064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15453,"took":"482.756204ms","hash":2327063210,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":31916032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T18:28:48.531122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2327063210,"revision":15453,"compact-revision":12865} {"level":"info","ts":"2026-05-25T18:28:50.005167Z","caller":"traceutil/trace.go:171","msg":"trace[489317259] linearizableReadLoop","detail":"{readStateIndex:18320; appliedIndex:18319; }","duration":"170.327316ms","start":"2026-05-25T18:28:49.83477Z","end":"2026-05-25T18:28:50.005098Z","steps":["trace[489317259] 'read index received' (duration: 170.092239ms)","trace[489317259] 'applied index is now lower than readState.Index' (duration: 233.827µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:28:50.005292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.49966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T18:28:50.005366Z","caller":"traceutil/trace.go:171","msg":"trace[560047397] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:17718; }","duration":"170.639014ms","start":"2026-05-25T18:28:49.834714Z","end":"2026-05-25T18:28:50.005353Z","steps":["trace[560047397] 'agreement among raft nodes before linearized reading' (duration: 170.49804ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:28:50.005737Z","caller":"traceutil/trace.go:171","msg":"trace[2082527956] transaction","detail":"{read_only:false; response_revision:17718; number_of_response:1; }","duration":"194.016361ms","start":"2026-05-25T18:28:49.81114Z","end":"2026-05-25T18:28:50.005157Z","steps":["trace[2082527956] 'process raft request' (duration: 193.744454ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:28:50.006516Z","caller":"traceutil/trace.go:171","msg":"trace[1218187171] transaction","detail":"{read_only:false; response_revision:17719; number_of_response:1; }","duration":"139.356007ms","start":"2026-05-25T18:28:49.867145Z","end":"2026-05-25T18:28:50.006501Z","steps":["trace[1218187171] 'process raft request' (duration: 139.10016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:28:50.200212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.663949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T18:28:50.200301Z","caller":"traceutil/trace.go:171","msg":"trace[446301099] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:17719; }","duration":"172.834744ms","start":"2026-05-25T18:28:50.027446Z","end":"2026-05-25T18:28:50.200281Z","steps":["trace[446301099] 'count revisions from in-memory index tree' (duration: 172.529725ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:28:50.227396Z","caller":"traceutil/trace.go:171","msg":"trace[512934759] transaction","detail":"{read_only:false; response_revision:17720; number_of_response:1; }","duration":"106.090786ms","start":"2026-05-25T18:28:50.121279Z","end":"2026-05-25T18:28:50.22737Z","steps":["trace[512934759] 'process raft request' (duration: 105.916432ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:33:44.245329Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T18:33:44.249899Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-05-25T18:33:44.250039Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-05-25T18:33:48.053129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17702} {"level":"info","ts":"2026-05-25T18:33:48.15149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17702,"took":"95.088192ms","hash":2682295829,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":27328512,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-25T18:33:48.151558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2682295829,"revision":17702,"compact-revision":15453}