{"level":"warn","ts":"2026-08-22T10:33:48.49658Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:33:48.49669Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.182: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.182:2380","--initial-cluster=instance=https://199.204.45.182:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.182:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.182: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-08-22T10:33:48.496766Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:33:48.496773Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-08-22T10:33:48.496792Z","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-08-22T10:33:48.497494Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"]} {"level":"info","ts":"2026-08-22T10:33:48.497578Z","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.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.182: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-08-22T10:33:48.503568Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.078492ms"} {"level":"info","ts":"2026-08-22T10:33:48.510261Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e44ae1545340c14b","cluster-id":"7284b69afd367451"} {"level":"info","ts":"2026-08-22T10:33:48.51036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:33:48.510392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 0"} {"level":"info","ts":"2026-08-22T10:33:48.510413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e44ae1545340c14b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:33:48.510432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 1"} {"level":"info","ts":"2026-08-22T10:33:48.510482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"warn","ts":"2026-08-22T10:33:48.51918Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:33:48.521115Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:33:48.522439Z","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-08-22T10:33:48.525044Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e44ae1545340c14b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:33:48.525295Z","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-08-22T10:33:48.525441Z","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-08-22T10:33:48.525456Z","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-08-22T10:33:48.525323Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e44ae1545340c14b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:33:48.526102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"info","ts":"2026-08-22T10:33:48.527677Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","added-peer-id":"e44ae1545340c14b","added-peer-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-08-22T10:33:48.531096Z","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-08-22T10:33:48.531207Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-08-22T10:33:48.531294Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-08-22T10:33:48.531444Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e44ae1545340c14b","initial-advertise-peer-urls":["https://199.204.45.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:33:48.531487Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:33:49.311106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:33:49.311182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:33:49.311234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgPreVoteResp from e44ae1545340c14b at term 1"} {"level":"info","ts":"2026-08-22T10:33:49.311259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:33:49.31127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgVoteResp from e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-08-22T10:33:49.311287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became leader at term 2"} {"level":"info","ts":"2026-08-22T10:33:49.311302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e44ae1545340c14b elected leader e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-08-22T10:33:49.3124Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e44ae1545340c14b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.182:2379]}","request-path":"/0/members/e44ae1545340c14b/attributes","cluster-id":"7284b69afd367451","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:33:49.312708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:33:49.312715Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:33:49.312833Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:33:49.31289Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:33:49.312942Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:33:49.316408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.182:2379"} {"level":"info","ts":"2026-08-22T10:33:49.316975Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:33:49.321454Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:33:49.32174Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:33:49.321812Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:34:27.768236Z","caller":"traceutil/trace.go:171","msg":"trace[566539641] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"278.74921ms","start":"2026-08-22T10:34:27.48946Z","end":"2026-08-22T10:34:27.768209Z","steps":["trace[566539641] 'process raft request' (duration: 278.624097ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.774735Z","caller":"traceutil/trace.go:171","msg":"trace[1728352179] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"278.911634ms","start":"2026-08-22T10:34:27.495799Z","end":"2026-08-22T10:34:27.77471Z","steps":["trace[1728352179] 'process raft request' (duration: 278.652447ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.774858Z","caller":"traceutil/trace.go:171","msg":"trace[1729783253] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"278.211578ms","start":"2026-08-22T10:34:27.496559Z","end":"2026-08-22T10:34:27.77477Z","steps":["trace[1729783253] 'process raft request' (duration: 278.043574ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.774874Z","caller":"traceutil/trace.go:171","msg":"trace[191740360] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"271.174669ms","start":"2026-08-22T10:34:27.503688Z","end":"2026-08-22T10:34:27.774863Z","steps":["trace[191740360] 'process raft request' (duration: 271.051646ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.774973Z","caller":"traceutil/trace.go:171","msg":"trace[56062716] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"270.679117ms","start":"2026-08-22T10:34:27.504244Z","end":"2026-08-22T10:34:27.774923Z","steps":["trace[56062716] 'process raft request' (duration: 270.542964ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.774954Z","caller":"traceutil/trace.go:171","msg":"trace[56020104] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"272.05799ms","start":"2026-08-22T10:34:27.502824Z","end":"2026-08-22T10:34:27.774881Z","steps":["trace[56020104] 'process raft request' (duration: 271.833625ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.775113Z","caller":"traceutil/trace.go:171","msg":"trace[896201946] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"270.257747ms","start":"2026-08-22T10:34:27.504834Z","end":"2026-08-22T10:34:27.775092Z","steps":["trace[896201946] 'process raft request' (duration: 270.053492ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.775179Z","caller":"traceutil/trace.go:171","msg":"trace[534478312] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"270.466632ms","start":"2026-08-22T10:34:27.504548Z","end":"2026-08-22T10:34:27.775015Z","steps":["trace[534478312] 'process raft request' (duration: 270.299308ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.775264Z","caller":"traceutil/trace.go:171","msg":"trace[1264085501] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"250.690829ms","start":"2026-08-22T10:34:27.524545Z","end":"2026-08-22T10:34:27.775236Z","steps":["trace[1264085501] 'process raft request' (duration: 250.499334ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.775328Z","caller":"traceutil/trace.go:171","msg":"trace[303672283] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"241.107448ms","start":"2026-08-22T10:34:27.534193Z","end":"2026-08-22T10:34:27.7753Z","steps":["trace[303672283] 'process raft request' (duration: 240.948374ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.775484Z","caller":"traceutil/trace.go:171","msg":"trace[2110647707] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"222.869712ms","start":"2026-08-22T10:34:27.552596Z","end":"2026-08-22T10:34:27.775466Z","steps":["trace[2110647707] 'process raft request' (duration: 222.701408ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:27.78419Z","caller":"traceutil/trace.go:171","msg":"trace[1952500253] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"125.948278ms","start":"2026-08-22T10:34:27.658218Z","end":"2026-08-22T10:34:27.784166Z","steps":["trace[1952500253] 'process raft request' (duration: 125.640371ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:33.797891Z","caller":"traceutil/trace.go:171","msg":"trace[1490873099] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"252.04591ms","start":"2026-08-22T10:34:33.545789Z","end":"2026-08-22T10:34:33.797835Z","steps":["trace[1490873099] 'process raft request' (duration: 251.522557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:34.389392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402370672780153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:34:34.890551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402370672780153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:34:34.943237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09159539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-22T10:34:34.943341Z","caller":"traceutil/trace.go:171","msg":"trace[537487682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.091806126s","start":"2026-08-22T10:34:33.851512Z","end":"2026-08-22T10:34:34.943318Z","steps":["trace[537487682] 'range keys from in-memory index tree' (duration: 1.091393475s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:34.943397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:33.851489Z","time spent":"1.091898737s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:34:34.948295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088486006s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:34:34.948783Z","caller":"traceutil/trace.go:171","msg":"trace[1012401050] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"1.060492484s","start":"2026-08-22T10:34:33.888261Z","end":"2026-08-22T10:34:34.948754Z","steps":["trace[1012401050] 'read index received' (duration: 1.06028669s)","trace[1012401050] 'applied index is now lower than readState.Index' (duration: 204.754µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:34:34.948836Z","caller":"traceutil/trace.go:171","msg":"trace[1276443103] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.089172742s","start":"2026-08-22T10:34:33.859642Z","end":"2026-08-22T10:34:34.948814Z","steps":["trace[1276443103] 'process raft request' (duration: 1.088971077s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:34.949052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060775942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-22T10:34:34.949114Z","caller":"traceutil/trace.go:171","msg":"trace[1378345494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"1.060868395s","start":"2026-08-22T10:34:33.888229Z","end":"2026-08-22T10:34:34.949098Z","steps":["trace[1378345494] 'agreement among raft nodes before linearized reading' (duration: 1.06066695s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:34.949156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:33.888218Z","time spent":"1.060926096s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T10:34:34.949414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:33.85961Z","time spent":"1.089277176s","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:34:34.961867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.843362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:34:34.961962Z","caller":"traceutil/trace.go:171","msg":"trace[808428150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"796.025747ms","start":"2026-08-22T10:34:34.165908Z","end":"2026-08-22T10:34:34.961934Z","steps":["trace[808428150] 'agreement among raft nodes before linearized reading' (duration: 795.823762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:34.962004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:34.165882Z","time spent":"796.113669ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:34:36.66661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402370672780162,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:34:36.974491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917116934s","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-08-22T10:34:36.974609Z","caller":"traceutil/trace.go:171","msg":"trace[927965775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"1.917291199s","start":"2026-08-22T10:34:35.057297Z","end":"2026-08-22T10:34:36.974588Z","steps":["trace[927965775] 'range keys from in-memory index tree' (duration: 1.91689018s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:36.97466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:35.057275Z","time spent":"1.917372801s","remote":"127.0.0.1:42220","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-08-22T10:34:36.97446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.823668884s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:34:36.974697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913528558s","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-08-22T10:34:36.974775Z","caller":"traceutil/trace.go:171","msg":"trace[1096669509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"1.91362132s","start":"2026-08-22T10:34:35.061127Z","end":"2026-08-22T10:34:36.974748Z","steps":["trace[1096669509] 'range keys from in-memory index tree' (duration: 1.913332144s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:36.974837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:35.061106Z","time spent":"1.913722062s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T10:34:36.9748Z","caller":"traceutil/trace.go:171","msg":"trace[451258882] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:638; }","duration":"1.823997862s","start":"2026-08-22T10:34:35.150739Z","end":"2026-08-22T10:34:36.974737Z","steps":["trace[451258882] 'range keys from in-memory index tree' (duration: 1.823640254s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:36.976163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.088436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:34:36.976586Z","caller":"traceutil/trace.go:171","msg":"trace[419413523] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.793621824s","start":"2026-08-22T10:34:35.182899Z","end":"2026-08-22T10:34:36.976521Z","steps":["trace[419413523] 'process raft request' (duration: 935.896719ms)","trace[419413523] 'compare' (duration: 855.956653ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:34:36.976785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:35.182872Z","time spent":"1.793811709s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:34:36.977117Z","caller":"traceutil/trace.go:171","msg":"trace[366899386] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"811.276593ms","start":"2026-08-22T10:34:36.165823Z","end":"2026-08-22T10:34:36.977099Z","steps":["trace[366899386] 'read index received' (duration: 808.591898ms)","trace[366899386] 'applied index is now lower than readState.Index' (duration: 2.683725ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:34:36.977373Z","caller":"traceutil/trace.go:171","msg":"trace[1326707219] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.609940752s","start":"2026-08-22T10:34:35.367401Z","end":"2026-08-22T10:34:36.977341Z","steps":["trace[1326707219] 'process raft request' (duration: 1.609160663s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:36.97741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.538489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:34:36.977544Z","caller":"traceutil/trace.go:171","msg":"trace[228468282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"811.769824ms","start":"2026-08-22T10:34:36.165741Z","end":"2026-08-22T10:34:36.977511Z","steps":["trace[228468282] 'agreement among raft nodes before linearized reading' (duration: 811.493408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:36.977555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:35.367373Z","time spent":"1.610082026s","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:34:36.977613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:36.165717Z","time spent":"811.887717ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:34:38.86457Z","caller":"traceutil/trace.go:171","msg":"trace[1548416228] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"223.356215ms","start":"2026-08-22T10:34:38.64119Z","end":"2026-08-22T10:34:38.864546Z","steps":["trace[1548416228] 'process raft request' (duration: 223.225872ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:48.889422Z","caller":"traceutil/trace.go:171","msg":"trace[454431019] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"350.636209ms","start":"2026-08-22T10:34:48.538751Z","end":"2026-08-22T10:34:48.889387Z","steps":["trace[454431019] 'process raft request' (duration: 350.404893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:48.889667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:48.538715Z","time spent":"350.830672ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5035,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:34:51.664955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402370672780462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:34:52.16623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402370672780462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:34:52.243693Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.114238059s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:34:52.270798Z","caller":"traceutil/trace.go:171","msg":"trace[713554597] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.14137896s","start":"2026-08-22T10:34:51.129359Z","end":"2026-08-22T10:34:52.270737Z","steps":["trace[713554597] 'process raft request' (duration: 1.114535596s)","trace[713554597] 'compare' (duration: 23.857173ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:34:52.270992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:51.129336Z","time spent":"1.141549715s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:34:52.27164Z","caller":"traceutil/trace.go:171","msg":"trace[774912151] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.141430001s","start":"2026-08-22T10:34:51.130135Z","end":"2026-08-22T10:34:52.271565Z","steps":["trace[774912151] 'process raft request' (duration: 1.140453268s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:52.272196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:51.130119Z","time spent":"1.141577844s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:34:52.274003Z","caller":"traceutil/trace.go:171","msg":"trace[1946990925] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.143388579s","start":"2026-08-22T10:34:51.130577Z","end":"2026-08-22T10:34:52.273965Z","steps":["trace[1946990925] 'process raft request' (duration: 1.143149103s)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:52.274018Z","caller":"traceutil/trace.go:171","msg":"trace[1506906181] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:823; }","duration":"1.109563057s","start":"2026-08-22T10:34:51.16439Z","end":"2026-08-22T10:34:52.273953Z","steps":["trace[1506906181] 'read index received' (duration: 1.079519557s)","trace[1506906181] 'applied index is now lower than readState.Index' (duration: 30.04175ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:34:52.274168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109768802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:34:52.274117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:51.130567Z","time spent":"1.143488891s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:34:52.274234Z","caller":"traceutil/trace.go:171","msg":"trace[1981189182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"1.109845624s","start":"2026-08-22T10:34:51.164367Z","end":"2026-08-22T10:34:52.274213Z","steps":["trace[1981189182] 'agreement among raft nodes before linearized reading' (duration: 1.10967878s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:52.274272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:51.164357Z","time spent":"1.109906295s","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:34:52.274323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.434848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6tmhb\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-22T10:34:52.27458Z","caller":"traceutil/trace.go:171","msg":"trace[1660295403] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6tmhb; range_end:; response_count:1; response_revision:799; }","duration":"723.702234ms","start":"2026-08-22T10:34:51.55083Z","end":"2026-08-22T10:34:52.274532Z","steps":["trace[1660295403] 'agreement among raft nodes before linearized reading' (duration: 723.350876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:52.274715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:51.550816Z","time spent":"723.830687ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6tmhb\" "} {"level":"info","ts":"2026-08-22T10:34:52.61188Z","caller":"traceutil/trace.go:171","msg":"trace[1180805592] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"329.609815ms","start":"2026-08-22T10:34:52.282246Z","end":"2026-08-22T10:34:52.611856Z","steps":["trace[1180805592] 'process raft request' (duration: 329.485492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:52.612062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:52.282226Z","time spent":"329.735587ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:34:52.614409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:52.282299Z","time spent":"332.107124ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-22T10:34:52.614444Z","caller":"traceutil/trace.go:171","msg":"trace[1791906705] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"315.789363ms","start":"2026-08-22T10:34:52.298614Z","end":"2026-08-22T10:34:52.614403Z","steps":["trace[1791906705] 'read index received' (duration: 313.1666ms)","trace[1791906705] 'applied index is now lower than readState.Index' (duration: 2.621193ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:34:52.614535Z","caller":"traceutil/trace.go:171","msg":"trace[1819049130] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"280.868886ms","start":"2026-08-22T10:34:52.333629Z","end":"2026-08-22T10:34:52.614498Z","steps":["trace[1819049130] 'process raft request' (duration: 280.64627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:52.614737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.785178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T10:34:52.614748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.11263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T10:34:52.614792Z","caller":"traceutil/trace.go:171","msg":"trace[1967413611] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:801; }","duration":"163.8701ms","start":"2026-08-22T10:34:52.450908Z","end":"2026-08-22T10:34:52.614778Z","steps":["trace[1967413611] 'agreement among raft nodes before linearized reading' (duration: 163.766167ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:52.614804Z","caller":"traceutil/trace.go:171","msg":"trace[19352582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"316.224903ms","start":"2026-08-22T10:34:52.298564Z","end":"2026-08-22T10:34:52.614789Z","steps":["trace[19352582] 'agreement among raft nodes before linearized reading' (duration: 316.008798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:52.614853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:52.298551Z","time spent":"316.289284ms","remote":"127.0.0.1:42220","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-08-22T10:36:08.930142Z","caller":"traceutil/trace.go:171","msg":"trace[515797926] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"235.060963ms","start":"2026-08-22T10:36:08.695053Z","end":"2026-08-22T10:36:08.930114Z","steps":["trace[515797926] 'read index received' (duration: 234.94891ms)","trace[515797926] 'applied index is now lower than readState.Index' (duration: 110.983µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:08.930462Z","caller":"traceutil/trace.go:171","msg":"trace[548551889] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"253.430114ms","start":"2026-08-22T10:36:08.677019Z","end":"2026-08-22T10:36:08.930449Z","steps":["trace[548551889] 'process raft request' (duration: 252.945942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:08.930961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.680834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-22T10:36:08.931011Z","caller":"traceutil/trace.go:171","msg":"trace[613477890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1858; }","duration":"234.781036ms","start":"2026-08-22T10:36:08.696216Z","end":"2026-08-22T10:36:08.930997Z","steps":["trace[613477890] 'agreement among raft nodes before linearized reading' (duration: 234.518009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:08.931174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.94633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-22T10:36:08.931051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.819261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T10:36:08.931274Z","caller":"traceutil/trace.go:171","msg":"trace[576282684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1858; }","duration":"235.084524ms","start":"2026-08-22T10:36:08.696163Z","end":"2026-08-22T10:36:08.931248Z","steps":["trace[576282684] 'agreement among raft nodes before linearized reading' (duration: 234.534321ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:08.931563Z","caller":"traceutil/trace.go:171","msg":"trace[25031718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1858; }","duration":"236.301622ms","start":"2026-08-22T10:36:08.695013Z","end":"2026-08-22T10:36:08.931314Z","steps":["trace[25031718] 'agreement among raft nodes before linearized reading' (duration: 235.645857ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:22.228576Z","caller":"traceutil/trace.go:171","msg":"trace[180055037] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"255.35911ms","start":"2026-08-22T10:36:21.973192Z","end":"2026-08-22T10:36:22.228551Z","steps":["trace[180055037] 'process raft request' (duration: 254.899939ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:54.049369Z","caller":"traceutil/trace.go:171","msg":"trace[512820434] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"306.786034ms","start":"2026-08-22T10:36:53.742551Z","end":"2026-08-22T10:36:54.049337Z","steps":["trace[512820434] 'process raft request' (duration: 306.539728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:54.049544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:53.742527Z","time spent":"306.926997ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:54.704693Z","caller":"traceutil/trace.go:171","msg":"trace[380086835] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2243; }","duration":"195.939964ms","start":"2026-08-22T10:36:54.508731Z","end":"2026-08-22T10:36:54.704671Z","steps":["trace[380086835] 'read index received' (duration: 164.152962ms)","trace[380086835] 'applied index is now lower than readState.Index' (duration: 31.786072ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:54.704773Z","caller":"traceutil/trace.go:171","msg":"trace[1963510384] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"365.379296ms","start":"2026-08-22T10:36:54.339361Z","end":"2026-08-22T10:36:54.70474Z","steps":["trace[1963510384] 'process raft request' (duration: 333.525142ms)","trace[1963510384] 'compare' (duration: 31.643939ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:54.704928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:54.339333Z","time spent":"365.511868ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:54.704976Z","caller":"traceutil/trace.go:171","msg":"trace[545216884] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"172.049125ms","start":"2026-08-22T10:36:54.532885Z","end":"2026-08-22T10:36:54.704934Z","steps":["trace[545216884] 'process raft request' (duration: 171.749648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:54.705105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.359504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-22T10:36:54.705147Z","caller":"traceutil/trace.go:171","msg":"trace[1129557934] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2163; }","duration":"196.448036ms","start":"2026-08-22T10:36:54.508687Z","end":"2026-08-22T10:36:54.705135Z","steps":["trace[1129557934] 'agreement among raft nodes before linearized reading' (duration: 196.061277ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:59.659911Z","caller":"traceutil/trace.go:171","msg":"trace[1088173635] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"169.636126ms","start":"2026-08-22T10:36:59.490235Z","end":"2026-08-22T10:36:59.659871Z","steps":["trace[1088173635] 'read index received' (duration: 169.437862ms)","trace[1088173635] 'applied index is now lower than readState.Index' (duration: 197.074µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:59.660042Z","caller":"traceutil/trace.go:171","msg":"trace[1879922223] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"170.768963ms","start":"2026-08-22T10:36:59.489214Z","end":"2026-08-22T10:36:59.659983Z","steps":["trace[1879922223] 'process raft request' (duration: 170.431505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:59.660255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.988964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T10:36:59.660303Z","caller":"traceutil/trace.go:171","msg":"trace[1051837010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2192; }","duration":"170.126197ms","start":"2026-08-22T10:36:59.490167Z","end":"2026-08-22T10:36:59.660293Z","steps":["trace[1051837010] 'agreement among raft nodes before linearized reading' (duration: 169.871791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:59.670886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.607547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:36:59.670942Z","caller":"traceutil/trace.go:171","msg":"trace[832764968] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2193; }","duration":"106.687099ms","start":"2026-08-22T10:36:59.564242Z","end":"2026-08-22T10:36:59.670929Z","steps":["trace[832764968] 'agreement among raft nodes before linearized reading' (duration: 106.564196ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:59.818541Z","caller":"traceutil/trace.go:171","msg":"trace[1598059148] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2277; }","duration":"111.381943ms","start":"2026-08-22T10:36:59.70714Z","end":"2026-08-22T10:36:59.818522Z","steps":["trace[1598059148] 'read index received' (duration: 62.973379ms)","trace[1598059148] 'applied index is now lower than readState.Index' (duration: 48.407534ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:59.818591Z","caller":"traceutil/trace.go:171","msg":"trace[2110116502] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"153.533095ms","start":"2026-08-22T10:36:59.665021Z","end":"2026-08-22T10:36:59.818554Z","steps":["trace[2110116502] 'process raft request' (duration: 105.081199ms)","trace[2110116502] 'compare' (duration: 48.311482ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:59.818686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.534046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:36:59.818726Z","caller":"traceutil/trace.go:171","msg":"trace[327695577] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2194; }","duration":"111.611188ms","start":"2026-08-22T10:36:59.707103Z","end":"2026-08-22T10:36:59.818715Z","steps":["trace[327695577] 'agreement among raft nodes before linearized reading' (duration: 111.536556ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:48.420018Z","caller":"traceutil/trace.go:171","msg":"trace[1859442078] linearizableReadLoop","detail":"{readStateIndex:2729; appliedIndex:2728; }","duration":"112.875818ms","start":"2026-08-22T10:37:48.307112Z","end":"2026-08-22T10:37:48.419988Z","steps":["trace[1859442078] 'read index received' (duration: 112.705774ms)","trace[1859442078] 'applied index is now lower than readState.Index' (duration: 168.954µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:37:48.420096Z","caller":"traceutil/trace.go:171","msg":"trace[910892552] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"124.273465ms","start":"2026-08-22T10:37:48.295766Z","end":"2026-08-22T10:37:48.420039Z","steps":["trace[910892552] 'process raft request' (duration: 124.05191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:48.420303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.155794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-22T10:37:48.420385Z","caller":"traceutil/trace.go:171","msg":"trace[1051974014] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2628; }","duration":"113.30131ms","start":"2026-08-22T10:37:48.307062Z","end":"2026-08-22T10:37:48.420363Z","steps":["trace[1051974014] 'agreement among raft nodes before linearized reading' (duration: 113.037423ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:48.466505Z","caller":"traceutil/trace.go:171","msg":"trace[1803205254] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"137.582998ms","start":"2026-08-22T10:37:48.328894Z","end":"2026-08-22T10:37:48.466477Z","steps":["trace[1803205254] 'process raft request' (duration: 137.189059ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:21.885407Z","caller":"traceutil/trace.go:171","msg":"trace[1310929719] linearizableReadLoop","detail":"{readStateIndex:2937; appliedIndex:2936; }","duration":"149.235837ms","start":"2026-08-22T10:38:21.736133Z","end":"2026-08-22T10:38:21.885369Z","steps":["trace[1310929719] 'read index received' (duration: 148.94129ms)","trace[1310929719] 'applied index is now lower than readState.Index' (duration: 292.847µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:38:21.885474Z","caller":"traceutil/trace.go:171","msg":"trace[855050465] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"151.04053ms","start":"2026-08-22T10:38:21.734385Z","end":"2026-08-22T10:38:21.885426Z","steps":["trace[855050465] 'process raft request' (duration: 150.770203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:21.885726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.553476ms","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-08-22T10:38:21.885777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.446473ms","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-08-22T10:38:21.885826Z","caller":"traceutil/trace.go:171","msg":"trace[350790539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2829; }","duration":"149.645098ms","start":"2026-08-22T10:38:21.736167Z","end":"2026-08-22T10:38:21.885813Z","steps":["trace[350790539] 'agreement among raft nodes before linearized reading' (duration: 149.418243ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:21.885833Z","caller":"traceutil/trace.go:171","msg":"trace[197991807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2829; }","duration":"149.751761ms","start":"2026-08-22T10:38:21.736058Z","end":"2026-08-22T10:38:21.885809Z","steps":["trace[197991807] 'agreement among raft nodes before linearized reading' (duration: 149.448513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:22.330188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.803283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-22T10:38:22.330216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.570833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:38:22.330287Z","caller":"traceutil/trace.go:171","msg":"trace[611716851] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2831; }","duration":"219.969296ms","start":"2026-08-22T10:38:22.110295Z","end":"2026-08-22T10:38:22.330265Z","steps":["trace[611716851] 'range keys from in-memory index tree' (duration: 219.531017ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:22.3303Z","caller":"traceutil/trace.go:171","msg":"trace[120577199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2831; }","duration":"163.715507ms","start":"2026-08-22T10:38:22.166559Z","end":"2026-08-22T10:38:22.330274Z","steps":["trace[120577199] 'range keys from in-memory index tree' (duration: 163.472121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:22.330396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.846579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-22T10:38:22.33051Z","caller":"traceutil/trace.go:171","msg":"trace[1844309985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2831; }","duration":"116.023334ms","start":"2026-08-22T10:38:22.214463Z","end":"2026-08-22T10:38:22.330486Z","steps":["trace[1844309985] 'range keys from in-memory index tree' (duration: 115.640364ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:22.604173Z","caller":"traceutil/trace.go:171","msg":"trace[5387208] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"268.358197ms","start":"2026-08-22T10:38:22.335779Z","end":"2026-08-22T10:38:22.604137Z","steps":["trace[5387208] 'process raft request' (duration: 268.208583ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:22.604171Z","caller":"traceutil/trace.go:171","msg":"trace[217063228] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"268.664294ms","start":"2026-08-22T10:38:22.335446Z","end":"2026-08-22T10:38:22.60411Z","steps":["trace[217063228] 'process raft request' (duration: 224.118587ms)","trace[217063228] 'compare' (duration: 44.150208ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:38:22.604382Z","caller":"traceutil/trace.go:171","msg":"trace[849651229] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"210.793834ms","start":"2026-08-22T10:38:22.393542Z","end":"2026-08-22T10:38:22.604336Z","steps":["trace[849651229] 'process raft request' (duration: 210.508748ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:22.604417Z","caller":"traceutil/trace.go:171","msg":"trace[2029035228] linearizableReadLoop","detail":"{readStateIndex:2943; appliedIndex:2940; }","duration":"208.385646ms","start":"2026-08-22T10:38:22.395909Z","end":"2026-08-22T10:38:22.604294Z","steps":["trace[2029035228] 'read index received' (duration: 163.637545ms)","trace[2029035228] 'applied index is now lower than readState.Index' (duration: 44.745541ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:38:22.604692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.760265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-08-22T10:38:22.604748Z","caller":"traceutil/trace.go:171","msg":"trace[170038383] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2834; }","duration":"208.865428ms","start":"2026-08-22T10:38:22.39587Z","end":"2026-08-22T10:38:22.604735Z","steps":["trace[170038383] 'agreement among raft nodes before linearized reading' (duration: 208.655643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:22.604769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.542776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:38:22.604895Z","caller":"traceutil/trace.go:171","msg":"trace[1113066520] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2834; }","duration":"171.70125ms","start":"2026-08-22T10:38:22.433168Z","end":"2026-08-22T10:38:22.604869Z","steps":["trace[1113066520] 'agreement among raft nodes before linearized reading' (duration: 171.461704ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:11.751882Z","caller":"traceutil/trace.go:171","msg":"trace[1048781739] transaction","detail":"{read_only:false; response_revision:3551; number_of_response:1; }","duration":"114.057584ms","start":"2026-08-22T10:40:11.637791Z","end":"2026-08-22T10:40:11.751848Z","steps":["trace[1048781739] 'process raft request' (duration: 113.956572ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:11.751896Z","caller":"traceutil/trace.go:171","msg":"trace[1580107092] transaction","detail":"{read_only:false; response_revision:3550; number_of_response:1; }","duration":"120.50218ms","start":"2026-08-22T10:40:11.631356Z","end":"2026-08-22T10:40:11.751858Z","steps":["trace[1580107092] 'process raft request' (duration: 78.813166ms)","trace[1580107092] 'compare' (duration: 41.372756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:10.348165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.040832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:10.348271Z","caller":"traceutil/trace.go:171","msg":"trace[1342961045] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4955; }","duration":"110.202346ms","start":"2026-08-22T10:42:10.238044Z","end":"2026-08-22T10:42:10.348247Z","steps":["trace[1342961045] 'count revisions from in-memory index tree' (duration: 109.94263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.348476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.528732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:10.348553Z","caller":"traceutil/trace.go:171","msg":"trace[286939026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4955; }","duration":"182.687776ms","start":"2026-08-22T10:42:10.16585Z","end":"2026-08-22T10:42:10.348538Z","steps":["trace[286939026] 'range keys from in-memory index tree' (duration: 182.340708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.348701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.862308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T10:42:10.348879Z","caller":"traceutil/trace.go:171","msg":"trace[1253865206] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4955; }","duration":"317.131744ms","start":"2026-08-22T10:42:10.031709Z","end":"2026-08-22T10:42:10.348841Z","steps":["trace[1253865206] 'range keys from in-memory index tree' (duration: 316.577261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.34896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:10.031683Z","time spent":"317.259596ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T10:42:12.080567Z","caller":"traceutil/trace.go:171","msg":"trace[514511069] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"112.117981ms","start":"2026-08-22T10:42:11.968426Z","end":"2026-08-22T10:42:12.080544Z","steps":["trace[514511069] 'process raft request' (duration: 111.977928ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:12.087353Z","caller":"traceutil/trace.go:171","msg":"trace[1571826600] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"114.694284ms","start":"2026-08-22T10:42:11.972638Z","end":"2026-08-22T10:42:12.087332Z","steps":["trace[1571826600] 'process raft request' (duration: 114.645153ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:12.087378Z","caller":"traceutil/trace.go:171","msg":"trace[242745093] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"118.356242ms","start":"2026-08-22T10:42:11.969001Z","end":"2026-08-22T10:42:12.087358Z","steps":["trace[242745093] 'process raft request' (duration: 118.176867ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:13.375448Z","caller":"traceutil/trace.go:171","msg":"trace[1492928576] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"244.817218ms","start":"2026-08-22T10:42:13.130616Z","end":"2026-08-22T10:42:13.375433Z","steps":["trace[1492928576] 'process raft request' (duration: 244.700805ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:13.376997Z","caller":"traceutil/trace.go:171","msg":"trace[927979723] linearizableReadLoop","detail":"{readStateIndex:5206; appliedIndex:5204; }","duration":"210.903392ms","start":"2026-08-22T10:42:13.16607Z","end":"2026-08-22T10:42:13.376973Z","steps":["trace[927979723] 'read index received' (duration: 209.239402ms)","trace[927979723] 'applied index is now lower than readState.Index' (duration: 1.6626ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:13.377284Z","caller":"traceutil/trace.go:171","msg":"trace[1283033752] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"246.016206ms","start":"2026-08-22T10:42:13.131255Z","end":"2026-08-22T10:42:13.377271Z","steps":["trace[1283033752] 'process raft request' (duration: 245.594626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:13.377455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.381715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:13.377488Z","caller":"traceutil/trace.go:171","msg":"trace[418969446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5016; }","duration":"211.471467ms","start":"2026-08-22T10:42:13.166007Z","end":"2026-08-22T10:42:13.377479Z","steps":["trace[418969446] 'agreement among raft nodes before linearized reading' (duration: 211.408605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:13.377791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.691971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:13.377854Z","caller":"traceutil/trace.go:171","msg":"trace[238488304] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:0; response_revision:5016; }","duration":"142.770683ms","start":"2026-08-22T10:42:13.23507Z","end":"2026-08-22T10:42:13.37784Z","steps":["trace[238488304] 'agreement among raft nodes before linearized reading' (duration: 142.539958ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:19.590448Z","caller":"traceutil/trace.go:171","msg":"trace[800725717] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"185.844569ms","start":"2026-08-22T10:42:19.404579Z","end":"2026-08-22T10:42:19.590424Z","steps":["trace[800725717] 'process raft request' (duration: 185.703216ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:19.59204Z","caller":"traceutil/trace.go:171","msg":"trace[1096076615] linearizableReadLoop","detail":"{readStateIndex:5351; appliedIndex:5349; }","duration":"182.414387ms","start":"2026-08-22T10:42:19.409596Z","end":"2026-08-22T10:42:19.592011Z","steps":["trace[1096076615] 'read index received' (duration: 180.745956ms)","trace[1096076615] 'applied index is now lower than readState.Index' (duration: 1.667111ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:19.592126Z","caller":"traceutil/trace.go:171","msg":"trace[957417987] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"187.437408ms","start":"2026-08-22T10:42:19.404604Z","end":"2026-08-22T10:42:19.592042Z","steps":["trace[957417987] 'process raft request' (duration: 187.245164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:19.592287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.683694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-smv4p\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:42:19.59234Z","caller":"traceutil/trace.go:171","msg":"trace[1119412630] range","detail":"{range_begin:/registry/pods/openstack/keepalived-smv4p; range_end:; response_count:1; response_revision:5156; }","duration":"182.810556ms","start":"2026-08-22T10:42:19.409518Z","end":"2026-08-22T10:42:19.592328Z","steps":["trace[1119412630] 'agreement among raft nodes before linearized reading' (duration: 182.611421ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:23.03946Z","caller":"traceutil/trace.go:171","msg":"trace[1862000223] linearizableReadLoop","detail":"{readStateIndex:5388; appliedIndex:5387; }","duration":"284.483732ms","start":"2026-08-22T10:42:22.754943Z","end":"2026-08-22T10:42:23.039427Z","steps":["trace[1862000223] 'read index received' (duration: 251.050468ms)","trace[1862000223] 'applied index is now lower than readState.Index' (duration: 33.431144ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:23.039503Z","caller":"traceutil/trace.go:171","msg":"trace[1591759869] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"346.283148ms","start":"2026-08-22T10:42:22.693178Z","end":"2026-08-22T10:42:23.039461Z","steps":["trace[1591759869] 'process raft request' (duration: 312.998398ms)","trace[1591759869] 'compare' (duration: 32.850959ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:23.039658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.686677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:23.039759Z","caller":"traceutil/trace.go:171","msg":"trace[1199746885] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5191; }","duration":"284.858731ms","start":"2026-08-22T10:42:22.754878Z","end":"2026-08-22T10:42:23.039736Z","steps":["trace[1199746885] 'agreement among raft nodes before linearized reading' (duration: 284.640976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:23.039837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:22.693142Z","time spent":"346.550025ms","remote":"127.0.0.1:42002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":126003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:42:23.040513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.472936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:23.040598Z","caller":"traceutil/trace.go:171","msg":"trace[1996457614] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:5191; }","duration":"184.62406ms","start":"2026-08-22T10:42:22.855952Z","end":"2026-08-22T10:42:23.040576Z","steps":["trace[1996457614] 'agreement among raft nodes before linearized reading' (duration: 184.471266ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.036306Z","caller":"traceutil/trace.go:171","msg":"trace[765870539] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"115.157599ms","start":"2026-08-22T10:42:23.921125Z","end":"2026-08-22T10:42:24.036282Z","steps":["trace[765870539] 'process raft request' (duration: 114.945024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:24.332319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.540212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:24.33256Z","caller":"traceutil/trace.go:171","msg":"trace[1917563941] linearizableReadLoop","detail":"{readStateIndex:5419; appliedIndex:5416; }","duration":"318.443239ms","start":"2026-08-22T10:42:24.014098Z","end":"2026-08-22T10:42:24.332541Z","steps":["trace[1917563941] 'read index received' (duration: 21.954208ms)","trace[1917563941] 'applied index is now lower than readState.Index' (duration: 296.487791ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:24.332624Z","caller":"traceutil/trace.go:171","msg":"trace[1159777397] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"329.524984ms","start":"2026-08-22T10:42:24.003072Z","end":"2026-08-22T10:42:24.332597Z","steps":["trace[1159777397] 'process raft request' (duration: 329.366941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:24.332735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.301121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:24.332729Z","caller":"traceutil/trace.go:171","msg":"trace[1250680268] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"333.006148ms","start":"2026-08-22T10:42:23.999675Z","end":"2026-08-22T10:42:24.332681Z","steps":["trace[1250680268] 'process raft request' (duration: 146.991225ms)","trace[1250680268] 'compare' (duration: 185.323787ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:24.332784Z","caller":"traceutil/trace.go:171","msg":"trace[1503913506] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5222; }","duration":"295.393474ms","start":"2026-08-22T10:42:24.037379Z","end":"2026-08-22T10:42:24.332772Z","steps":["trace[1503913506] 'agreement among raft nodes before linearized reading' (duration: 295.307671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:24.332773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:24.003059Z","time spent":"329.626306ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:42:24.332787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.316643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:42:24.332803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.602032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:42:24.332837Z","caller":"traceutil/trace.go:171","msg":"trace[1878557167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5222; }","duration":"167.411107ms","start":"2026-08-22T10:42:24.165408Z","end":"2026-08-22T10:42:24.332819Z","steps":["trace[1878557167] 'agreement among raft nodes before linearized reading' (duration: 167.315984ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.332848Z","caller":"traceutil/trace.go:171","msg":"trace[3338719] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:5222; }","duration":"318.802877ms","start":"2026-08-22T10:42:24.01403Z","end":"2026-08-22T10:42:24.332833Z","steps":["trace[3338719] 'agreement among raft nodes before linearized reading' (duration: 318.612172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:24.33287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:23.999645Z","time spent":"333.146002ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:42:24.332898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:24.014007Z","time spent":"318.880379ms","remote":"127.0.0.1:49304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":10,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:42:24.332898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.176206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-wxp6z\" ","response":"range_response_count:1 size:7818"} {"level":"warn","ts":"2026-08-22T10:42:24.332911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.614099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-22T10:42:24.332901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.905256ms","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-08-22T10:42:24.332949Z","caller":"traceutil/trace.go:171","msg":"trace[1984857320] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-wxp6z; range_end:; response_count:1; response_revision:5222; }","duration":"292.271918ms","start":"2026-08-22T10:42:24.040662Z","end":"2026-08-22T10:42:24.332934Z","steps":["trace[1984857320] 'agreement among raft nodes before linearized reading' (duration: 292.090304ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.332989Z","caller":"traceutil/trace.go:171","msg":"trace[924861395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5222; }","duration":"147.720252ms","start":"2026-08-22T10:42:24.185247Z","end":"2026-08-22T10:42:24.332968Z","steps":["trace[924861395] 'agreement among raft nodes before linearized reading' (duration: 147.477606ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.332998Z","caller":"traceutil/trace.go:171","msg":"trace[1282376136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5222; }","duration":"153.046139ms","start":"2026-08-22T10:42:24.179934Z","end":"2026-08-22T10:42:24.33298Z","steps":["trace[1282376136] 'agreement among raft nodes before linearized reading' (duration: 152.787703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:24.575159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.935391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:24.575403Z","caller":"traceutil/trace.go:171","msg":"trace[543750466] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"236.886187ms","start":"2026-08-22T10:42:24.338483Z","end":"2026-08-22T10:42:24.575369Z","steps":["trace[543750466] 'process raft request' (duration: 122.640089ms)","trace[543750466] 'compare' (duration: 113.719686ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:24.575489Z","caller":"traceutil/trace.go:171","msg":"trace[1949604086] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"233.276899ms","start":"2026-08-22T10:42:24.342181Z","end":"2026-08-22T10:42:24.575458Z","steps":["trace[1949604086] 'process raft request' (duration: 233.100245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:24.575537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.474237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126017"} {"level":"info","ts":"2026-08-22T10:42:24.575568Z","caller":"traceutil/trace.go:171","msg":"trace[413315496] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5225; }","duration":"236.534889ms","start":"2026-08-22T10:42:24.339021Z","end":"2026-08-22T10:42:24.575556Z","steps":["trace[413315496] 'agreement among raft nodes before linearized reading' (duration: 236.353004ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.575605Z","caller":"traceutil/trace.go:171","msg":"trace[814970266] linearizableReadLoop","detail":"{readStateIndex:5421; appliedIndex:5419; }","duration":"236.300863ms","start":"2026-08-22T10:42:24.339069Z","end":"2026-08-22T10:42:24.57537Z","steps":["trace[814970266] 'read index received' (duration: 43.737522ms)","trace[814970266] 'applied index is now lower than readState.Index' (duration: 192.562401ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:24.57593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.040378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T10:42:24.575962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.572906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-22T10:42:24.575984Z","caller":"traceutil/trace.go:171","msg":"trace[1373820921] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5225; }","duration":"224.14392ms","start":"2026-08-22T10:42:24.351823Z","end":"2026-08-22T10:42:24.575967Z","steps":["trace[1373820921] 'agreement among raft nodes before linearized reading' (duration: 223.971006ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.576016Z","caller":"traceutil/trace.go:171","msg":"trace[1996141262] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5225; }","duration":"134.662208ms","start":"2026-08-22T10:42:24.441339Z","end":"2026-08-22T10:42:24.576001Z","steps":["trace[1996141262] 'agreement among raft nodes before linearized reading' (duration: 134.492224ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.776124Z","caller":"traceutil/trace.go:171","msg":"trace[304921816] linearizableReadLoop","detail":"{readStateIndex:5423; appliedIndex:5422; }","duration":"196.029344ms","start":"2026-08-22T10:42:24.580042Z","end":"2026-08-22T10:42:24.776071Z","steps":["trace[304921816] 'read index received' (duration: 138.350727ms)","trace[304921816] 'applied index is now lower than readState.Index' (duration: 57.677597ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:24.776162Z","caller":"traceutil/trace.go:171","msg":"trace[584709544] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"197.621313ms","start":"2026-08-22T10:42:24.578496Z","end":"2026-08-22T10:42:24.776118Z","steps":["trace[584709544] 'process raft request' (duration: 139.426424ms)","trace[584709544] 'compare' (duration: 57.993144ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:24.776399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.337893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-7dqt4\" ","response":"range_response_count:1 size:9769"} {"level":"info","ts":"2026-08-22T10:42:24.776448Z","caller":"traceutil/trace.go:171","msg":"trace[1949491876] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-7dqt4; range_end:; response_count:1; response_revision:5226; }","duration":"196.410214ms","start":"2026-08-22T10:42:24.580025Z","end":"2026-08-22T10:42:24.776435Z","steps":["trace[1949491876] 'agreement among raft nodes before linearized reading' (duration: 196.190189ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.832323Z","caller":"traceutil/trace.go:171","msg":"trace[1787394345] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"251.677463ms","start":"2026-08-22T10:42:24.580621Z","end":"2026-08-22T10:42:24.832299Z","steps":["trace[1787394345] 'process raft request' (duration: 250.047043ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.832743Z","caller":"traceutil/trace.go:171","msg":"trace[311223855] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"173.81668ms","start":"2026-08-22T10:42:24.658876Z","end":"2026-08-22T10:42:24.832692Z","steps":["trace[311223855] 'process raft request' (duration: 173.745779ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.832827Z","caller":"traceutil/trace.go:171","msg":"trace[944187251] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"251.192331ms","start":"2026-08-22T10:42:24.581625Z","end":"2026-08-22T10:42:24.832818Z","steps":["trace[944187251] 'process raft request' (duration: 250.600787ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:24.832823Z","caller":"traceutil/trace.go:171","msg":"trace[1062468920] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"251.165141ms","start":"2026-08-22T10:42:24.581629Z","end":"2026-08-22T10:42:24.832794Z","steps":["trace[1062468920] 'process raft request' (duration: 250.881444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:25.026339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.752007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:25.026753Z","caller":"traceutil/trace.go:171","msg":"trace[2008931614] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"170.290566ms","start":"2026-08-22T10:42:24.856425Z","end":"2026-08-22T10:42:25.026715Z","steps":["trace[2008931614] 'process raft request' (duration: 36.061918ms)","trace[2008931614] 'compare' (duration: 133.422779ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:25.026924Z","caller":"traceutil/trace.go:171","msg":"trace[506175405] linearizableReadLoop","detail":"{readStateIndex:5431; appliedIndex:5429; }","duration":"169.955908ms","start":"2026-08-22T10:42:24.856948Z","end":"2026-08-22T10:42:25.026904Z","steps":["trace[506175405] 'read index received' (duration: 35.556005ms)","trace[506175405] 'applied index is now lower than readState.Index' (duration: 134.398493ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:25.027043Z","caller":"traceutil/trace.go:171","msg":"trace[543584487] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"169.108117ms","start":"2026-08-22T10:42:24.857924Z","end":"2026-08-22T10:42:25.027032Z","steps":["trace[543584487] 'process raft request' (duration: 168.884731ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:25.027053Z","caller":"traceutil/trace.go:171","msg":"trace[833679685] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"169.466015ms","start":"2026-08-22T10:42:24.85755Z","end":"2026-08-22T10:42:25.027016Z","steps":["trace[833679685] 'process raft request' (duration: 169.181269ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:25.027217Z","caller":"traceutil/trace.go:171","msg":"trace[419873128] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"170.404749ms","start":"2026-08-22T10:42:24.856799Z","end":"2026-08-22T10:42:25.027204Z","steps":["trace[419873128] 'process raft request' (duration: 169.826675ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:25.027582Z","caller":"traceutil/trace.go:171","msg":"trace[206218104] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"165.802877ms","start":"2026-08-22T10:42:24.861747Z","end":"2026-08-22T10:42:25.02755Z","steps":["trace[206218104] 'process raft request' (duration: 165.108851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:25.027763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.804029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-7dqt4\" ","response":"range_response_count:1 size:9579"} {"level":"info","ts":"2026-08-22T10:42:25.02785Z","caller":"traceutil/trace.go:171","msg":"trace[1801451888] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-7dqt4; range_end:; response_count:1; response_revision:5237; }","duration":"170.937331ms","start":"2026-08-22T10:42:24.856897Z","end":"2026-08-22T10:42:25.027834Z","steps":["trace[1801451888] 'agreement among raft nodes before linearized reading' (duration: 170.701075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:25.930877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.909752ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:25.93101Z","caller":"traceutil/trace.go:171","msg":"trace[798475959] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5237; }","duration":"779.055276ms","start":"2026-08-22T10:42:25.151936Z","end":"2026-08-22T10:42:25.930991Z","steps":["trace[798475959] 'range keys from in-memory index tree' (duration: 778.894282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:25.93144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.637642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:25.931811Z","caller":"traceutil/trace.go:171","msg":"trace[640508616] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"821.490966ms","start":"2026-08-22T10:42:25.110277Z","end":"2026-08-22T10:42:25.931767Z","steps":["trace[640508616] 'process raft request' (duration: 70.437604ms)","trace[640508616] 'compare' (duration: 750.510019ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:25.932012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.422108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T10:42:25.932094Z","caller":"traceutil/trace.go:171","msg":"trace[123693098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5238; }","duration":"802.51522ms","start":"2026-08-22T10:42:25.129542Z","end":"2026-08-22T10:42:25.932058Z","steps":["trace[123693098] 'agreement among raft nodes before linearized reading' (duration: 802.324576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:25.932145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:25.129526Z","time spent":"802.599552ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T10:42:25.932029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:25.110263Z","time spent":"821.65748ms","remote":"127.0.0.1:42132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18663,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:42:25.931823Z","caller":"traceutil/trace.go:171","msg":"trace[154173713] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5434; }","duration":"801.944577ms","start":"2026-08-22T10:42:25.129584Z","end":"2026-08-22T10:42:25.931529Z","steps":["trace[154173713] 'read index received' (duration: 51.110759ms)","trace[154173713] 'applied index is now lower than readState.Index' (duration: 750.832748ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:25.932739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.795694ms","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-08-22T10:42:25.932782Z","caller":"traceutil/trace.go:171","msg":"trace[1193828051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5238; }","duration":"315.871936ms","start":"2026-08-22T10:42:25.6169Z","end":"2026-08-22T10:42:25.932772Z","steps":["trace[1193828051] 'agreement among raft nodes before linearized reading' (duration: 315.761894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:25.932812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:25.616882Z","time spent":"315.922847ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T10:42:25.93287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.697208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-smv4p\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:42:25.932947Z","caller":"traceutil/trace.go:171","msg":"trace[715076860] range","detail":"{range_begin:/registry/pods/openstack/keepalived-smv4p; range_end:; response_count:1; response_revision:5238; }","duration":"309.819971ms","start":"2026-08-22T10:42:25.623111Z","end":"2026-08-22T10:42:25.932931Z","steps":["trace[715076860] 'agreement among raft nodes before linearized reading' (duration: 309.572785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:25.932995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:25.623095Z","time spent":"309.892272ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-smv4p\" "} {"level":"warn","ts":"2026-08-22T10:42:25.933256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.193924ms","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-08-22T10:42:25.933348Z","caller":"traceutil/trace.go:171","msg":"trace[48040523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5238; }","duration":"316.337857ms","start":"2026-08-22T10:42:25.616993Z","end":"2026-08-22T10:42:25.933331Z","steps":["trace[48040523] 'agreement among raft nodes before linearized reading' (duration: 315.783973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:25.933388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:25.616971Z","time spent":"316.40921ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:42:26.666573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402370672790696,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T10:42:26.969809Z","caller":"traceutil/trace.go:171","msg":"trace[163952640] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"1.03112556s","start":"2026-08-22T10:42:25.938642Z","end":"2026-08-22T10:42:26.969768Z","steps":["trace[163952640] 'process raft request' (duration: 1.030862972s)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:26.969837Z","caller":"traceutil/trace.go:171","msg":"trace[1200826967] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"631.482118ms","start":"2026-08-22T10:42:26.338308Z","end":"2026-08-22T10:42:26.96979Z","steps":["trace[1200826967] 'process raft request' (duration: 631.395766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:26.970016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:25.938596Z","time spent":"1.031305184s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:42:26.970037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:26.338281Z","time spent":"631.640881ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:42:26.970111Z","caller":"traceutil/trace.go:171","msg":"trace[118456515] linearizableReadLoop","detail":"{readStateIndex:5441; appliedIndex:5436; }","duration":"803.893703ms","start":"2026-08-22T10:42:26.166193Z","end":"2026-08-22T10:42:26.970087Z","steps":["trace[118456515] 'read index received' (duration: 780.754046ms)","trace[118456515] 'applied index is now lower than readState.Index' (duration: 23.138337ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:26.97014Z","caller":"traceutil/trace.go:171","msg":"trace[549485203] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"630.536235ms","start":"2026-08-22T10:42:26.339573Z","end":"2026-08-22T10:42:26.97011Z","steps":["trace[549485203] 'process raft request' (duration: 630.169466ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:26.970095Z","caller":"traceutil/trace.go:171","msg":"trace[1531543718] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"700.355614ms","start":"2026-08-22T10:42:26.269667Z","end":"2026-08-22T10:42:26.970022Z","steps":["trace[1531543718] 'process raft request' (duration: 699.961594ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:26.970202Z","caller":"traceutil/trace.go:171","msg":"trace[1784632672] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"131.023342ms","start":"2026-08-22T10:42:26.839137Z","end":"2026-08-22T10:42:26.970161Z","steps":["trace[1784632672] 'process raft request' (duration: 130.854558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:26.970313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:26.339541Z","time spent":"630.672907ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:42:26.970401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.711726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:26.970453Z","caller":"traceutil/trace.go:171","msg":"trace[1084029572] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5245; }","duration":"202.783557ms","start":"2026-08-22T10:42:26.767654Z","end":"2026-08-22T10:42:26.970438Z","steps":["trace[1084029572] 'agreement among raft nodes before linearized reading' (duration: 202.626274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:26.970422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.198581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:42:26.970439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:26.269644Z","time spent":"700.6535ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:42:26.970519Z","caller":"traceutil/trace.go:171","msg":"trace[2109788784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5245; }","duration":"804.370335ms","start":"2026-08-22T10:42:26.166125Z","end":"2026-08-22T10:42:26.970495Z","steps":["trace[2109788784] 'agreement among raft nodes before linearized reading' (duration: 804.054187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:26.970524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.201904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-08-22T10:42:26.970589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:26.166101Z","time spent":"804.479308ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:42:26.970594Z","caller":"traceutil/trace.go:171","msg":"trace[736024380] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5245; }","duration":"133.315557ms","start":"2026-08-22T10:42:26.83726Z","end":"2026-08-22T10:42:26.970575Z","steps":["trace[736024380] 'agreement among raft nodes before linearized reading' (duration: 133.01141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:26.970677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.343437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T10:42:26.970755Z","caller":"traceutil/trace.go:171","msg":"trace[1623678493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5245; }","duration":"390.552254ms","start":"2026-08-22T10:42:26.580188Z","end":"2026-08-22T10:42:26.97074Z","steps":["trace[1623678493] 'agreement among raft nodes before linearized reading' (duration: 390.090693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:26.97083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:26.580165Z","time spent":"390.653245ms","remote":"127.0.0.1:42220","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-08-22T10:42:26.970922Z","caller":"traceutil/trace.go:171","msg":"trace[1539797411] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"1.03118114s","start":"2026-08-22T10:42:25.938565Z","end":"2026-08-22T10:42:26.969746Z","steps":["trace[1539797411] 'process raft request' (duration: 1.008363101s)","trace[1539797411] 'compare' (duration: 21.479517ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:26.970946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.578739ms","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-08-22T10:42:26.971008Z","caller":"traceutil/trace.go:171","msg":"trace[1298221823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5245; }","duration":"584.693842ms","start":"2026-08-22T10:42:26.386296Z","end":"2026-08-22T10:42:26.97099Z","steps":["trace[1298221823] 'agreement among raft nodes before linearized reading' (duration: 583.977546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:26.971028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:25.93855Z","time spent":"1.03243293s","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:42:26.971067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:26.38627Z","time spent":"584.785444ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:42:34.643059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.386741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8522322-e634-40e1-a7db-d3ccc2a16ecc\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-22T10:42:34.64316Z","caller":"traceutil/trace.go:171","msg":"trace[504305227] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f8522322-e634-40e1-a7db-d3ccc2a16ecc; range_end:; response_count:1; response_revision:5430; }","duration":"262.540304ms","start":"2026-08-22T10:42:34.380596Z","end":"2026-08-22T10:42:34.643136Z","steps":["trace[504305227] 'range keys from in-memory index tree' (duration: 262.160645ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:34.643319Z","caller":"traceutil/trace.go:171","msg":"trace[1249964224] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"165.627613ms","start":"2026-08-22T10:42:34.477663Z","end":"2026-08-22T10:42:34.64329Z","steps":["trace[1249964224] 'process raft request' (duration: 106.703556ms)","trace[1249964224] 'compare' (duration: 58.677531ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:34.912789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.030842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:34.912913Z","caller":"traceutil/trace.go:171","msg":"trace[1749692073] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"246.667562ms","start":"2026-08-22T10:42:34.666222Z","end":"2026-08-22T10:42:34.91289Z","steps":["trace[1749692073] 'process raft request' (duration: 95.446866ms)","trace[1749692073] 'compare' (duration: 150.881698ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:35.178552Z","caller":"traceutil/trace.go:171","msg":"trace[1093815167] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"178.575625ms","start":"2026-08-22T10:42:34.999951Z","end":"2026-08-22T10:42:35.178526Z","steps":["trace[1093815167] 'process raft request' (duration: 178.430402ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.243251Z","caller":"traceutil/trace.go:171","msg":"trace[1521490774] linearizableReadLoop","detail":"{readStateIndex:5638; appliedIndex:5635; }","duration":"236.104658ms","start":"2026-08-22T10:42:35.007113Z","end":"2026-08-22T10:42:35.243217Z","steps":["trace[1521490774] 'read index received' (duration: 171.378421ms)","trace[1521490774] 'applied index is now lower than readState.Index' (duration: 64.724867ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:35.24365Z","caller":"traceutil/trace.go:171","msg":"trace[471014744] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"242.250045ms","start":"2026-08-22T10:42:35.001353Z","end":"2026-08-22T10:42:35.243604Z","steps":["trace[471014744] 'process raft request' (duration: 241.816735ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.24365Z","caller":"traceutil/trace.go:171","msg":"trace[71725956] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"243.451005ms","start":"2026-08-22T10:42:35.00015Z","end":"2026-08-22T10:42:35.243601Z","steps":["trace[71725956] 'process raft request' (duration: 242.767919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:35.243803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.48582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T10:42:35.24386Z","caller":"traceutil/trace.go:171","msg":"trace[1164494223] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5435; }","duration":"229.551441ms","start":"2026-08-22T10:42:35.014286Z","end":"2026-08-22T10:42:35.243838Z","steps":["trace[1164494223] 'agreement among raft nodes before linearized reading' (duration: 229.455138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:35.243685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.582581ms","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-08-22T10:42:35.24396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.718177ms","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-08-22T10:42:35.243941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.940885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16551"} {"level":"info","ts":"2026-08-22T10:42:35.24398Z","caller":"traceutil/trace.go:171","msg":"trace[1914520767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5435; }","duration":"234.90659ms","start":"2026-08-22T10:42:35.009054Z","end":"2026-08-22T10:42:35.243961Z","steps":["trace[1914520767] 'agreement among raft nodes before linearized reading' (duration: 234.464429ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.244Z","caller":"traceutil/trace.go:171","msg":"trace[2017181591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5435; }","duration":"232.800968ms","start":"2026-08-22T10:42:35.011186Z","end":"2026-08-22T10:42:35.243987Z","steps":["trace[2017181591] 'agreement among raft nodes before linearized reading' (duration: 232.603044ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.244043Z","caller":"traceutil/trace.go:171","msg":"trace[721175748] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5435; }","duration":"167.111789ms","start":"2026-08-22T10:42:35.0769Z","end":"2026-08-22T10:42:35.244012Z","steps":["trace[721175748] 'agreement among raft nodes before linearized reading' (duration: 166.768741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:35.24435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.606332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-22T10:42:35.2444Z","caller":"traceutil/trace.go:171","msg":"trace[606515918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5435; }","duration":"234.697474ms","start":"2026-08-22T10:42:35.009692Z","end":"2026-08-22T10:42:35.24439Z","steps":["trace[606515918] 'agreement among raft nodes before linearized reading' (duration: 234.464318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:35.244579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.46504ms","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-08-22T10:42:35.244622Z","caller":"traceutil/trace.go:171","msg":"trace[1277647702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5435; }","duration":"237.533442ms","start":"2026-08-22T10:42:35.007078Z","end":"2026-08-22T10:42:35.244611Z","steps":["trace[1277647702] 'agreement among raft nodes before linearized reading' (duration: 236.369394ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.352155Z","caller":"traceutil/trace.go:171","msg":"trace[587852344] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"102.867014ms","start":"2026-08-22T10:42:35.249259Z","end":"2026-08-22T10:42:35.352126Z","steps":["trace[587852344] 'process raft request' (duration: 94.430132ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.365989Z","caller":"traceutil/trace.go:171","msg":"trace[1169106987] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"116.146723ms","start":"2026-08-22T10:42:35.249817Z","end":"2026-08-22T10:42:35.365963Z","steps":["trace[1169106987] 'process raft request' (duration: 115.866327ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.366032Z","caller":"traceutil/trace.go:171","msg":"trace[1623745499] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"114.874642ms","start":"2026-08-22T10:42:35.251125Z","end":"2026-08-22T10:42:35.366Z","steps":["trace[1623745499] 'process raft request' (duration: 114.77918ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.36607Z","caller":"traceutil/trace.go:171","msg":"trace[1962135073] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"116.018441ms","start":"2026-08-22T10:42:35.250018Z","end":"2026-08-22T10:42:35.366037Z","steps":["trace[1962135073] 'process raft request' (duration: 115.835257ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.366313Z","caller":"traceutil/trace.go:171","msg":"trace[393495773] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"114.961314ms","start":"2026-08-22T10:42:35.251339Z","end":"2026-08-22T10:42:35.3663Z","steps":["trace[393495773] 'process raft request' (duration: 114.610006ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.366407Z","caller":"traceutil/trace.go:171","msg":"trace[1883202700] linearizableReadLoop","detail":"{readStateIndex:5640; appliedIndex:5638; }","duration":"116.505082ms","start":"2026-08-22T10:42:35.249879Z","end":"2026-08-22T10:42:35.366384Z","steps":["trace[1883202700] 'read index received' (duration: 93.902919ms)","trace[1883202700] 'applied index is now lower than readState.Index' (duration: 22.600153ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:35.366497Z","caller":"traceutil/trace.go:171","msg":"trace[2085079340] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"108.138791ms","start":"2026-08-22T10:42:35.258332Z","end":"2026-08-22T10:42:35.36647Z","steps":["trace[2085079340] 'process raft request' (duration: 107.884205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:35.367171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.26072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-22T10:42:35.367244Z","caller":"traceutil/trace.go:171","msg":"trace[125580501] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5441; }","duration":"117.381443ms","start":"2026-08-22T10:42:35.249847Z","end":"2026-08-22T10:42:35.367228Z","steps":["trace[125580501] 'agreement among raft nodes before linearized reading' (duration: 116.641866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:35.367251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.952798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-22T10:42:35.367317Z","caller":"traceutil/trace.go:171","msg":"trace[852013912] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5441; }","duration":"116.066261ms","start":"2026-08-22T10:42:35.251229Z","end":"2026-08-22T10:42:35.367295Z","steps":["trace[852013912] 'agreement among raft nodes before linearized reading' (duration: 115.292112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:35.6099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.393577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:35.610374Z","caller":"traceutil/trace.go:171","msg":"trace[1527090224] linearizableReadLoop","detail":"{readStateIndex:5650; appliedIndex:5648; }","duration":"143.515151ms","start":"2026-08-22T10:42:35.466836Z","end":"2026-08-22T10:42:35.610351Z","steps":["trace[1527090224] 'read index received' (duration: 45.601µs)","trace[1527090224] 'applied index is now lower than readState.Index' (duration: 143.46762ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:35.610565Z","caller":"traceutil/trace.go:171","msg":"trace[1893774703] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"175.937631ms","start":"2026-08-22T10:42:35.43461Z","end":"2026-08-22T10:42:35.610548Z","steps":["trace[1893774703] 'process raft request' (duration: 24.771716ms)","trace[1893774703] 'compare' (duration: 150.155491ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:35.610762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.9055ms","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-08-22T10:42:35.610791Z","caller":"traceutil/trace.go:171","msg":"trace[1454049573] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"174.437985ms","start":"2026-08-22T10:42:35.436299Z","end":"2026-08-22T10:42:35.610737Z","steps":["trace[1454049573] 'process raft request' (duration: 173.940613ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:35.610878Z","caller":"traceutil/trace.go:171","msg":"trace[718337363] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5447; }","duration":"144.070694ms","start":"2026-08-22T10:42:35.466784Z","end":"2026-08-22T10:42:35.610854Z","steps":["trace[718337363] 'agreement among raft nodes before linearized reading' (duration: 143.843529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:35.610911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.011725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:42:35.61102Z","caller":"traceutil/trace.go:171","msg":"trace[1230677173] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5447; }","duration":"105.21991ms","start":"2026-08-22T10:42:35.505783Z","end":"2026-08-22T10:42:35.611003Z","steps":["trace[1230677173] 'agreement among raft nodes before linearized reading' (duration: 105.003995ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:41.516111Z","caller":"traceutil/trace.go:171","msg":"trace[1692078003] linearizableReadLoop","detail":"{readStateIndex:5721; appliedIndex:5720; }","duration":"126.154034ms","start":"2026-08-22T10:42:41.389892Z","end":"2026-08-22T10:42:41.516046Z","steps":["trace[1692078003] 'read index received' (duration: 125.909258ms)","trace[1692078003] 'applied index is now lower than readState.Index' (duration: 243.696µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:41.516207Z","caller":"traceutil/trace.go:171","msg":"trace[1536500369] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"137.093307ms","start":"2026-08-22T10:42:41.379069Z","end":"2026-08-22T10:42:41.516162Z","steps":["trace[1536500369] 'process raft request' (duration: 136.80455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:41.5164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.628737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-22T10:42:41.516431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.503912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-22T10:42:41.51645Z","caller":"traceutil/trace.go:171","msg":"trace[1068713149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5517; }","duration":"124.719289ms","start":"2026-08-22T10:42:41.391717Z","end":"2026-08-22T10:42:41.516436Z","steps":["trace[1068713149] 'agreement among raft nodes before linearized reading' (duration: 124.523315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:41.516492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.511088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:42:41.516542Z","caller":"traceutil/trace.go:171","msg":"trace[2013419601] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5517; }","duration":"126.661796ms","start":"2026-08-22T10:42:41.389814Z","end":"2026-08-22T10:42:41.516476Z","steps":["trace[2013419601] 'agreement among raft nodes before linearized reading' (duration: 126.377979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:41.516554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.397011ms","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-08-22T10:42:41.516577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.722679ms","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-08-22T10:42:41.516615Z","caller":"traceutil/trace.go:171","msg":"trace[2113191819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5517; }","duration":"124.610546ms","start":"2026-08-22T10:42:41.391989Z","end":"2026-08-22T10:42:41.516599Z","steps":["trace[2113191819] 'agreement among raft nodes before linearized reading' (duration: 124.314759ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:41.516606Z","caller":"traceutil/trace.go:171","msg":"trace[394827633] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:5517; }","duration":"120.669942ms","start":"2026-08-22T10:42:41.395908Z","end":"2026-08-22T10:42:41.516578Z","steps":["trace[394827633] 'agreement among raft nodes before linearized reading' (duration: 120.368204ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:41.516656Z","caller":"traceutil/trace.go:171","msg":"trace[700577489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5517; }","duration":"124.870352ms","start":"2026-08-22T10:42:41.391767Z","end":"2026-08-22T10:42:41.516637Z","steps":["trace[700577489] 'agreement among raft nodes before linearized reading' (duration: 124.586995ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:45.763773Z","caller":"traceutil/trace.go:171","msg":"trace[524242045] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"107.796562ms","start":"2026-08-22T10:42:45.655935Z","end":"2026-08-22T10:42:45.763731Z","steps":["trace[524242045] 'process raft request' (duration: 98.095169ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:45.763815Z","caller":"traceutil/trace.go:171","msg":"trace[1555611729] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"107.730211ms","start":"2026-08-22T10:42:45.656052Z","end":"2026-08-22T10:42:45.763782Z","steps":["trace[1555611729] 'process raft request' (duration: 107.609138ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:49.347211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2982} {"level":"info","ts":"2026-08-22T10:43:49.420126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2982,"took":"71.195853ms","hash":3025782967,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":34066432,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T10:43:49.420199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3025782967,"revision":2982,"compact-revision":-1} {"level":"info","ts":"2026-08-22T10:48:24.877563Z","caller":"traceutil/trace.go:171","msg":"trace[537918420] transaction","detail":"{read_only:false; response_revision:8138; number_of_response:1; }","duration":"269.483656ms","start":"2026-08-22T10:48:24.608042Z","end":"2026-08-22T10:48:24.877526Z","steps":["trace[537918420] 'process raft request' (duration: 269.242611ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:25.016567Z","caller":"traceutil/trace.go:171","msg":"trace[1041380830] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"405.807222ms","start":"2026-08-22T10:48:24.610721Z","end":"2026-08-22T10:48:25.016528Z","steps":["trace[1041380830] 'process raft request' (duration: 353.607538ms)","trace[1041380830] 'compare' (duration: 51.950747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:48:25.016863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:24.610714Z","time spent":"405.995367ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:48:25.01688Z","caller":"traceutil/trace.go:171","msg":"trace[642292914] transaction","detail":"{read_only:false; response_revision:8140; number_of_response:1; }","duration":"402.999815ms","start":"2026-08-22T10:48:24.613852Z","end":"2026-08-22T10:48:25.016852Z","steps":["trace[642292914] 'process raft request' (duration: 402.599625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:25.017044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:24.613846Z","time spent":"403.109188ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:48:25.017349Z","caller":"traceutil/trace.go:171","msg":"trace[1938914153] transaction","detail":"{read_only:false; response_revision:8141; number_of_response:1; }","duration":"402.486253ms","start":"2026-08-22T10:48:24.614828Z","end":"2026-08-22T10:48:25.017315Z","steps":["trace[1938914153] 'process raft request' (duration: 401.877528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:25.017516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:24.614822Z","time spent":"402.601145ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:48:37.760285Z","caller":"traceutil/trace.go:171","msg":"trace[1359471816] transaction","detail":"{read_only:false; response_revision:8205; number_of_response:1; }","duration":"310.986524ms","start":"2026-08-22T10:48:37.449263Z","end":"2026-08-22T10:48:37.760249Z","steps":["trace[1359471816] 'process raft request' (duration: 310.803779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:37.76047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:37.44925Z","time spent":"311.126867ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:48:37.791213Z","caller":"traceutil/trace.go:171","msg":"trace[1796888876] transaction","detail":"{read_only:false; response_revision:8206; number_of_response:1; }","duration":"340.140175ms","start":"2026-08-22T10:48:37.451041Z","end":"2026-08-22T10:48:37.791182Z","steps":["trace[1796888876] 'process raft request' (duration: 339.963571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:37.791383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:37.451024Z","time spent":"340.274278ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:48:37.832783Z","caller":"traceutil/trace.go:171","msg":"trace[1414244848] linearizableReadLoop","detail":"{readStateIndex:8495; appliedIndex:8493; }","duration":"379.549622ms","start":"2026-08-22T10:48:37.453201Z","end":"2026-08-22T10:48:37.83275Z","steps":["trace[1414244848] 'read index received' (duration: 306.867715ms)","trace[1414244848] 'applied index is now lower than readState.Index' (duration: 72.680397ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:48:37.833044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.108239ms","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":"warn","ts":"2026-08-22T10:48:37.833085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.895296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-22T10:48:37.833098Z","caller":"traceutil/trace.go:171","msg":"trace[616718475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8206; }","duration":"366.189091ms","start":"2026-08-22T10:48:37.466898Z","end":"2026-08-22T10:48:37.833087Z","steps":["trace[616718475] 'agreement among raft nodes before linearized reading' (duration: 366.033148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:37.833127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:37.466888Z","time spent":"366.234282ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T10:48:37.833125Z","caller":"traceutil/trace.go:171","msg":"trace[581480298] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8206; }","duration":"157.999068ms","start":"2026-08-22T10:48:37.675115Z","end":"2026-08-22T10:48:37.833114Z","steps":["trace[581480298] 'agreement among raft nodes before linearized reading' (duration: 157.814874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:37.833044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.833969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-22T10:48:37.83309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.309115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-22T10:48:37.833515Z","caller":"traceutil/trace.go:171","msg":"trace[1944578087] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8206; }","duration":"314.766186ms","start":"2026-08-22T10:48:37.518711Z","end":"2026-08-22T10:48:37.833478Z","steps":["trace[1944578087] 'agreement among raft nodes before linearized reading' (duration: 314.241103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:37.833608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:37.518692Z","time spent":"314.902628ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T10:48:37.83325Z","caller":"traceutil/trace.go:171","msg":"trace[2011243566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8206; }","duration":"380.057044ms","start":"2026-08-22T10:48:37.453182Z","end":"2026-08-22T10:48:37.833239Z","steps":["trace[2011243566] 'agreement among raft nodes before linearized reading' (duration: 379.702256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:37.83378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:37.453174Z","time spent":"380.588136ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T10:48:38.185683Z","caller":"traceutil/trace.go:171","msg":"trace[10112678] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"111.5166ms","start":"2026-08-22T10:48:38.074147Z","end":"2026-08-22T10:48:38.185664Z","steps":["trace[10112678] 'process raft request' (duration: 111.384376ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:49.354058Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6079} {"level":"info","ts":"2026-08-22T10:48:49.455696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6079,"took":"98.929775ms","hash":2772887154,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":23216128,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-22T10:48:49.455785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2772887154,"revision":6079,"compact-revision":2982} {"level":"info","ts":"2026-08-22T10:49:05.487246Z","caller":"traceutil/trace.go:171","msg":"trace[1955656420] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"442.209243ms","start":"2026-08-22T10:49:05.044998Z","end":"2026-08-22T10:49:05.487207Z","steps":["trace[1955656420] 'process raft request' (duration: 441.955597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:05.487501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:05.044973Z","time spent":"442.410108ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:05.487951Z","caller":"traceutil/trace.go:171","msg":"trace[2072956561] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"336.432266ms","start":"2026-08-22T10:49:05.151487Z","end":"2026-08-22T10:49:05.487919Z","steps":["trace[2072956561] 'process raft request' (duration: 336.175779ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:05.487982Z","caller":"traceutil/trace.go:171","msg":"trace[711239081] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"303.29634ms","start":"2026-08-22T10:49:05.184614Z","end":"2026-08-22T10:49:05.48791Z","steps":["trace[711239081] 'process raft request' (duration: 303.214487ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:05.488061Z","caller":"traceutil/trace.go:171","msg":"trace[1511432558] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"284.405467ms","start":"2026-08-22T10:49:05.203628Z","end":"2026-08-22T10:49:05.488034Z","steps":["trace[1511432558] 'process raft request' (duration: 284.244173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:05.488174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:05.151455Z","time spent":"336.588089ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:05.488191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:05.18458Z","time spent":"303.474524ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:12.215361Z","caller":"traceutil/trace.go:171","msg":"trace[1896877664] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"265.508253ms","start":"2026-08-22T10:49:11.949829Z","end":"2026-08-22T10:49:12.215337Z","steps":["trace[1896877664] 'process raft request' (duration: 265.357949ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:12.612973Z","caller":"traceutil/trace.go:171","msg":"trace[126531326] linearizableReadLoop","detail":"{readStateIndex:8743; appliedIndex:8741; }","duration":"571.376094ms","start":"2026-08-22T10:49:12.041568Z","end":"2026-08-22T10:49:12.612944Z","steps":["trace[126531326] 'read index received' (duration: 173.705519ms)","trace[126531326] 'applied index is now lower than readState.Index' (duration: 397.668895ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:12.613124Z","caller":"traceutil/trace.go:171","msg":"trace[1434354569] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"662.718416ms","start":"2026-08-22T10:49:11.950371Z","end":"2026-08-22T10:49:12.613089Z","steps":["trace[1434354569] 'process raft request' (duration: 622.47995ms)","trace[1434354569] 'compare' (duration: 39.869077ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:49:12.613228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.64695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T10:49:12.613273Z","caller":"traceutil/trace.go:171","msg":"trace[1151302151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8443; }","duration":"571.754522ms","start":"2026-08-22T10:49:12.041507Z","end":"2026-08-22T10:49:12.613261Z","steps":["trace[1151302151] 'agreement among raft nodes before linearized reading' (duration: 571.573948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:12.613283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:11.950338Z","time spent":"662.857779ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:12.613278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.030567ms","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-08-22T10:49:12.613313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:12.041483Z","time spent":"571.822094ms","remote":"127.0.0.1:42220","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-08-22T10:49:12.613361Z","caller":"traceutil/trace.go:171","msg":"trace[809044483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8443; }","duration":"549.169361ms","start":"2026-08-22T10:49:12.064168Z","end":"2026-08-22T10:49:12.613338Z","steps":["trace[809044483] 'agreement among raft nodes before linearized reading' (duration: 548.950976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:12.613379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.116235ms","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-08-22T10:49:12.613413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:12.064144Z","time spent":"549.260822ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:49:12.613436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.176093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-smv4p\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:49:12.613462Z","caller":"traceutil/trace.go:171","msg":"trace[1547878265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8443; }","duration":"258.247598ms","start":"2026-08-22T10:49:12.355192Z","end":"2026-08-22T10:49:12.61344Z","steps":["trace[1547878265] 'agreement among raft nodes before linearized reading' (duration: 258.009633ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:12.613512Z","caller":"traceutil/trace.go:171","msg":"trace[1277190699] range","detail":"{range_begin:/registry/pods/openstack/keepalived-smv4p; range_end:; response_count:1; response_revision:8443; }","duration":"222.260594ms","start":"2026-08-22T10:49:12.391232Z","end":"2026-08-22T10:49:12.613493Z","steps":["trace[1277190699] 'agreement among raft nodes before linearized reading' (duration: 222.101941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:12.613768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.671998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T10:49:12.613849Z","caller":"traceutil/trace.go:171","msg":"trace[1524503709] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8443; }","duration":"448.841912ms","start":"2026-08-22T10:49:12.164988Z","end":"2026-08-22T10:49:12.61383Z","steps":["trace[1524503709] 'agreement among raft nodes before linearized reading' (duration: 448.447483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:12.614016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:12.164967Z","time spent":"448.994046ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T10:49:12.614006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.461911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.182\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T10:49:12.614131Z","caller":"traceutil/trace.go:171","msg":"trace[65313907] range","detail":"{range_begin:/registry/masterleases/199.204.45.182; range_end:; response_count:1; response_revision:8443; }","duration":"285.618925ms","start":"2026-08-22T10:49:12.328499Z","end":"2026-08-22T10:49:12.614118Z","steps":["trace[65313907] 'agreement among raft nodes before linearized reading' (duration: 284.717004ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:13.069489Z","caller":"traceutil/trace.go:171","msg":"trace[1324468701] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"450.544603ms","start":"2026-08-22T10:49:12.618928Z","end":"2026-08-22T10:49:13.069473Z","steps":["trace[1324468701] 'process raft request' (duration: 450.519963ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:13.069504Z","caller":"traceutil/trace.go:171","msg":"trace[222896127] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"450.640595ms","start":"2026-08-22T10:49:12.618824Z","end":"2026-08-22T10:49:13.069465Z","steps":["trace[222896127] 'process raft request' (duration: 450.475671ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:13.069546Z","caller":"traceutil/trace.go:171","msg":"trace[1766589749] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"450.626566ms","start":"2026-08-22T10:49:12.618895Z","end":"2026-08-22T10:49:13.069521Z","steps":["trace[1766589749] 'process raft request' (duration: 450.507743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:13.069608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:12.618914Z","time spent":"450.628235ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:13.069655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:12.618807Z","time spent":"450.768549ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:13.069671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:12.61889Z","time spent":"450.713488ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:13.069681Z","caller":"traceutil/trace.go:171","msg":"trace[2090056088] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"450.696307ms","start":"2026-08-22T10:49:12.61893Z","end":"2026-08-22T10:49:13.069626Z","steps":["trace[2090056088] 'process raft request' (duration: 450.498312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:13.069923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:12.618924Z","time spent":"450.870011ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:13.079782Z","caller":"traceutil/trace.go:171","msg":"trace[567128745] linearizableReadLoop","detail":"{readStateIndex:8749; appliedIndex:8746; }","duration":"104.688933ms","start":"2026-08-22T10:49:12.975076Z","end":"2026-08-22T10:49:13.079765Z","steps":["trace[567128745] 'read index received' (duration: 94.351534ms)","trace[567128745] 'applied index is now lower than readState.Index' (duration: 10.336469ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:13.079968Z","caller":"traceutil/trace.go:171","msg":"trace[334402873] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"444.951309ms","start":"2026-08-22T10:49:12.635Z","end":"2026-08-22T10:49:13.079951Z","steps":["trace[334402873] 'process raft request' (duration: 444.630512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:13.080059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:12.634973Z","time spent":"445.039192ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:13.080346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.263647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T10:49:13.080379Z","caller":"traceutil/trace.go:171","msg":"trace[507071473] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8449; }","duration":"105.353269ms","start":"2026-08-22T10:49:12.975019Z","end":"2026-08-22T10:49:13.080372Z","steps":["trace[507071473] 'agreement among raft nodes before linearized reading' (duration: 104.849377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:13.490702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.43375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-08-22T10:49:13.490784Z","caller":"traceutil/trace.go:171","msg":"trace[1167367753] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8449; }","duration":"285.567403ms","start":"2026-08-22T10:49:13.205197Z","end":"2026-08-22T10:49:13.490765Z","steps":["trace[1167367753] 'range keys from in-memory index tree' (duration: 285.246196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:13.490765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.002826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:49:13.490844Z","caller":"traceutil/trace.go:171","msg":"trace[250894492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8449; }","duration":"324.129129ms","start":"2026-08-22T10:49:13.166694Z","end":"2026-08-22T10:49:13.490823Z","steps":["trace[250894492] 'range keys from in-memory index tree' (duration: 323.914623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:13.490894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:13.166673Z","time spent":"324.212711ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:49:18.428894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.764255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:49:18.429033Z","caller":"traceutil/trace.go:171","msg":"trace[95124444] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"359.444397ms","start":"2026-08-22T10:49:18.069566Z","end":"2026-08-22T10:49:18.42901Z","steps":["trace[95124444] 'process raft request' (duration: 114.484938ms)","trace[95124444] 'compare' (duration: 244.345234ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:49:18.429091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.069541Z","time spent":"359.526828ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:49:18.487143Z","caller":"traceutil/trace.go:171","msg":"trace[1998986250] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8790; }","duration":"323.384681ms","start":"2026-08-22T10:49:18.163733Z","end":"2026-08-22T10:49:18.487118Z","steps":["trace[1998986250] 'read index received' (duration: 20.314928ms)","trace[1998986250] 'applied index is now lower than readState.Index' (duration: 303.068563ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:18.487221Z","caller":"traceutil/trace.go:171","msg":"trace[1165833425] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"408.808782ms","start":"2026-08-22T10:49:18.078362Z","end":"2026-08-22T10:49:18.487171Z","steps":["trace[1165833425] 'process raft request' (duration: 408.597107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:18.48817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.078349Z","time spent":"409.665411ms","remote":"127.0.0.1:49294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:18.487302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.554336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:49:18.488466Z","caller":"traceutil/trace.go:171","msg":"trace[1072627515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8490; }","duration":"324.763275ms","start":"2026-08-22T10:49:18.163679Z","end":"2026-08-22T10:49:18.488442Z","steps":["trace[1072627515] 'agreement among raft nodes before linearized reading' (duration: 323.542106ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:18.487275Z","caller":"traceutil/trace.go:171","msg":"trace[1825107915] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8490; }","duration":"400.726437ms","start":"2026-08-22T10:49:18.08651Z","end":"2026-08-22T10:49:18.487236Z","steps":["trace[1825107915] 'process raft request' (duration: 400.580024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:18.488536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.163665Z","time spent":"324.847166ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:49:18.488665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.086488Z","time spent":"402.068839ms","remote":"127.0.0.1:49294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":77,"response count":0,"response size":1848,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:18.763735Z","caller":"traceutil/trace.go:171","msg":"trace[2104851089] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"347.45978ms","start":"2026-08-22T10:49:18.416236Z","end":"2026-08-22T10:49:18.763696Z","steps":["trace[2104851089] 'process raft request' (duration: 346.423544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:18.763938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.416203Z","time spent":"347.637413ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:49:18.764228Z","caller":"traceutil/trace.go:171","msg":"trace[1396817246] linearizableReadLoop","detail":"{readStateIndex:8794; appliedIndex:8794; }","duration":"276.986908ms","start":"2026-08-22T10:49:18.487203Z","end":"2026-08-22T10:49:18.76419Z","steps":["trace[1396817246] 'read index received' (duration: 276.972128ms)","trace[1396817246] 'applied index is now lower than readState.Index' (duration: 12.7µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:49:18.764523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.113269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-pkx4q.18ce1a3c435b7683\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-08-22T10:49:18.764557Z","caller":"traceutil/trace.go:171","msg":"trace[1327069225] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-pkx4q.18ce1a3c435b7683; range_end:; response_count:1; response_revision:8491; }","duration":"314.209561ms","start":"2026-08-22T10:49:18.450334Z","end":"2026-08-22T10:49:18.764544Z","steps":["trace[1327069225] 'agreement among raft nodes before linearized reading' (duration: 314.010396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:18.764589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.450315Z","time spent":"314.265223ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":788,"request content":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-pkx4q.18ce1a3c435b7683\" "} {"level":"warn","ts":"2026-08-22T10:49:18.764763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.278414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-m6tl6\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-22T10:49:18.764814Z","caller":"traceutil/trace.go:171","msg":"trace[434529880] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-m6tl6; range_end:; response_count:1; response_revision:8491; }","duration":"349.380856ms","start":"2026-08-22T10:49:18.415416Z","end":"2026-08-22T10:49:18.764797Z","steps":["trace[434529880] 'agreement among raft nodes before linearized reading' (duration: 349.170791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:18.764849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.415391Z","time spent":"349.450617ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4671,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-m6tl6\" "} {"level":"warn","ts":"2026-08-22T10:49:18.764855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.979688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-22T10:49:18.764887Z","caller":"traceutil/trace.go:171","msg":"trace[1911176508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8491; }","duration":"512.06153ms","start":"2026-08-22T10:49:18.252817Z","end":"2026-08-22T10:49:18.764879Z","steps":["trace[1911176508] 'agreement among raft nodes before linearized reading' (duration: 511.961397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:18.76491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.252791Z","time spent":"512.113841ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T10:49:19.256686Z","caller":"traceutil/trace.go:171","msg":"trace[491058431] linearizableReadLoop","detail":"{readStateIndex:8795; appliedIndex:8794; }","duration":"492.357457ms","start":"2026-08-22T10:49:18.764305Z","end":"2026-08-22T10:49:19.256662Z","steps":["trace[491058431] 'read index received' (duration: 408.275049ms)","trace[491058431] 'applied index is now lower than readState.Index' (duration: 84.081658ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:49:19.256869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.522708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-smv4p\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:49:19.256892Z","caller":"traceutil/trace.go:171","msg":"trace[217234184] range","detail":"{range_begin:/registry/pods/openstack/keepalived-smv4p; range_end:; response_count:1; response_revision:8492; }","duration":"591.59215ms","start":"2026-08-22T10:49:18.665292Z","end":"2026-08-22T10:49:19.256885Z","steps":["trace[217234184] 'agreement among raft nodes before linearized reading' (duration: 591.464237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.256914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.665272Z","time spent":"591.63729ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-smv4p\" "} {"level":"warn","ts":"2026-08-22T10:49:19.256886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.199004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-22T10:49:19.256954Z","caller":"traceutil/trace.go:171","msg":"trace[2086857946] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8492; }","duration":"572.305607ms","start":"2026-08-22T10:49:18.684631Z","end":"2026-08-22T10:49:19.256936Z","steps":["trace[2086857946] 'agreement among raft nodes before linearized reading' (duration: 572.046191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.256991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.684621Z","time spent":"572.363058ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-22T10:49:19.257076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.526211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:49:19.257097Z","caller":"traceutil/trace.go:171","msg":"trace[480285529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8492; }","duration":"767.583422ms","start":"2026-08-22T10:49:18.489508Z","end":"2026-08-22T10:49:19.257091Z","steps":["trace[480285529] 'agreement among raft nodes before linearized reading' (duration: 767.545652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.25711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.489488Z","time spent":"767.619964ms","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:49:19.257057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.576145ms","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-08-22T10:49:19.257234Z","caller":"traceutil/trace.go:171","msg":"trace[344918239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8492; }","duration":"620.78136ms","start":"2026-08-22T10:49:18.636402Z","end":"2026-08-22T10:49:19.257183Z","steps":["trace[344918239] 'agreement among raft nodes before linearized reading' (duration: 620.36711ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:19.257284Z","caller":"traceutil/trace.go:171","msg":"trace[739303324] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"751.085697ms","start":"2026-08-22T10:49:18.50619Z","end":"2026-08-22T10:49:19.257276Z","steps":["trace[739303324] 'process raft request' (duration: 666.373444ms)","trace[739303324] 'compare' (duration: 83.888212ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:49:19.257359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.636379Z","time spent":"620.939464ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T10:49:19.257447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.506168Z","time spent":"751.22645ms","remote":"127.0.0.1:49294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2348,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:19.53697Z","caller":"traceutil/trace.go:171","msg":"trace[1834160919] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"769.644372ms","start":"2026-08-22T10:49:18.767267Z","end":"2026-08-22T10:49:19.536912Z","steps":["trace[1834160919] 'process raft request' (duration: 769.389415ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:19.536997Z","caller":"traceutil/trace.go:171","msg":"trace[285079891] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"768.516316ms","start":"2026-08-22T10:49:18.768453Z","end":"2026-08-22T10:49:19.536969Z","steps":["trace[285079891] 'process raft request' (duration: 768.379643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.537246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.767235Z","time spent":"769.865078ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:19.537272Z","caller":"traceutil/trace.go:171","msg":"trace[1869164404] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"765.094642ms","start":"2026-08-22T10:49:18.772143Z","end":"2026-08-22T10:49:19.537237Z","steps":["trace[1869164404] 'process raft request' (duration: 764.759314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.537414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.768427Z","time spent":"768.788272ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:19.537531Z","caller":"traceutil/trace.go:171","msg":"trace[1874006950] linearizableReadLoop","detail":"{readStateIndex:8799; appliedIndex:8795; }","duration":"280.761478ms","start":"2026-08-22T10:49:19.256736Z","end":"2026-08-22T10:49:19.537498Z","steps":["trace[1874006950] 'read index received' (duration: 279.980409ms)","trace[1874006950] 'applied index is now lower than readState.Index' (duration: 780.079µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:49:19.537603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.772131Z","time spent":"765.338699ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:19.538112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.83677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-22T10:49:19.538155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.512406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T10:49:19.538179Z","caller":"traceutil/trace.go:171","msg":"trace[1031295738] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8496; }","duration":"430.940323ms","start":"2026-08-22T10:49:19.107219Z","end":"2026-08-22T10:49:19.538159Z","steps":["trace[1031295738] 'agreement among raft nodes before linearized reading' (duration: 430.760949ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:19.538216Z","caller":"traceutil/trace.go:171","msg":"trace[1700857222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8496; }","duration":"431.611099ms","start":"2026-08-22T10:49:19.106587Z","end":"2026-08-22T10:49:19.538198Z","steps":["trace[1700857222] 'agreement among raft nodes before linearized reading' (duration: 431.422454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.538228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.003318ms","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-08-22T10:49:19.538272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.106565Z","time spent":"431.69617ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T10:49:19.538284Z","caller":"traceutil/trace.go:171","msg":"trace[771949155] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"765.392309ms","start":"2026-08-22T10:49:18.772876Z","end":"2026-08-22T10:49:19.538268Z","steps":["trace[771949155] 'process raft request' (duration: 764.263283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.538233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.107196Z","time spent":"431.025414ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T10:49:19.538374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:18.772857Z","time spent":"765.462071ms","remote":"127.0.0.1:42174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:19.538282Z","caller":"traceutil/trace.go:171","msg":"trace[577720404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8496; }","duration":"432.1062ms","start":"2026-08-22T10:49:19.106158Z","end":"2026-08-22T10:49:19.538264Z","steps":["trace[577720404] 'agreement among raft nodes before linearized reading' (duration: 431.967677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.538516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.10613Z","time spent":"432.369187ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:49:19.538578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.657276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T10:49:19.538647Z","caller":"traceutil/trace.go:171","msg":"trace[1301856317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8496; }","duration":"430.756568ms","start":"2026-08-22T10:49:19.107876Z","end":"2026-08-22T10:49:19.538633Z","steps":["trace[1301856317] 'agreement among raft nodes before linearized reading' (duration: 430.503612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:19.538695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.107865Z","time spent":"430.82133ms","remote":"127.0.0.1:42220","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-08-22T10:49:20.038082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402370672799462,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T10:49:20.214751Z","caller":"traceutil/trace.go:171","msg":"trace[587521844] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"950.962244ms","start":"2026-08-22T10:49:19.263761Z","end":"2026-08-22T10:49:20.214723Z","steps":["trace[587521844] 'process raft request' (duration: 950.717918ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:20.214782Z","caller":"traceutil/trace.go:171","msg":"trace[117364307] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"849.365196ms","start":"2026-08-22T10:49:19.365391Z","end":"2026-08-22T10:49:20.214756Z","steps":["trace[117364307] 'process raft request' (duration: 849.309855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.214927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.365369Z","time spent":"849.49179ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2479,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:49:20.214937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.263747Z","time spent":"951.081417ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:20.214889Z","caller":"traceutil/trace.go:171","msg":"trace[128856657] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"944.729645ms","start":"2026-08-22T10:49:19.270093Z","end":"2026-08-22T10:49:20.214822Z","steps":["trace[128856657] 'process raft request' (duration: 944.548689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.215163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.270082Z","time spent":"944.95735ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:49:20.522454Z","caller":"traceutil/trace.go:171","msg":"trace[1965953503] linearizableReadLoop","detail":"{readStateIndex:8810; appliedIndex:8799; }","duration":"984.814106ms","start":"2026-08-22T10:49:19.537614Z","end":"2026-08-22T10:49:20.522429Z","steps":["trace[1965953503] 'read index received' (duration: 676.955687ms)","trace[1965953503] 'applied index is now lower than readState.Index' (duration: 307.857129ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:20.522537Z","caller":"traceutil/trace.go:171","msg":"trace[1761821971] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"912.859628ms","start":"2026-08-22T10:49:19.609634Z","end":"2026-08-22T10:49:20.522494Z","steps":["trace[1761821971] 'process raft request' (duration: 912.645253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.522931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.609622Z","time spent":"913.244438ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:20.522588Z","caller":"traceutil/trace.go:171","msg":"trace[1638536069] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"912.251934ms","start":"2026-08-22T10:49:19.610317Z","end":"2026-08-22T10:49:20.522569Z","steps":["trace[1638536069] 'process raft request' (duration: 912.015778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.522981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259740245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-22T10:49:20.523067Z","caller":"traceutil/trace.go:171","msg":"trace[1656067870] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8507; }","duration":"1.259885348s","start":"2026-08-22T10:49:19.263158Z","end":"2026-08-22T10:49:20.523043Z","steps":["trace[1656067870] 'agreement among raft nodes before linearized reading' (duration: 1.259553039s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.523094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.610307Z","time spent":"912.704296ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:20.523113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259123171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228476"} {"level":"warn","ts":"2026-08-22T10:49:20.523132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.263138Z","time spent":"1.25997664s","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-22T10:49:20.523159Z","caller":"traceutil/trace.go:171","msg":"trace[1689510782] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8507; }","duration":"1.259217083s","start":"2026-08-22T10:49:19.263931Z","end":"2026-08-22T10:49:20.523148Z","steps":["trace[1689510782] 'agreement among raft nodes before linearized reading' (duration: 1.258825163s)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:20.522635Z","caller":"traceutil/trace.go:171","msg":"trace[1767001216] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"976.368553ms","start":"2026-08-22T10:49:19.546259Z","end":"2026-08-22T10:49:20.522627Z","steps":["trace[1767001216] 'process raft request' (duration: 975.961854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.5232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.263921Z","time spent":"1.259269974s","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228499,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-22T10:49:20.52323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.546232Z","time spent":"976.965598ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:20.522646Z","caller":"traceutil/trace.go:171","msg":"trace[484306526] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"976.401293ms","start":"2026-08-22T10:49:19.54622Z","end":"2026-08-22T10:49:20.522621Z","steps":["trace[484306526] 'process raft request' (duration: 975.960563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.523432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.546196Z","time spent":"977.125552ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:20.522613Z","caller":"traceutil/trace.go:171","msg":"trace[2029014724] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"505.369279ms","start":"2026-08-22T10:49:20.017177Z","end":"2026-08-22T10:49:20.522547Z","steps":["trace[2029014724] 'process raft request' (duration: 505.211485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.523547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:20.017135Z","time spent":"506.370543ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:20.522704Z","caller":"traceutil/trace.go:171","msg":"trace[555694421] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"976.554707ms","start":"2026-08-22T10:49:19.546138Z","end":"2026-08-22T10:49:20.522693Z","steps":["trace[555694421] 'process raft request' (duration: 975.892741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.523647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.546114Z","time spent":"977.497271ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:20.522719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25245427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:49:20.523704Z","caller":"traceutil/trace.go:171","msg":"trace[515152643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8507; }","duration":"1.253468465s","start":"2026-08-22T10:49:19.270223Z","end":"2026-08-22T10:49:20.523692Z","steps":["trace[515152643] 'agreement among raft nodes before linearized reading' (duration: 1.252444709s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.523738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.270217Z","time spent":"1.253511556s","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:49:20.522762Z","caller":"traceutil/trace.go:171","msg":"trace[1503035132] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"975.116743ms","start":"2026-08-22T10:49:19.547637Z","end":"2026-08-22T10:49:20.522754Z","steps":["trace[1503035132] 'process raft request' (duration: 974.616232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.523802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.547631Z","time spent":"976.143709ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:20.522738Z","caller":"traceutil/trace.go:171","msg":"trace[685362079] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"912.311296ms","start":"2026-08-22T10:49:19.610411Z","end":"2026-08-22T10:49:20.522722Z","steps":["trace[685362079] 'process raft request' (duration: 911.949467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.52399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.610391Z","time spent":"913.548996ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:49:20.704384Z","caller":"traceutil/trace.go:171","msg":"trace[249738437] linearizableReadLoop","detail":"{readStateIndex:8812; appliedIndex:8810; }","duration":"181.535976ms","start":"2026-08-22T10:49:20.522828Z","end":"2026-08-22T10:49:20.704364Z","steps":["trace[249738437] 'read index received' (duration: 176.848075ms)","trace[249738437] 'applied index is now lower than readState.Index' (duration: 4.686851ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:49:20.704545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038480214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T10:49:20.704562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.84163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-22T10:49:20.704589Z","caller":"traceutil/trace.go:171","msg":"trace[1000690926] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:8509; }","duration":"1.038607166s","start":"2026-08-22T10:49:19.66597Z","end":"2026-08-22T10:49:20.704578Z","steps":["trace[1000690926] 'agreement among raft nodes before linearized reading' (duration: 1.038498464s)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:20.704619Z","caller":"traceutil/trace.go:171","msg":"trace[485954039] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"483.657878ms","start":"2026-08-22T10:49:20.220892Z","end":"2026-08-22T10:49:20.70455Z","steps":["trace[485954039] 'process raft request' (duration: 478.765561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.704741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103513684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:49:20.704758Z","caller":"traceutil/trace.go:171","msg":"trace[2054075168] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8509; }","duration":"485.965393ms","start":"2026-08-22T10:49:20.218703Z","end":"2026-08-22T10:49:20.704669Z","steps":["trace[2054075168] 'agreement among raft nodes before linearized reading' (duration: 485.715207ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:20.704777Z","caller":"traceutil/trace.go:171","msg":"trace[518129069] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8509; }","duration":"1.103590976s","start":"2026-08-22T10:49:19.601176Z","end":"2026-08-22T10:49:20.704767Z","steps":["trace[518129069] 'agreement among raft nodes before linearized reading' (duration: 1.103536365s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.704817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.601157Z","time spent":"1.103651358s","remote":"127.0.0.1:49262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:49:20.704808Z","caller":"traceutil/trace.go:171","msg":"trace[1209993223] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"476.264481ms","start":"2026-08-22T10:49:20.228491Z","end":"2026-08-22T10:49:20.704755Z","steps":["trace[1209993223] 'process raft request' (duration: 475.80314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.704894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:20.218684Z","time spent":"486.133867ms","remote":"127.0.0.1:42342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-08-22T10:49:20.704904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:20.220877Z","time spent":"483.854272ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:49:20.704951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.601277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T10:49:20.705053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:20.228479Z","time spent":"476.434654ms","remote":"127.0.0.1:49294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2650,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:20.70469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:19.665945Z","time spent":"1.03873434s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":7,"response size":30,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:49:20.705079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.063126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-22T10:49:20.705154Z","caller":"traceutil/trace.go:171","msg":"trace[446647925] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8509; }","duration":"603.967346ms","start":"2026-08-22T10:49:20.101113Z","end":"2026-08-22T10:49:20.70508Z","steps":["trace[446647925] 'agreement among raft nodes before linearized reading' (duration: 603.529385ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:20.705301Z","caller":"traceutil/trace.go:171","msg":"trace[1726485116] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8509; }","duration":"486.352153ms","start":"2026-08-22T10:49:20.218917Z","end":"2026-08-22T10:49:20.705269Z","steps":["trace[1726485116] 'agreement among raft nodes before linearized reading' (duration: 485.83847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.705346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:20.101089Z","time spent":"604.237112ms","remote":"127.0.0.1:37188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:49:20.705411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:20.2189Z","time spent":"486.494437ms","remote":"127.0.0.1:42342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-08-22T10:49:20.711195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.242191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:49:20.71122Z","caller":"traceutil/trace.go:171","msg":"trace[1535622339] transaction","detail":"{read_only:false; response_revision:8510; number_of_response:1; }","duration":"126.069896ms","start":"2026-08-22T10:49:20.585135Z","end":"2026-08-22T10:49:20.711205Z","steps":["trace[1535622339] 'process raft request' (duration: 125.931593ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:20.711248Z","caller":"traceutil/trace.go:171","msg":"trace[1142864078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8510; }","duration":"186.318112ms","start":"2026-08-22T10:49:20.52492Z","end":"2026-08-22T10:49:20.711238Z","steps":["trace[1142864078] 'agreement among raft nodes before linearized reading' (duration: 186.21709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.711387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.153588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19384"} {"level":"info","ts":"2026-08-22T10:49:20.711442Z","caller":"traceutil/trace.go:171","msg":"trace[1650896781] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8510; }","duration":"186.22242ms","start":"2026-08-22T10:49:20.525203Z","end":"2026-08-22T10:49:20.711425Z","steps":["trace[1650896781] 'agreement among raft nodes before linearized reading' (duration: 186.108458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:20.711387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.019217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-08-22T10:49:20.711722Z","caller":"traceutil/trace.go:171","msg":"trace[2007310361] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8510; }","duration":"119.422707ms","start":"2026-08-22T10:49:20.592195Z","end":"2026-08-22T10:49:20.711617Z","steps":["trace[2007310361] 'agreement among raft nodes before linearized reading' (duration: 118.964836ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:10.767021Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243a.wal"} {"level":"info","ts":"2026-08-22T10:50:37.535989Z","caller":"traceutil/trace.go:171","msg":"trace[980529608] transaction","detail":"{read_only:false; response_revision:9216; number_of_response:1; }","duration":"181.295972ms","start":"2026-08-22T10:50:37.354672Z","end":"2026-08-22T10:50:37.535968Z","steps":["trace[980529608] 'process raft request' (duration: 181.164129ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:37.952476Z","caller":"traceutil/trace.go:171","msg":"trace[918551850] transaction","detail":"{read_only:false; response_revision:9217; number_of_response:1; }","duration":"406.775023ms","start":"2026-08-22T10:50:37.545669Z","end":"2026-08-22T10:50:37.952444Z","steps":["trace[918551850] 'process raft request' (duration: 406.604119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:37.952863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:50:37.545646Z","time spent":"406.924416ms","remote":"127.0.0.1:42462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:50:38.040889Z","caller":"traceutil/trace.go:171","msg":"trace[2124672660] transaction","detail":"{read_only:false; response_revision:9218; number_of_response:1; }","duration":"493.130285ms","start":"2026-08-22T10:50:37.547714Z","end":"2026-08-22T10:50:38.040845Z","steps":["trace[2124672660] 'process raft request' (duration: 464.18763ms)","trace[2124672660] 'compare' (duration: 28.697429ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:50:38.041059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:50:37.547692Z","time spent":"493.279219ms","remote":"127.0.0.1:42058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23636,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:50:38.0614Z","caller":"traceutil/trace.go:171","msg":"trace[1247455165] linearizableReadLoop","detail":"{readStateIndex:9542; appliedIndex:9540; }","duration":"436.405944ms","start":"2026-08-22T10:50:37.624967Z","end":"2026-08-22T10:50:38.061373Z","steps":["trace[1247455165] 'read index received' (duration: 327.338906ms)","trace[1247455165] 'applied index is now lower than readState.Index' (duration: 109.065648ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:50:38.06154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.570209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:50:38.061595Z","caller":"traceutil/trace.go:171","msg":"trace[1232504720] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:9218; }","duration":"436.675361ms","start":"2026-08-22T10:50:37.624905Z","end":"2026-08-22T10:50:38.06158Z","steps":["trace[1232504720] 'agreement among raft nodes before linearized reading' (duration: 436.568669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:38.061631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:50:37.624886Z","time spent":"436.735372ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:50:38.067583Z","caller":"traceutil/trace.go:171","msg":"trace[87158230] transaction","detail":"{read_only:false; response_revision:9219; number_of_response:1; }","duration":"108.956104ms","start":"2026-08-22T10:50:37.9586Z","end":"2026-08-22T10:50:38.067556Z","steps":["trace[87158230] 'process raft request' (duration: 108.648787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:38.06767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.187356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:50:38.067733Z","caller":"traceutil/trace.go:171","msg":"trace[1012093174] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:9221; }","duration":"218.294978ms","start":"2026-08-22T10:50:37.849425Z","end":"2026-08-22T10:50:38.067719Z","steps":["trace[1012093174] 'agreement among raft nodes before linearized reading' (duration: 218.176436ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:38.067746Z","caller":"traceutil/trace.go:171","msg":"trace[1458595199] transaction","detail":"{read_only:false; response_revision:9220; number_of_response:1; }","duration":"105.561832ms","start":"2026-08-22T10:50:37.962163Z","end":"2026-08-22T10:50:38.067725Z","steps":["trace[1458595199] 'process raft request' (duration: 105.318277ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:38.067703Z","caller":"traceutil/trace.go:171","msg":"trace[1030258025] transaction","detail":"{read_only:false; response_revision:9221; number_of_response:1; }","duration":"102.297424ms","start":"2026-08-22T10:50:37.965339Z","end":"2026-08-22T10:50:38.067637Z","steps":["trace[1030258025] 'process raft request' (duration: 102.193782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:38.068019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.087548ms","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-08-22T10:50:38.068063Z","caller":"traceutil/trace.go:171","msg":"trace[426402865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9221; }","duration":"154.132229ms","start":"2026-08-22T10:50:37.913921Z","end":"2026-08-22T10:50:38.068053Z","steps":["trace[426402865] 'agreement among raft nodes before linearized reading' (duration: 154.005706ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:51:26.802636Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e44ae1545340c14b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T10:51:26.81267Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T10:51:26.812758Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T10:53:49.360467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8275} {"level":"info","ts":"2026-08-22T10:53:49.440335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8275,"took":"76.700957ms","hash":3581456751,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":25616384,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T10:53:49.440428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3581456751,"revision":8275,"compact-revision":6079} {"level":"warn","ts":"2026-08-22T10:54:17.874487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.749888ms","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-08-22T10:54:17.87465Z","caller":"traceutil/trace.go:171","msg":"trace[1207887399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11141; }","duration":"196.980453ms","start":"2026-08-22T10:54:17.677646Z","end":"2026-08-22T10:54:17.874626Z","steps":["trace[1207887399] 'range keys from in-memory index tree' (duration: 196.512792ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:17.992168Z","caller":"traceutil/trace.go:171","msg":"trace[1487904252] transaction","detail":"{read_only:false; response_revision:11142; number_of_response:1; }","duration":"112.306634ms","start":"2026-08-22T10:54:17.879826Z","end":"2026-08-22T10:54:17.992132Z","steps":["trace[1487904252] 'process raft request' (duration: 112.018567ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:18.137625Z","caller":"traceutil/trace.go:171","msg":"trace[72304736] transaction","detail":"{read_only:false; response_revision:11145; number_of_response:1; }","duration":"100.47585ms","start":"2026-08-22T10:54:18.03712Z","end":"2026-08-22T10:54:18.137595Z","steps":["trace[72304736] 'process raft request' (duration: 99.545378ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:58:49.366367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10794} {"level":"info","ts":"2026-08-22T10:58:49.444588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10794,"took":"75.769233ms","hash":3768300258,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":27852800,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T10:58:49.444638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3768300258,"revision":10794,"compact-revision":8275} {"level":"info","ts":"2026-08-22T11:00:36.36933Z","caller":"traceutil/trace.go:171","msg":"trace[26884752] transaction","detail":"{read_only:false; response_revision:13700; number_of_response:1; }","duration":"103.060789ms","start":"2026-08-22T11:00:36.266232Z","end":"2026-08-22T11:00:36.369292Z","steps":["trace[26884752] 'process raft request' (duration: 102.607988ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:37.661348Z","caller":"traceutil/trace.go:171","msg":"trace[1278117466] linearizableReadLoop","detail":"{readStateIndex:14173; appliedIndex:14172; }","duration":"496.427084ms","start":"2026-08-22T11:00:37.164887Z","end":"2026-08-22T11:00:37.661314Z","steps":["trace[1278117466] 'read index received' (duration: 496.219639ms)","trace[1278117466] 'applied index is now lower than readState.Index' (duration: 206.205µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:00:37.661505Z","caller":"traceutil/trace.go:171","msg":"trace[267782072] transaction","detail":"{read_only:false; response_revision:13702; number_of_response:1; }","duration":"552.559304ms","start":"2026-08-22T11:00:37.108909Z","end":"2026-08-22T11:00:37.661468Z","steps":["trace[267782072] 'process raft request' (duration: 552.205256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:00:37.661566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.66236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:00:37.661622Z","caller":"traceutil/trace.go:171","msg":"trace[953259793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13702; }","duration":"496.734502ms","start":"2026-08-22T11:00:37.164872Z","end":"2026-08-22T11:00:37.661606Z","steps":["trace[953259793] 'agreement among raft nodes before linearized reading' (duration: 496.599478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:00:37.661676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:00:37.16481Z","time spent":"496.856064ms","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T11:00:37.661717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:00:37.108891Z","time spent":"552.717309ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:00:37.683912Z","caller":"traceutil/trace.go:171","msg":"trace[1462920271] transaction","detail":"{read_only:false; response_revision:13703; number_of_response:1; }","duration":"478.445722ms","start":"2026-08-22T11:00:37.20542Z","end":"2026-08-22T11:00:37.683866Z","steps":["trace[1462920271] 'process raft request' (duration: 478.159985ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:37.684257Z","caller":"traceutil/trace.go:171","msg":"trace[990267425] transaction","detail":"{read_only:false; response_revision:13704; number_of_response:1; }","duration":"478.707758ms","start":"2026-08-22T11:00:37.205518Z","end":"2026-08-22T11:00:37.684226Z","steps":["trace[990267425] 'process raft request' (duration: 478.289788ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:37.684351Z","caller":"traceutil/trace.go:171","msg":"trace[1002170580] transaction","detail":"{read_only:false; response_revision:13705; number_of_response:1; }","duration":"472.623072ms","start":"2026-08-22T11:00:37.211697Z","end":"2026-08-22T11:00:37.684321Z","steps":["trace[1002170580] 'process raft request' (duration: 472.331035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:00:37.684326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:00:37.205398Z","time spent":"478.613196ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:00:37.684523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:00:37.21166Z","time spent":"472.768525ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:00:37.684543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:00:37.205502Z","time spent":"478.873282ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:00:37.696446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.672101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-smv4p\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-08-22T11:00:37.696466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.349138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T11:00:37.696509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.067077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-08-22T11:00:37.696671Z","caller":"traceutil/trace.go:171","msg":"trace[856446495] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:13705; }","duration":"531.272172ms","start":"2026-08-22T11:00:37.165379Z","end":"2026-08-22T11:00:37.696651Z","steps":["trace[856446495] 'agreement among raft nodes before linearized reading' (duration: 530.922724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:00:37.696622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.005891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-22T11:00:37.696786Z","caller":"traceutil/trace.go:171","msg":"trace[1148726627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13705; }","duration":"483.203285ms","start":"2026-08-22T11:00:37.213567Z","end":"2026-08-22T11:00:37.69677Z","steps":["trace[1148726627] 'agreement among raft nodes before linearized reading' (duration: 482.866917ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:37.696646Z","caller":"traceutil/trace.go:171","msg":"trace[1859936413] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:13705; }","duration":"261.520282ms","start":"2026-08-22T11:00:37.435065Z","end":"2026-08-22T11:00:37.696585Z","steps":["trace[1859936413] 'agreement among raft nodes before linearized reading' (duration: 261.330257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:00:37.696866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:00:37.213549Z","time spent":"483.305579ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T11:00:37.696491Z","caller":"traceutil/trace.go:171","msg":"trace[1876948504] range","detail":"{range_begin:/registry/pods/openstack/keepalived-smv4p; range_end:; response_count:1; response_revision:13705; }","duration":"127.760943ms","start":"2026-08-22T11:00:37.568719Z","end":"2026-08-22T11:00:37.69648Z","steps":["trace[1876948504] 'agreement among raft nodes before linearized reading' (duration: 127.60432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:00:37.696726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:00:37.165372Z","time spent":"531.340344ms","remote":"127.0.0.1:42058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-08-22T11:00:47.327351Z","caller":"traceutil/trace.go:171","msg":"trace[652010769] linearizableReadLoop","detail":"{readStateIndex:14340; appliedIndex:14339; }","duration":"161.606458ms","start":"2026-08-22T11:00:47.165713Z","end":"2026-08-22T11:00:47.32732Z","steps":["trace[652010769] 'read index received' (duration: 161.426234ms)","trace[652010769] 'applied index is now lower than readState.Index' (duration: 179.144µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:00:47.327463Z","caller":"traceutil/trace.go:171","msg":"trace[2010961484] transaction","detail":"{read_only:false; response_revision:13866; number_of_response:1; }","duration":"166.946157ms","start":"2026-08-22T11:00:47.160484Z","end":"2026-08-22T11:00:47.32743Z","steps":["trace[2010961484] 'process raft request' (duration: 166.66672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:00:47.327651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.888405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:00:47.32779Z","caller":"traceutil/trace.go:171","msg":"trace[989876311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13866; }","duration":"162.072418ms","start":"2026-08-22T11:00:47.165681Z","end":"2026-08-22T11:00:47.327754Z","steps":["trace[989876311] 'agreement among raft nodes before linearized reading' (duration: 161.797562ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:01:50.454183Z","caller":"traceutil/trace.go:171","msg":"trace[922035879] transaction","detail":"{read_only:false; response_revision:14755; number_of_response:1; }","duration":"101.5308ms","start":"2026-08-22T11:01:50.352613Z","end":"2026-08-22T11:01:50.454144Z","steps":["trace[922035879] 'process raft request' (duration: 101.288053ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.218959Z","caller":"traceutil/trace.go:171","msg":"trace[1733748312] transaction","detail":"{read_only:false; response_revision:14834; number_of_response:1; }","duration":"235.976418ms","start":"2026-08-22T11:02:03.982951Z","end":"2026-08-22T11:02:04.218927Z","steps":["trace[1733748312] 'process raft request' (duration: 235.807304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.704188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.950557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T11:02:04.704481Z","caller":"traceutil/trace.go:171","msg":"trace[264746909] transaction","detail":"{read_only:false; response_revision:14835; number_of_response:1; }","duration":"659.273366ms","start":"2026-08-22T11:02:04.04517Z","end":"2026-08-22T11:02:04.704444Z","steps":["trace[264746909] 'process raft request' (duration: 387.922819ms)","trace[264746909] 'compare' (duration: 270.471646ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:02:04.704628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:04.045138Z","time spent":"659.401439ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:02:04.705155Z","caller":"traceutil/trace.go:171","msg":"trace[1152387006] linearizableReadLoop","detail":"{readStateIndex:15339; appliedIndex:15335; }","duration":"560.996596ms","start":"2026-08-22T11:02:04.144125Z","end":"2026-08-22T11:02:04.705122Z","steps":["trace[1152387006] 'read index received' (duration: 74.616292ms)","trace[1152387006] 'applied index is now lower than readState.Index' (duration: 486.379474ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:02:04.705203Z","caller":"traceutil/trace.go:171","msg":"trace[1390285189] transaction","detail":"{read_only:false; response_revision:14837; number_of_response:1; }","duration":"627.875012ms","start":"2026-08-22T11:02:04.077284Z","end":"2026-08-22T11:02:04.705159Z","steps":["trace[1390285189] 'process raft request' (duration: 627.748829ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.705254Z","caller":"traceutil/trace.go:171","msg":"trace[1879250959] transaction","detail":"{read_only:false; response_revision:14836; number_of_response:1; }","duration":"653.787385ms","start":"2026-08-22T11:02:04.05139Z","end":"2026-08-22T11:02:04.705177Z","steps":["trace[1879250959] 'process raft request' (duration: 652.907673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.705376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.880163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-08-22T11:02:04.705407Z","caller":"traceutil/trace.go:171","msg":"trace[1567185264] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14837; }","duration":"483.981215ms","start":"2026-08-22T11:02:04.221416Z","end":"2026-08-22T11:02:04.705397Z","steps":["trace[1567185264] 'agreement among raft nodes before linearized reading' (duration: 483.844482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.705436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:04.22139Z","time spent":"484.038857ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-22T11:02:04.705409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.326694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-22T11:02:04.705508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:04.077263Z","time spent":"628.173488ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:02:04.705524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.908709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6hcpl\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-08-22T11:02:04.705544Z","caller":"traceutil/trace.go:171","msg":"trace[276995109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14837; }","duration":"561.526288ms","start":"2026-08-22T11:02:04.143985Z","end":"2026-08-22T11:02:04.705512Z","steps":["trace[276995109] 'agreement among raft nodes before linearized reading' (duration: 561.231271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.705574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:04.05138Z","time spent":"654.025591ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:02:04.705615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.319464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T11:02:04.705613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:04.143949Z","time spent":"561.648961ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T11:02:04.705638Z","caller":"traceutil/trace.go:171","msg":"trace[1667257349] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14837; }","duration":"469.378505ms","start":"2026-08-22T11:02:04.236254Z","end":"2026-08-22T11:02:04.705632Z","steps":["trace[1667257349] 'agreement among raft nodes before linearized reading' (duration: 469.338554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.70566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:04.236237Z","time spent":"469.419246ms","remote":"127.0.0.1:42174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-22T11:02:04.705662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.861901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:02:04.705704Z","caller":"traceutil/trace.go:171","msg":"trace[1062620949] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14837; }","duration":"189.945912ms","start":"2026-08-22T11:02:04.515746Z","end":"2026-08-22T11:02:04.705692Z","steps":["trace[1062620949] 'agreement among raft nodes before linearized reading' (duration: 189.876981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.705677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.729743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-08-22T11:02:04.705593Z","caller":"traceutil/trace.go:171","msg":"trace[146994266] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6hcpl; range_end:; response_count:1; response_revision:14837; }","duration":"234.031552ms","start":"2026-08-22T11:02:04.471538Z","end":"2026-08-22T11:02:04.70557Z","steps":["trace[146994266] 'agreement among raft nodes before linearized reading' (duration: 233.826637ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.705767Z","caller":"traceutil/trace.go:171","msg":"trace[1042812005] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14837; }","duration":"464.852376ms","start":"2026-08-22T11:02:04.240894Z","end":"2026-08-22T11:02:04.705746Z","steps":["trace[1042812005] 'agreement among raft nodes before linearized reading' (duration: 464.549399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.705721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.82193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-22T11:02:04.705813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:04.240884Z","time spent":"464.920417ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-22T11:02:04.705851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.978601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-22T11:02:04.705867Z","caller":"traceutil/trace.go:171","msg":"trace[1533508450] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14837; }","duration":"189.989334ms","start":"2026-08-22T11:02:04.515836Z","end":"2026-08-22T11:02:04.705826Z","steps":["trace[1533508450] 'agreement among raft nodes before linearized reading' (duration: 189.522223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.705896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.813858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:02:04.705906Z","caller":"traceutil/trace.go:171","msg":"trace[1599322943] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14837; }","duration":"458.100514ms","start":"2026-08-22T11:02:04.247789Z","end":"2026-08-22T11:02:04.70589Z","steps":["trace[1599322943] 'agreement among raft nodes before linearized reading' (duration: 457.948181ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.705921Z","caller":"traceutil/trace.go:171","msg":"trace[1279021295] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14837; }","duration":"191.87165ms","start":"2026-08-22T11:02:04.514044Z","end":"2026-08-22T11:02:04.705916Z","steps":["trace[1279021295] 'agreement among raft nodes before linearized reading' (duration: 191.832379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.705957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:04.24774Z","time spent":"458.206797ms","remote":"127.0.0.1:42174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-08-22T11:02:04.705995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.88152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:02:04.706016Z","caller":"traceutil/trace.go:171","msg":"trace[1089304062] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14837; }","duration":"191.923771ms","start":"2026-08-22T11:02:04.514088Z","end":"2026-08-22T11:02:04.706012Z","steps":["trace[1089304062] 'agreement among raft nodes before linearized reading' (duration: 191.89254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.706151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.992137ms","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":"warn","ts":"2026-08-22T11:02:04.706143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.85604ms","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-08-22T11:02:04.706171Z","caller":"traceutil/trace.go:171","msg":"trace[1594162492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14837; }","duration":"171.028098ms","start":"2026-08-22T11:02:04.535135Z","end":"2026-08-22T11:02:04.706163Z","steps":["trace[1594162492] 'agreement among raft nodes before linearized reading' (duration: 170.971656ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.706178Z","caller":"traceutil/trace.go:171","msg":"trace[1991770568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14837; }","duration":"164.944743ms","start":"2026-08-22T11:02:04.541223Z","end":"2026-08-22T11:02:04.706168Z","steps":["trace[1991770568] 'agreement among raft nodes before linearized reading' (duration: 164.84267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.706221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.487007ms","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-08-22T11:02:04.706354Z","caller":"traceutil/trace.go:171","msg":"trace[1056577350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14837; }","duration":"168.651051ms","start":"2026-08-22T11:02:04.537686Z","end":"2026-08-22T11:02:04.706337Z","steps":["trace[1056577350] 'agreement among raft nodes before linearized reading' (duration: 168.433116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.706392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.547669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T11:02:04.706441Z","caller":"traceutil/trace.go:171","msg":"trace[118049959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14837; }","duration":"178.628881ms","start":"2026-08-22T11:02:04.527801Z","end":"2026-08-22T11:02:04.70643Z","steps":["trace[118049959] 'agreement among raft nodes before linearized reading' (duration: 178.510608ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.835016Z","caller":"traceutil/trace.go:171","msg":"trace[1732295727] transaction","detail":"{read_only:false; response_revision:14839; number_of_response:1; }","duration":"123.475056ms","start":"2026-08-22T11:02:04.711494Z","end":"2026-08-22T11:02:04.834969Z","steps":["trace[1732295727] 'process raft request' (duration: 119.003828ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.835054Z","caller":"traceutil/trace.go:171","msg":"trace[289726290] linearizableReadLoop","detail":"{readStateIndex:15342; appliedIndex:15339; }","duration":"123.275261ms","start":"2026-08-22T11:02:04.711755Z","end":"2026-08-22T11:02:04.83503Z","steps":["trace[289726290] 'read index received' (duration: 52.291116ms)","trace[289726290] 'applied index is now lower than readState.Index' (duration: 70.983025ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:02:04.835195Z","caller":"traceutil/trace.go:171","msg":"trace[1888708629] transaction","detail":"{read_only:false; response_revision:14840; number_of_response:1; }","duration":"123.594579ms","start":"2026-08-22T11:02:04.711583Z","end":"2026-08-22T11:02:04.835177Z","steps":["trace[1888708629] 'process raft request' (duration: 123.280821ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.83541Z","caller":"traceutil/trace.go:171","msg":"trace[384492389] transaction","detail":"{read_only:false; response_revision:14842; number_of_response:1; }","duration":"122.36765ms","start":"2026-08-22T11:02:04.713028Z","end":"2026-08-22T11:02:04.835395Z","steps":["trace[384492389] 'process raft request' (duration: 121.95147ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:04.835441Z","caller":"traceutil/trace.go:171","msg":"trace[1937001036] transaction","detail":"{read_only:false; response_revision:14841; number_of_response:1; }","duration":"123.426545ms","start":"2026-08-22T11:02:04.711937Z","end":"2026-08-22T11:02:04.835364Z","steps":["trace[1937001036] 'process raft request' (duration: 122.992895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.836573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.334747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769648"} {"level":"info","ts":"2026-08-22T11:02:04.836706Z","caller":"traceutil/trace.go:171","msg":"trace[1929073563] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:14842; }","duration":"124.520432ms","start":"2026-08-22T11:02:04.71216Z","end":"2026-08-22T11:02:04.83668Z","steps":["trace[1929073563] 'agreement among raft nodes before linearized reading' (duration: 123.143928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:04.836804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.991362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769648"} {"level":"info","ts":"2026-08-22T11:02:04.83695Z","caller":"traceutil/trace.go:171","msg":"trace[78416320] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:14842; }","duration":"125.193518ms","start":"2026-08-22T11:02:04.711729Z","end":"2026-08-22T11:02:04.836922Z","steps":["trace[78416320] 'agreement among raft nodes before linearized reading' (duration: 123.490907ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:03:49.372359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12991} {"level":"info","ts":"2026-08-22T11:03:49.444163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12991,"took":"68.913746ms","hash":2138317451,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":30851072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:03:49.444215Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2138317451,"revision":12991,"compact-revision":10794} {"level":"info","ts":"2026-08-22T11:05:05.058676Z","caller":"traceutil/trace.go:171","msg":"trace[1922663320] transaction","detail":"{read_only:false; response_revision:16242; number_of_response:1; }","duration":"118.058716ms","start":"2026-08-22T11:05:04.940582Z","end":"2026-08-22T11:05:05.058641Z","steps":["trace[1922663320] 'process raft request' (duration: 117.831181ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:08:10.047645Z","caller":"traceutil/trace.go:171","msg":"trace[1242397141] transaction","detail":"{read_only:false; response_revision:17726; number_of_response:1; }","duration":"283.073758ms","start":"2026-08-22T11:08:09.764541Z","end":"2026-08-22T11:08:10.047614Z","steps":["trace[1242397141] 'process raft request' (duration: 282.915574ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:08:10.100165Z","caller":"traceutil/trace.go:171","msg":"trace[278337639] linearizableReadLoop","detail":"{readStateIndex:18318; appliedIndex:18317; }","duration":"289.892643ms","start":"2026-08-22T11:08:09.810247Z","end":"2026-08-22T11:08:10.10014Z","steps":["trace[278337639] 'read index received' (duration: 238.204571ms)","trace[278337639] 'applied index is now lower than readState.Index' (duration: 51.686982ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:08:10.100495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.237661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fh8fj\" ","response":"range_response_count:1 size:20755"} {"level":"info","ts":"2026-08-22T11:08:10.100822Z","caller":"traceutil/trace.go:171","msg":"trace[275747113] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fh8fj; range_end:; response_count:1; response_revision:17727; }","duration":"290.568959ms","start":"2026-08-22T11:08:09.810231Z","end":"2026-08-22T11:08:10.1008Z","steps":["trace[275747113] 'agreement among raft nodes before linearized reading' (duration: 290.116118ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:08:10.100473Z","caller":"traceutil/trace.go:171","msg":"trace[902968786] transaction","detail":"{read_only:false; response_revision:17727; number_of_response:1; }","duration":"291.531771ms","start":"2026-08-22T11:08:09.808912Z","end":"2026-08-22T11:08:10.100444Z","steps":["trace[902968786] 'process raft request' (duration: 283.538729ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:08:49.378985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15504} {"level":"info","ts":"2026-08-22T11:08:49.483323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15504,"took":"99.323035ms","hash":378802203,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":33341440,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T11:08:49.483391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":378802203,"revision":15504,"compact-revision":12991} {"level":"info","ts":"2026-08-22T11:11:29.193081Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e44ae1545340c14b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:11:29.198864Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T11:11:29.199011Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T11:12:39.321228Z","caller":"traceutil/trace.go:171","msg":"trace[262700322] transaction","detail":"{read_only:false; response_revision:19748; number_of_response:1; }","duration":"133.436305ms","start":"2026-08-22T11:12:39.187758Z","end":"2026-08-22T11:12:39.321194Z","steps":["trace[262700322] 'process raft request' (duration: 84.981072ms)","trace[262700322] 'compare' (duration: 48.252918ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:12:40.430489Z","caller":"traceutil/trace.go:171","msg":"trace[1424139232] linearizableReadLoop","detail":"{readStateIndex:20409; appliedIndex:20408; }","duration":"239.113053ms","start":"2026-08-22T11:12:40.19135Z","end":"2026-08-22T11:12:40.430463Z","steps":["trace[1424139232] 'read index received' (duration: 238.96364ms)","trace[1424139232] 'applied index is now lower than readState.Index' (duration: 148.263µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:12:40.43056Z","caller":"traceutil/trace.go:171","msg":"trace[1277393498] transaction","detail":"{read_only:false; response_revision:19749; number_of_response:1; }","duration":"246.001759ms","start":"2026-08-22T11:12:40.184481Z","end":"2026-08-22T11:12:40.430482Z","steps":["trace[1277393498] 'process raft request' (duration: 245.825525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:12:40.43082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.446751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T11:12:40.430933Z","caller":"traceutil/trace.go:171","msg":"trace[743360714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19749; }","duration":"239.555744ms","start":"2026-08-22T11:12:40.191328Z","end":"2026-08-22T11:12:40.430883Z","steps":["trace[743360714] 'agreement among raft nodes before linearized reading' (duration: 239.257667ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:12:40.574053Z","caller":"traceutil/trace.go:171","msg":"trace[438307301] transaction","detail":"{read_only:false; response_revision:19752; number_of_response:1; }","duration":"139.130762ms","start":"2026-08-22T11:12:40.434891Z","end":"2026-08-22T11:12:40.574022Z","steps":["trace[438307301] 'process raft request' (duration: 139.012809ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:12:40.574117Z","caller":"traceutil/trace.go:171","msg":"trace[313790885] linearizableReadLoop","detail":"{readStateIndex:20414; appliedIndex:20409; }","duration":"108.65008ms","start":"2026-08-22T11:12:40.46545Z","end":"2026-08-22T11:12:40.5741Z","steps":["trace[313790885] 'read index received' (duration: 107.106813ms)","trace[313790885] 'applied index is now lower than readState.Index' (duration: 1.539627ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:12:40.57424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.775162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:12:40.574291Z","caller":"traceutil/trace.go:171","msg":"trace[2067315749] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19754; }","duration":"108.882865ms","start":"2026-08-22T11:12:40.465391Z","end":"2026-08-22T11:12:40.574274Z","steps":["trace[2067315749] 'agreement among raft nodes before linearized reading' (duration: 108.770712ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:12:40.574615Z","caller":"traceutil/trace.go:171","msg":"trace[222768392] transaction","detail":"{read_only:false; response_revision:19753; number_of_response:1; }","duration":"139.387117ms","start":"2026-08-22T11:12:40.435195Z","end":"2026-08-22T11:12:40.574582Z","steps":["trace[222768392] 'process raft request' (duration: 138.772043ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:12:40.574663Z","caller":"traceutil/trace.go:171","msg":"trace[1617215734] transaction","detail":"{read_only:false; response_revision:19754; number_of_response:1; }","duration":"139.275274ms","start":"2026-08-22T11:12:40.435351Z","end":"2026-08-22T11:12:40.574626Z","steps":["trace[1617215734] 'process raft request' (duration: 138.697911ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:12:40.574715Z","caller":"traceutil/trace.go:171","msg":"trace[1847244605] transaction","detail":"{read_only:false; response_revision:19751; number_of_response:1; }","duration":"139.852509ms","start":"2026-08-22T11:12:40.434765Z","end":"2026-08-22T11:12:40.574617Z","steps":["trace[1847244605] 'process raft request' (duration: 139.03344ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:12:40.575158Z","caller":"traceutil/trace.go:171","msg":"trace[1348710677] transaction","detail":"{read_only:false; response_revision:19750; number_of_response:1; }","duration":"140.584677ms","start":"2026-08-22T11:12:40.434275Z","end":"2026-08-22T11:12:40.57486Z","steps":["trace[1348710677] 'process raft request' (duration: 138.255582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:12:40.895592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.274001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:12:40.895687Z","caller":"traceutil/trace.go:171","msg":"trace[774285780] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:19754; }","duration":"163.453255ms","start":"2026-08-22T11:12:40.732214Z","end":"2026-08-22T11:12:40.895667Z","steps":["trace[774285780] 'count revisions from in-memory index tree' (duration: 163.119517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:12:40.896003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.02707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:12:40.896189Z","caller":"traceutil/trace.go:171","msg":"trace[177126615] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:19754; }","duration":"214.249845ms","start":"2026-08-22T11:12:40.681873Z","end":"2026-08-22T11:12:40.896123Z","steps":["trace[177126615] 'count revisions from in-memory index tree' (duration: 213.939208ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:12:41.433451Z","caller":"traceutil/trace.go:171","msg":"trace[926230032] transaction","detail":"{read_only:false; response_revision:19755; number_of_response:1; }","duration":"314.465902ms","start":"2026-08-22T11:12:41.118951Z","end":"2026-08-22T11:12:41.433416Z","steps":["trace[926230032] 'process raft request' (duration: 314.222157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:12:41.433696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:12:41.118922Z","time spent":"314.632366ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:12:41.457465Z","caller":"traceutil/trace.go:171","msg":"trace[674911915] transaction","detail":"{read_only:false; response_revision:19756; number_of_response:1; }","duration":"336.185314ms","start":"2026-08-22T11:12:41.121255Z","end":"2026-08-22T11:12:41.45744Z","steps":["trace[674911915] 'process raft request' (duration: 335.997119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:12:41.457708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:12:41.121232Z","time spent":"336.357648ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:12:41.457767Z","caller":"traceutil/trace.go:171","msg":"trace[1818278078] transaction","detail":"{read_only:false; response_revision:19757; number_of_response:1; }","duration":"130.071495ms","start":"2026-08-22T11:12:41.327665Z","end":"2026-08-22T11:12:41.457736Z","steps":["trace[1818278078] 'process raft request' (duration: 129.727006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:12:41.457822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.499713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:12:41.457691Z","caller":"traceutil/trace.go:171","msg":"trace[994199942] linearizableReadLoop","detail":"{readStateIndex:20416; appliedIndex:20414; }","duration":"319.325109ms","start":"2026-08-22T11:12:41.13831Z","end":"2026-08-22T11:12:41.457635Z","steps":["trace[994199942] 'read index received' (duration: 294.842961ms)","trace[994199942] 'applied index is now lower than readState.Index' (duration: 24.480888ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:12:41.457872Z","caller":"traceutil/trace.go:171","msg":"trace[721034964] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:19757; }","duration":"319.601846ms","start":"2026-08-22T11:12:41.138251Z","end":"2026-08-22T11:12:41.457853Z","steps":["trace[721034964] 'agreement among raft nodes before linearized reading' (duration: 319.508254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:12:41.457912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:12:41.13823Z","time spent":"319.672239ms","remote":"127.0.0.1:52628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:12:41.457998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.071761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:12:41.458057Z","caller":"traceutil/trace.go:171","msg":"trace[381094509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19757; }","duration":"291.169643ms","start":"2026-08-22T11:12:41.166874Z","end":"2026-08-22T11:12:41.458043Z","steps":["trace[381094509] 'agreement among raft nodes before linearized reading' (duration: 291.077251ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:49.38722Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18086} {"level":"info","ts":"2026-08-22T11:13:49.505861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18086,"took":"114.741164ms","hash":1181438221,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:13:49.505971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1181438221,"revision":18086,"compact-revision":15504} {"level":"info","ts":"2026-08-22T11:16:25.320387Z","caller":"traceutil/trace.go:171","msg":"trace[1721319734] transaction","detail":"{read_only:false; response_revision:22141; number_of_response:1; }","duration":"106.368834ms","start":"2026-08-22T11:16:25.213993Z","end":"2026-08-22T11:16:25.320361Z","steps":["trace[1721319734] 'process raft request' (duration: 106.216441ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:25.720425Z","caller":"traceutil/trace.go:171","msg":"trace[440195409] linearizableReadLoop","detail":"{readStateIndex:22871; appliedIndex:22870; }","duration":"181.639722ms","start":"2026-08-22T11:16:25.538753Z","end":"2026-08-22T11:16:25.720393Z","steps":["trace[440195409] 'read index received' (duration: 160.970136ms)","trace[440195409] 'applied index is now lower than readState.Index' (duration: 20.668256ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:16:25.720586Z","caller":"traceutil/trace.go:171","msg":"trace[488680524] transaction","detail":"{read_only:false; response_revision:22142; number_of_response:1; }","duration":"188.681861ms","start":"2026-08-22T11:16:25.531825Z","end":"2026-08-22T11:16:25.720507Z","steps":["trace[488680524] 'process raft request' (duration: 167.928653ms)","trace[488680524] 'compare' (duration: 19.672652ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:16:25.720694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.888979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:16:25.721001Z","caller":"traceutil/trace.go:171","msg":"trace[1805519338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:22142; }","duration":"182.155765ms","start":"2026-08-22T11:16:25.538699Z","end":"2026-08-22T11:16:25.720855Z","steps":["trace[1805519338] 'agreement among raft nodes before linearized reading' (duration: 181.829787ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:18:49.393658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20373} {"level":"info","ts":"2026-08-22T11:18:49.512162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20373,"took":"111.420027ms","hash":2746931495,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":43315200,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-22T11:18:49.51223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2746931495,"revision":20373,"compact-revision":18086} {"level":"info","ts":"2026-08-22T11:23:49.39894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23501} {"level":"info","ts":"2026-08-22T11:23:49.536115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23501,"took":"133.189948ms","hash":3694943536,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":29888512,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:23:49.536183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3694943536,"revision":23501,"compact-revision":20373}