{"level":"warn","ts":"2026-08-22T12:58:10.053742Z","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-22T12:58:10.053884Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-22T12:58:10.053961Z","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-22T12:58:10.053979Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-22T12:58:10.053997Z","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-22T12:58:10.054633Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-08-22T12:58:10.054731Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-22T12:58:10.057635Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.630758ms"} {"level":"info","ts":"2026-08-22T12:58:10.063035Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-08-22T12:58:10.063176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T12:58:10.063209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-08-22T12:58:10.063221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T12:58:10.063241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-08-22T12:58:10.063285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-08-22T12:58:10.074059Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T12:58:10.07699Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T12:58:10.079002Z","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-22T12:58:10.081617Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T12:58:10.081785Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T12:58:10.08193Z","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-22T12:58:10.082121Z","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-22T12:58:10.08217Z","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-22T12:58:10.084245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-08-22T12:58:10.085106Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-22T12:58:10.089037Z","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-22T12:58:10.089165Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-22T12:58:10.089308Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-22T12:58:10.089333Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T12:58:10.089376Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T12:58:10.164369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T12:58:10.16446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T12:58:10.164512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-08-22T12:58:10.164538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-08-22T12:58:10.164563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-22T12:58:10.164581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-08-22T12:58:10.164654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-22T12:58:10.166072Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T12:58:10.166117Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T12:58:10.166215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T12:58:10.166347Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T12:58:10.166412Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T12:58:10.16631Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T12:58:10.167717Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T12:58:10.16784Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T12:58:10.167901Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T12:58:10.170367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T12:58:10.17083Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-08-22T12:58:33.313149Z","caller":"traceutil/trace.go:171","msg":"trace[1501592276] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"182.891083ms","start":"2026-08-22T12:58:33.130225Z","end":"2026-08-22T12:58:33.313116Z","steps":["trace[1501592276] 'process raft request' (duration: 144.917298ms)","trace[1501592276] 'compare' (duration: 37.360754ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:58:52.95875Z","caller":"traceutil/trace.go:171","msg":"trace[880063907] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"114.099701ms","start":"2026-08-22T12:58:52.844601Z","end":"2026-08-22T12:58:52.958701Z","steps":["trace[880063907] 'process raft request' (duration: 108.47283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:58:53.129843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.354447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T12:58:53.129998Z","caller":"traceutil/trace.go:171","msg":"trace[155274277] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"115.482615ms","start":"2026-08-22T12:58:53.014495Z","end":"2026-08-22T12:58:53.129977Z","steps":["trace[155274277] 'compare' (duration: 108.173754ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:58:53.823409Z","caller":"traceutil/trace.go:171","msg":"trace[1514667646] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"257.422799ms","start":"2026-08-22T12:58:53.565949Z","end":"2026-08-22T12:58:53.823371Z","steps":["trace[1514667646] 'read index received' (duration: 256.265308ms)","trace[1514667646] 'applied index is now lower than readState.Index' (duration: 1.155921ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:58:53.823779Z","caller":"traceutil/trace.go:171","msg":"trace[1249569969] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"274.11465ms","start":"2026-08-22T12:58:53.549649Z","end":"2026-08-22T12:58:53.823764Z","steps":["trace[1249569969] 'process raft request' (duration: 272.916758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:58:53.824115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.154702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T12:58:53.824227Z","caller":"traceutil/trace.go:171","msg":"trace[1601039382] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:631; }","duration":"258.333525ms","start":"2026-08-22T12:58:53.565869Z","end":"2026-08-22T12:58:53.824202Z","steps":["trace[1601039382] 'agreement among raft nodes before linearized reading' (duration: 258.123651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:58:53.824239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.277698ms","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-22T12:58:53.824264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.737963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cmrjl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-22T12:58:53.824309Z","caller":"traceutil/trace.go:171","msg":"trace[61537126] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cmrjl; range_end:; response_count:1; response_revision:631; }","duration":"253.827994ms","start":"2026-08-22T12:58:53.570469Z","end":"2026-08-22T12:58:53.824297Z","steps":["trace[61537126] 'agreement among raft nodes before linearized reading' (duration: 253.529059ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:58:53.824315Z","caller":"traceutil/trace.go:171","msg":"trace[1339298284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"194.4255ms","start":"2026-08-22T12:58:53.629866Z","end":"2026-08-22T12:58:53.824291Z","steps":["trace[1339298284] 'agreement among raft nodes before linearized reading' (duration: 194.169706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:59:08.936003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.967614ms","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-22T12:59:08.936121Z","caller":"traceutil/trace.go:171","msg":"trace[1809081585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"183.152067ms","start":"2026-08-22T12:59:08.752947Z","end":"2026-08-22T12:59:08.936099Z","steps":["trace[1809081585] 'range keys from in-memory index tree' (duration: 182.734079ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:09.128446Z","caller":"traceutil/trace.go:171","msg":"trace[1106110371] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"187.1044ms","start":"2026-08-22T12:59:08.941298Z","end":"2026-08-22T12:59:09.128402Z","steps":["trace[1106110371] 'process raft request' (duration: 186.831755ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:09.12926Z","caller":"traceutil/trace.go:171","msg":"trace[1409261779] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"187.260192ms","start":"2026-08-22T12:59:08.941283Z","end":"2026-08-22T12:59:09.128543Z","steps":["trace[1409261779] 'process raft request' (duration: 186.687122ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:00:53.447696Z","caller":"traceutil/trace.go:171","msg":"trace[1449349315] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2036; }","duration":"177.768516ms","start":"2026-08-22T13:00:53.2699Z","end":"2026-08-22T13:00:53.447669Z","steps":["trace[1449349315] 'read index received' (duration: 177.515162ms)","trace[1449349315] 'applied index is now lower than readState.Index' (duration: 252.554µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:00:53.447753Z","caller":"traceutil/trace.go:171","msg":"trace[1469931105] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"213.22321ms","start":"2026-08-22T13:00:53.234507Z","end":"2026-08-22T13:00:53.44773Z","steps":["trace[1469931105] 'process raft request' (duration: 213.010726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:00:53.447998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.007081ms","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-22T13:00:53.448044Z","caller":"traceutil/trace.go:171","msg":"trace[516513199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1957; }","duration":"178.183874ms","start":"2026-08-22T13:00:53.269848Z","end":"2026-08-22T13:00:53.448032Z","steps":["trace[516513199] 'agreement among raft nodes before linearized reading' (duration: 177.93541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:00:53.723252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.115666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T13:00:53.723535Z","caller":"traceutil/trace.go:171","msg":"trace[106518898] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"239.323224ms","start":"2026-08-22T13:00:53.484154Z","end":"2026-08-22T13:00:53.723478Z","steps":["trace[106518898] 'process raft request' (duration: 24.705009ms)","trace[106518898] 'compare' (duration: 213.989034ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:00:53.723686Z","caller":"traceutil/trace.go:171","msg":"trace[699587453] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"232.071647ms","start":"2026-08-22T13:00:53.491586Z","end":"2026-08-22T13:00:53.723657Z","steps":["trace[699587453] 'process raft request' (duration: 231.779211ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:00:53.723967Z","caller":"traceutil/trace.go:171","msg":"trace[1080949088] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"231.401314ms","start":"2026-08-22T13:00:53.492548Z","end":"2026-08-22T13:00:53.723949Z","steps":["trace[1080949088] 'process raft request' (duration: 230.902085ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:00:53.724331Z","caller":"traceutil/trace.go:171","msg":"trace[1808140670] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"229.6151ms","start":"2026-08-22T13:00:53.494661Z","end":"2026-08-22T13:00:53.724276Z","steps":["trace[1808140670] 'process raft request' (duration: 229.292553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:00:53.724449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.732522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T13:00:53.724506Z","caller":"traceutil/trace.go:171","msg":"trace[1948995870] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:1964; }","duration":"150.831054ms","start":"2026-08-22T13:00:53.57366Z","end":"2026-08-22T13:00:53.724491Z","steps":["trace[1948995870] 'agreement among raft nodes before linearized reading' (duration: 150.664471ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:00:53.724628Z","caller":"traceutil/trace.go:171","msg":"trace[871330669] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"230.64599ms","start":"2026-08-22T13:00:53.49396Z","end":"2026-08-22T13:00:53.724606Z","steps":["trace[871330669] 'process raft request' (duration: 229.738813ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:00:53.724277Z","caller":"traceutil/trace.go:171","msg":"trace[1359535885] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2039; }","duration":"150.497588ms","start":"2026-08-22T13:00:53.573721Z","end":"2026-08-22T13:00:53.724218Z","steps":["trace[1359535885] 'read index received' (duration: 69.991µs)","trace[1359535885] 'applied index is now lower than readState.Index' (duration: 150.426567ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:00:53.93307Z","caller":"traceutil/trace.go:171","msg":"trace[1555463609] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"201.486006ms","start":"2026-08-22T13:00:53.731555Z","end":"2026-08-22T13:00:53.933041Z","steps":["trace[1555463609] 'process raft request' (duration: 201.426425ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:00:53.933178Z","caller":"traceutil/trace.go:171","msg":"trace[2000096876] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"202.25354ms","start":"2026-08-22T13:00:53.730889Z","end":"2026-08-22T13:00:53.933143Z","steps":["trace[2000096876] 'process raft request' (duration: 201.246521ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:00:53.933619Z","caller":"traceutil/trace.go:171","msg":"trace[1046583043] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2045; }","duration":"187.907458ms","start":"2026-08-22T13:00:53.745696Z","end":"2026-08-22T13:00:53.933604Z","steps":["trace[1046583043] 'read index received' (duration: 186.467021ms)","trace[1046583043] 'applied index is now lower than readState.Index' (duration: 1.439107ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:00:53.93364Z","caller":"traceutil/trace.go:171","msg":"trace[1491149351] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"196.831368ms","start":"2026-08-22T13:00:53.736776Z","end":"2026-08-22T13:00:53.933608Z","steps":["trace[1491149351] 'process raft request' (duration: 196.713736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:00:53.933859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.152883ms","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-22T13:00:53.933944Z","caller":"traceutil/trace.go:171","msg":"trace[724755064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1968; }","duration":"188.300296ms","start":"2026-08-22T13:00:53.745625Z","end":"2026-08-22T13:00:53.933926Z","steps":["trace[724755064] 'agreement among raft nodes before linearized reading' (duration: 188.084552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:00:53.934122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.460807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-22T13:00:53.934181Z","caller":"traceutil/trace.go:171","msg":"trace[1504403832] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1968; }","duration":"150.573068ms","start":"2026-08-22T13:00:53.783595Z","end":"2026-08-22T13:00:53.934168Z","steps":["trace[1504403832] 'agreement among raft nodes before linearized reading' (duration: 150.265122ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:01:44.269008Z","caller":"traceutil/trace.go:171","msg":"trace[2032153775] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"153.453609ms","start":"2026-08-22T13:01:44.115515Z","end":"2026-08-22T13:01:44.268969Z","steps":["trace[2032153775] 'process raft request' (duration: 124.968899ms)","trace[2032153775] 'compare' (duration: 28.287596ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:01:50.072349Z","caller":"traceutil/trace.go:171","msg":"trace[1096714544] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"225.350052ms","start":"2026-08-22T13:01:49.846964Z","end":"2026-08-22T13:01:50.072314Z","steps":["trace[1096714544] 'process raft request' (duration: 225.135338ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:02:58.753729Z","caller":"traceutil/trace.go:171","msg":"trace[1299375063] linearizableReadLoop","detail":"{readStateIndex:3000; appliedIndex:2999; }","duration":"233.614104ms","start":"2026-08-22T13:02:58.520074Z","end":"2026-08-22T13:02:58.753688Z","steps":["trace[1299375063] 'read index received' (duration: 143.872101ms)","trace[1299375063] 'applied index is now lower than readState.Index' (duration: 89.740483ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:02:58.753936Z","caller":"traceutil/trace.go:171","msg":"trace[1715106470] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"234.95352ms","start":"2026-08-22T13:02:58.518892Z","end":"2026-08-22T13:02:58.753845Z","steps":["trace[1715106470] 'process raft request' (duration: 145.049953ms)","trace[1715106470] 'compare' (duration: 89.461089ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:02:58.754061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.96167ms","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-22T13:02:58.754121Z","caller":"traceutil/trace.go:171","msg":"trace[1820676308] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2884; }","duration":"234.070013ms","start":"2026-08-22T13:02:58.520039Z","end":"2026-08-22T13:02:58.754109Z","steps":["trace[1820676308] 'agreement among raft nodes before linearized reading' (duration: 233.821708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:02:58.756146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.012907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T13:02:58.756288Z","caller":"traceutil/trace.go:171","msg":"trace[1465729864] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2885; }","duration":"141.236451ms","start":"2026-08-22T13:02:58.615026Z","end":"2026-08-22T13:02:58.756263Z","steps":["trace[1465729864] 'agreement among raft nodes before linearized reading' (duration: 140.500697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:02:58.756247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.104247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T13:02:58.75643Z","caller":"traceutil/trace.go:171","msg":"trace[783395392] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2885; }","duration":"221.314081ms","start":"2026-08-22T13:02:58.535057Z","end":"2026-08-22T13:02:58.756371Z","steps":["trace[783395392] 'agreement among raft nodes before linearized reading' (duration: 219.888694ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:02:58.755013Z","caller":"traceutil/trace.go:171","msg":"trace[503430215] transaction","detail":"{read_only:false; response_revision:2885; number_of_response:1; }","duration":"224.505281ms","start":"2026-08-22T13:02:58.530486Z","end":"2026-08-22T13:02:58.754991Z","steps":["trace[503430215] 'process raft request' (duration: 224.341938ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:02:58.948444Z","caller":"traceutil/trace.go:171","msg":"trace[94804200] transaction","detail":"{read_only:false; response_revision:2886; number_of_response:1; }","duration":"185.310507ms","start":"2026-08-22T13:02:58.763069Z","end":"2026-08-22T13:02:58.94838Z","steps":["trace[94804200] 'process raft request' (duration: 172.127676ms)","trace[94804200] 'compare' (duration: 12.499977ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:02:58.948472Z","caller":"traceutil/trace.go:171","msg":"trace[2131488788] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"183.742628ms","start":"2026-08-22T13:02:58.764697Z","end":"2026-08-22T13:02:58.94844Z","steps":["trace[2131488788] 'process raft request' (duration: 183.508824ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:02:58.948523Z","caller":"traceutil/trace.go:171","msg":"trace[581739980] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"184.065994ms","start":"2026-08-22T13:02:58.764422Z","end":"2026-08-22T13:02:58.948488Z","steps":["trace[581739980] 'process raft request' (duration: 183.729878ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:02:58.948486Z","caller":"traceutil/trace.go:171","msg":"trace[973636151] transaction","detail":"{read_only:false; response_revision:2889; number_of_response:1; }","duration":"179.059009ms","start":"2026-08-22T13:02:58.769403Z","end":"2026-08-22T13:02:58.948462Z","steps":["trace[973636151] 'process raft request' (duration: 178.841405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:06:58.853713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.285949ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T13:07:11.02271Z","caller":"traceutil/trace.go:171","msg":"trace[1143246676] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"131.803985ms","start":"2026-08-22T13:07:10.890868Z","end":"2026-08-22T13:07:11.022672Z","steps":["trace[1143246676] 'process raft request' (duration: 131.601741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:07:11.16531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.102659ms","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-22T13:07:11.165396Z","caller":"traceutil/trace.go:171","msg":"trace[1005545408] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5339; }","duration":"100.237302ms","start":"2026-08-22T13:07:11.065141Z","end":"2026-08-22T13:07:11.165378Z","steps":["trace[1005545408] 'agreement among raft nodes before linearized reading' (duration: 100.011328ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:07:11.165549Z","caller":"traceutil/trace.go:171","msg":"trace[1254742163] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"134.296433ms","start":"2026-08-22T13:07:11.031219Z","end":"2026-08-22T13:07:11.165515Z","steps":["trace[1254742163] 'process raft request' (duration: 133.849164ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:07:11.165954Z","caller":"traceutil/trace.go:171","msg":"trace[718844110] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"141.626982ms","start":"2026-08-22T13:07:11.024255Z","end":"2026-08-22T13:07:11.165882Z","steps":["trace[718844110] 'process raft request' (duration: 58.501076ms)","trace[718844110] 'compare' (duration: 82.147827ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:07:11.836994Z","caller":"traceutil/trace.go:171","msg":"trace[1068371769] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"138.979142ms","start":"2026-08-22T13:07:11.697966Z","end":"2026-08-22T13:07:11.836945Z","steps":["trace[1068371769] 'process raft request' (duration: 97.401308ms)","trace[1068371769] 'compare' (duration: 41.313969ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:07:19.037606Z","caller":"traceutil/trace.go:171","msg":"trace[894115522] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"162.299595ms","start":"2026-08-22T13:07:18.875252Z","end":"2026-08-22T13:07:19.037552Z","steps":["trace[894115522] 'process raft request' (duration: 144.479226ms)","trace[894115522] 'compare' (duration: 17.627626ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:07:30.78683Z","caller":"traceutil/trace.go:171","msg":"trace[1905588642] linearizableReadLoop","detail":"{readStateIndex:5875; appliedIndex:5871; }","duration":"140.822177ms","start":"2026-08-22T13:07:30.64598Z","end":"2026-08-22T13:07:30.786802Z","steps":["trace[1905588642] 'read index received' (duration: 47.781121ms)","trace[1905588642] 'applied index is now lower than readState.Index' (duration: 93.037226ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:07:30.786993Z","caller":"traceutil/trace.go:171","msg":"trace[422104991] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"149.951192ms","start":"2026-08-22T13:07:30.637029Z","end":"2026-08-22T13:07:30.78698Z","steps":["trace[422104991] 'process raft request' (duration: 149.617416ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:07:30.787107Z","caller":"traceutil/trace.go:171","msg":"trace[722670141] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"150.822617ms","start":"2026-08-22T13:07:30.636136Z","end":"2026-08-22T13:07:30.786959Z","steps":["trace[722670141] 'process raft request' (duration: 142.692972ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:07:30.78752Z","caller":"traceutil/trace.go:171","msg":"trace[889655031] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"150.450851ms","start":"2026-08-22T13:07:30.637057Z","end":"2026-08-22T13:07:30.787508Z","steps":["trace[889655031] 'process raft request' (duration: 149.697287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:07:30.78807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.0684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-22T13:07:30.788168Z","caller":"traceutil/trace.go:171","msg":"trace[1186127393] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5662; }","duration":"142.181283ms","start":"2026-08-22T13:07:30.645963Z","end":"2026-08-22T13:07:30.788145Z","steps":["trace[1186127393] 'agreement among raft nodes before linearized reading' (duration: 141.922278ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:08:08.706475Z","caller":"traceutil/trace.go:171","msg":"trace[2051023333] transaction","detail":"{read_only:false; response_revision:5995; number_of_response:1; }","duration":"112.129489ms","start":"2026-08-22T13:08:08.594313Z","end":"2026-08-22T13:08:08.706442Z","steps":["trace[2051023333] 'process raft request' (duration: 111.853904ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:08:10.442829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2950} {"level":"info","ts":"2026-08-22T13:08:10.518208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2950,"took":"73.738656ms","hash":2911863037,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":33910784,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T13:08:10.518311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2911863037,"revision":2950,"compact-revision":-1} {"level":"info","ts":"2026-08-22T13:13:10.452144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6004} {"level":"info","ts":"2026-08-22T13:13:10.550275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6004,"took":"96.367591ms","hash":1434788271,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":21250048,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-08-22T13:13:10.550329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1434788271,"revision":6004,"compact-revision":2950} {"level":"info","ts":"2026-08-22T13:13:21.508574Z","caller":"traceutil/trace.go:171","msg":"trace[2063768618] linearizableReadLoop","detail":"{readStateIndex:8609; appliedIndex:8608; }","duration":"287.889724ms","start":"2026-08-22T13:13:21.220663Z","end":"2026-08-22T13:13:21.508552Z","steps":["trace[2063768618] 'read index received' (duration: 287.774601ms)","trace[2063768618] 'applied index is now lower than readState.Index' (duration: 114.083µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:13:21.508763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.069258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.50882Z","caller":"traceutil/trace.go:171","msg":"trace[1071682019] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8311; }","duration":"288.164419ms","start":"2026-08-22T13:13:21.220645Z","end":"2026-08-22T13:13:21.508809Z","steps":["trace[1071682019] 'agreement among raft nodes before linearized reading' (duration: 288.048477ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.50866Z","caller":"traceutil/trace.go:171","msg":"trace[934952593] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"302.176127ms","start":"2026-08-22T13:13:21.206461Z","end":"2026-08-22T13:13:21.508637Z","steps":["trace[934952593] 'process raft request' (duration: 301.929333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.509452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:13:21.206447Z","time spent":"302.80094ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:13:21.527088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.295846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.527199Z","caller":"traceutil/trace.go:171","msg":"trace[583491333] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8313; }","duration":"306.45737ms","start":"2026-08-22T13:13:21.220722Z","end":"2026-08-22T13:13:21.52718Z","steps":["trace[583491333] 'agreement among raft nodes before linearized reading' (duration: 306.280256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.527244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:13:21.220704Z","time spent":"306.528571ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 "} {"level":"info","ts":"2026-08-22T13:13:21.527729Z","caller":"traceutil/trace.go:171","msg":"trace[395423021] transaction","detail":"{read_only:false; response_revision:8312; number_of_response:1; }","duration":"250.318514ms","start":"2026-08-22T13:13:21.277395Z","end":"2026-08-22T13:13:21.527714Z","steps":["trace[395423021] 'process raft request' (duration: 249.211883ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.527874Z","caller":"traceutil/trace.go:171","msg":"trace[2018938620] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"246.767827ms","start":"2026-08-22T13:13:21.281097Z","end":"2026-08-22T13:13:21.527865Z","steps":["trace[2018938620] 'process raft request' (duration: 245.818589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.527993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.945681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.528049Z","caller":"traceutil/trace.go:171","msg":"trace[1759837269] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8313; }","duration":"173.029264ms","start":"2026-08-22T13:13:21.355007Z","end":"2026-08-22T13:13:21.528037Z","steps":["trace[1759837269] 'agreement among raft nodes before linearized reading' (duration: 172.916682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.890765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.528122Z","caller":"traceutil/trace.go:171","msg":"trace[501212115] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8313; }","duration":"191.949346ms","start":"2026-08-22T13:13:21.336163Z","end":"2026-08-22T13:13:21.528112Z","steps":["trace[501212115] 'agreement among raft nodes before linearized reading' (duration: 191.904045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.420459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.897844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.528289Z","caller":"traceutil/trace.go:171","msg":"trace[15039286] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8313; }","duration":"133.916324ms","start":"2026-08-22T13:13:21.394367Z","end":"2026-08-22T13:13:21.528283Z","steps":["trace[15039286] 'agreement among raft nodes before linearized reading' (duration: 133.885864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.601732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.528305Z","caller":"traceutil/trace.go:171","msg":"trace[1786646500] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8313; }","duration":"282.582982ms","start":"2026-08-22T13:13:21.245708Z","end":"2026-08-22T13:13:21.528291Z","steps":["trace[1786646500] 'agreement among raft nodes before linearized reading' (duration: 282.398508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.52831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.817308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7d59d68775-dbvst.18ce22d2e38c2861\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-08-22T13:13:21.528326Z","caller":"traceutil/trace.go:171","msg":"trace[1402124863] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8313; }","duration":"306.670654ms","start":"2026-08-22T13:13:21.221646Z","end":"2026-08-22T13:13:21.528317Z","steps":["trace[1402124863] 'agreement among raft nodes before linearized reading' (duration: 306.612002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.988396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.531903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.52843Z","caller":"traceutil/trace.go:171","msg":"trace[624616938] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8313; }","duration":"133.16017ms","start":"2026-08-22T13:13:21.395224Z","end":"2026-08-22T13:13:21.528385Z","steps":["trace[624616938] 'agreement among raft nodes before linearized reading' (duration: 132.943805ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.528463Z","caller":"traceutil/trace.go:171","msg":"trace[311365689] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8313; }","duration":"150.580884ms","start":"2026-08-22T13:13:21.377864Z","end":"2026-08-22T13:13:21.528445Z","steps":["trace[311365689] 'agreement among raft nodes before linearized reading' (duration: 150.537394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.704571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.284003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.52801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.042654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.528634Z","caller":"traceutil/trace.go:171","msg":"trace[997187254] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8313; }","duration":"173.722617ms","start":"2026-08-22T13:13:21.354905Z","end":"2026-08-22T13:13:21.528628Z","steps":["trace[997187254] 'agreement among raft nodes before linearized reading' (duration: 173.071944ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.528633Z","caller":"traceutil/trace.go:171","msg":"trace[122347200] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8313; }","duration":"264.389984ms","start":"2026-08-22T13:13:21.264231Z","end":"2026-08-22T13:13:21.528621Z","steps":["trace[122347200] 'agreement among raft nodes before linearized reading' (duration: 264.302732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.869424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.092575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.528712Z","caller":"traceutil/trace.go:171","msg":"trace[492882643] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8313; }","duration":"173.771148ms","start":"2026-08-22T13:13:21.354935Z","end":"2026-08-22T13:13:21.528706Z","steps":["trace[492882643] 'agreement among raft nodes before linearized reading' (duration: 173.096645ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.528709Z","caller":"traceutil/trace.go:171","msg":"trace[502449961] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8313; }","duration":"192.967986ms","start":"2026-08-22T13:13:21.335731Z","end":"2026-08-22T13:13:21.528699Z","steps":["trace[502449961] 'agreement among raft nodes before linearized reading' (duration: 192.877505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.72475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.528582Z","caller":"traceutil/trace.go:171","msg":"trace[1131685230] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8313; }","duration":"192.805683ms","start":"2026-08-22T13:13:21.335732Z","end":"2026-08-22T13:13:21.528538Z","steps":["trace[1131685230] 'agreement among raft nodes before linearized reading' (duration: 192.710091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.951851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.528981Z","caller":"traceutil/trace.go:171","msg":"trace[31134293] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8313; }","duration":"222.030383ms","start":"2026-08-22T13:13:21.306941Z","end":"2026-08-22T13:13:21.528971Z","steps":["trace[31134293] 'agreement among raft nodes before linearized reading' (duration: 221.982532ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.528388Z","caller":"traceutil/trace.go:171","msg":"trace[1224946669] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7d59d68775-dbvst.18ce22d2e38c2861; range_end:; response_count:1; response_revision:8313; }","duration":"257.914781ms","start":"2026-08-22T13:13:21.270431Z","end":"2026-08-22T13:13:21.528346Z","steps":["trace[1224946669] 'agreement among raft nodes before linearized reading' (duration: 257.663505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.137852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.123555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529068Z","caller":"traceutil/trace.go:171","msg":"trace[1646509982] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8313; }","duration":"283.275076ms","start":"2026-08-22T13:13:21.245769Z","end":"2026-08-22T13:13:21.529044Z","steps":["trace[1646509982] 'agreement among raft nodes before linearized reading' (duration: 283.110583ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.529086Z","caller":"traceutil/trace.go:171","msg":"trace[122097072] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8313; }","duration":"151.634264ms","start":"2026-08-22T13:13:21.37742Z","end":"2026-08-22T13:13:21.529054Z","steps":["trace[122097072] 'agreement among raft nodes before linearized reading' (duration: 151.144165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.753791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529136Z","caller":"traceutil/trace.go:171","msg":"trace[647476204] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8313; }","duration":"134.669039ms","start":"2026-08-22T13:13:21.394462Z","end":"2026-08-22T13:13:21.529131Z","steps":["trace[647476204] 'agreement among raft nodes before linearized reading' (duration: 133.751841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:13:21.221635Z","time spent":"306.716555ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 "} {"level":"warn","ts":"2026-08-22T13:13:21.529159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.765131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.171459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.529152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.827143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529191Z","caller":"traceutil/trace.go:171","msg":"trace[624833048] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8313; }","duration":"112.684108ms","start":"2026-08-22T13:13:21.416501Z","end":"2026-08-22T13:13:21.529185Z","steps":["trace[624833048] 'agreement among raft nodes before linearized reading' (duration: 112.152988ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.52919Z","caller":"traceutil/trace.go:171","msg":"trace[1798083665] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8313; }","duration":"206.809481ms","start":"2026-08-22T13:13:21.322371Z","end":"2026-08-22T13:13:21.52918Z","steps":["trace[1798083665] 'agreement among raft nodes before linearized reading' (duration: 206.7613ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.529204Z","caller":"traceutil/trace.go:171","msg":"trace[962721705] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8313; }","duration":"238.922756ms","start":"2026-08-22T13:13:21.29027Z","end":"2026-08-22T13:13:21.529193Z","steps":["trace[962721705] 'agreement among raft nodes before linearized reading' (duration: 238.853464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.013271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529273Z","caller":"traceutil/trace.go:171","msg":"trace[1951504882] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8313; }","duration":"283.51631ms","start":"2026-08-22T13:13:21.24575Z","end":"2026-08-22T13:13:21.529266Z","steps":["trace[1951504882] 'agreement among raft nodes before linearized reading' (duration: 283.00453ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.528793Z","caller":"traceutil/trace.go:171","msg":"trace[1496261164] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8313; }","duration":"112.799192ms","start":"2026-08-22T13:13:21.41598Z","end":"2026-08-22T13:13:21.528779Z","steps":["trace[1496261164] 'agreement among raft nodes before linearized reading' (duration: 112.747051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.529337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.199078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.262967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.370493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529379Z","caller":"traceutil/trace.go:171","msg":"trace[1751247276] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8313; }","duration":"152.049272ms","start":"2026-08-22T13:13:21.377325Z","end":"2026-08-22T13:13:21.529374Z","steps":["trace[1751247276] 'agreement among raft nodes before linearized reading' (duration: 151.268747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.529379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.410291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.529395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.080588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529405Z","caller":"traceutil/trace.go:171","msg":"trace[1009240018] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8313; }","duration":"265.984144ms","start":"2026-08-22T13:13:21.263408Z","end":"2026-08-22T13:13:21.529393Z","steps":["trace[1009240018] 'agreement among raft nodes before linearized reading' (duration: 265.406753ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.52942Z","caller":"traceutil/trace.go:171","msg":"trace[1342397847] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8313; }","duration":"239.1357ms","start":"2026-08-22T13:13:21.290277Z","end":"2026-08-22T13:13:21.529413Z","steps":["trace[1342397847] 'agreement among raft nodes before linearized reading' (duration: 239.094899ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.529419Z","caller":"traceutil/trace.go:171","msg":"trace[875460486] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8313; }","duration":"222.463841ms","start":"2026-08-22T13:13:21.306939Z","end":"2026-08-22T13:13:21.529403Z","steps":["trace[875460486] 'agreement among raft nodes before linearized reading' (duration: 222.418661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.528848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.818972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.529409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529489Z","caller":"traceutil/trace.go:171","msg":"trace[1874151673] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8313; }","duration":"113.492814ms","start":"2026-08-22T13:13:21.415991Z","end":"2026-08-22T13:13:21.529484Z","steps":["trace[1874151673] 'agreement among raft nodes before linearized reading' (duration: 112.827011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.529493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.257367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529504Z","caller":"traceutil/trace.go:171","msg":"trace[1628644582] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8313; }","duration":"207.437283ms","start":"2026-08-22T13:13:21.322053Z","end":"2026-08-22T13:13:21.52949Z","steps":["trace[1628644582] 'agreement among raft nodes before linearized reading' (duration: 207.334621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:21.529327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.458977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:13:21.528873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.467564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T13:13:21.529544Z","caller":"traceutil/trace.go:171","msg":"trace[356924467] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8313; }","duration":"238.699861ms","start":"2026-08-22T13:13:21.290838Z","end":"2026-08-22T13:13:21.529538Z","steps":["trace[356924467] 'agreement among raft nodes before linearized reading' (duration: 238.443236ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.529368Z","caller":"traceutil/trace.go:171","msg":"trace[1391424381] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8313; }","duration":"207.230809ms","start":"2026-08-22T13:13:21.322128Z","end":"2026-08-22T13:13:21.529359Z","steps":["trace[1391424381] 'agreement among raft nodes before linearized reading' (duration: 207.185898ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.529542Z","caller":"traceutil/trace.go:171","msg":"trace[1567305078] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8313; }","duration":"222.329068ms","start":"2026-08-22T13:13:21.307198Z","end":"2026-08-22T13:13:21.529527Z","steps":["trace[1567305078] 'agreement among raft nodes before linearized reading' (duration: 222.252356ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:21.529564Z","caller":"traceutil/trace.go:171","msg":"trace[57900321] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8313; }","duration":"266.183978ms","start":"2026-08-22T13:13:21.26337Z","end":"2026-08-22T13:13:21.529554Z","steps":["trace[57900321] 'agreement among raft nodes before linearized reading' (duration: 265.483725ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:33.677456Z","caller":"traceutil/trace.go:171","msg":"trace[1408618383] transaction","detail":"{read_only:false; response_revision:8378; number_of_response:1; }","duration":"130.02564ms","start":"2026-08-22T13:13:33.547389Z","end":"2026-08-22T13:13:33.677415Z","steps":["trace[1408618383] 'process raft request' (duration: 129.732725ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:33.81979Z","caller":"traceutil/trace.go:171","msg":"trace[1381256441] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"245.589253ms","start":"2026-08-22T13:13:33.57417Z","end":"2026-08-22T13:13:33.81976Z","steps":["trace[1381256441] 'process raft request' (duration: 245.376489ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:13:33.819987Z","caller":"traceutil/trace.go:171","msg":"trace[1985604835] linearizableReadLoop","detail":"{readStateIndex:8681; appliedIndex:8678; }","duration":"207.150208ms","start":"2026-08-22T13:13:33.612791Z","end":"2026-08-22T13:13:33.819941Z","steps":["trace[1985604835] 'read index received' (duration: 64.267761ms)","trace[1985604835] 'applied index is now lower than readState.Index' (duration: 142.879667ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:13:33.820067Z","caller":"traceutil/trace.go:171","msg":"trace[1854402509] transaction","detail":"{read_only:false; response_revision:8380; number_of_response:1; }","duration":"240.771542ms","start":"2026-08-22T13:13:33.579262Z","end":"2026-08-22T13:13:33.820034Z","steps":["trace[1854402509] 'process raft request' (duration: 240.454506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:13:33.820295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.491804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T13:13:33.820352Z","caller":"traceutil/trace.go:171","msg":"trace[1148778544] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:8380; }","duration":"207.646887ms","start":"2026-08-22T13:13:33.612691Z","end":"2026-08-22T13:13:33.820338Z","steps":["trace[1148778544] 'agreement among raft nodes before linearized reading' (duration: 207.388292ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:14:02.752712Z","caller":"traceutil/trace.go:171","msg":"trace[957254224] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"147.693078ms","start":"2026-08-22T13:14:02.604997Z","end":"2026-08-22T13:14:02.75269Z","steps":["trace[957254224] 'process raft request' (duration: 147.592166ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:14:02.752799Z","caller":"traceutil/trace.go:171","msg":"trace[85130106] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"109.755582ms","start":"2026-08-22T13:14:02.643012Z","end":"2026-08-22T13:14:02.752767Z","steps":["trace[85130106] 'process raft request' (duration: 109.65281ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:14:02.752925Z","caller":"traceutil/trace.go:171","msg":"trace[1522466076] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"149.459902ms","start":"2026-08-22T13:14:02.603459Z","end":"2026-08-22T13:14:02.752918Z","steps":["trace[1522466076] 'process raft request' (duration: 146.262701ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:14:15.141753Z","caller":"traceutil/trace.go:171","msg":"trace[197055082] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"123.457025ms","start":"2026-08-22T13:14:15.018264Z","end":"2026-08-22T13:14:15.141721Z","steps":["trace[197055082] 'process raft request' (duration: 120.448437ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:14:16.525819Z","caller":"traceutil/trace.go:171","msg":"trace[1835289209] linearizableReadLoop","detail":"{readStateIndex:9022; appliedIndex:9021; }","duration":"177.071681ms","start":"2026-08-22T13:14:16.348724Z","end":"2026-08-22T13:14:16.525796Z","steps":["trace[1835289209] 'read index received' (duration: 176.922648ms)","trace[1835289209] 'applied index is now lower than readState.Index' (duration: 147.803µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:14:16.525889Z","caller":"traceutil/trace.go:171","msg":"trace[583777083] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"180.60303ms","start":"2026-08-22T13:14:16.345247Z","end":"2026-08-22T13:14:16.52585Z","steps":["trace[583777083] 'process raft request' (duration: 180.387096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:14:16.526078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.333436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tdqbl\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-22T13:14:16.526126Z","caller":"traceutil/trace.go:171","msg":"trace[1769012854] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tdqbl; range_end:; response_count:1; response_revision:8707; }","duration":"177.427498ms","start":"2026-08-22T13:14:16.348684Z","end":"2026-08-22T13:14:16.526112Z","steps":["trace[1769012854] 'agreement among raft nodes before linearized reading' (duration: 177.251015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:14:16.526099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.343238ms","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:7"} {"level":"info","ts":"2026-08-22T13:14:16.526189Z","caller":"traceutil/trace.go:171","msg":"trace[882190296] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8707; }","duration":"163.46842ms","start":"2026-08-22T13:14:16.362701Z","end":"2026-08-22T13:14:16.52617Z","steps":["trace[882190296] 'agreement among raft nodes before linearized reading' (duration: 163.304157ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:14:29.147011Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023bb.wal"} {"level":"info","ts":"2026-08-22T13:16:00.063728Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T13:16:00.070629Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T13:16:00.070878Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T13:18:10.458622Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8120} {"level":"info","ts":"2026-08-22T13:18:10.526011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8120,"took":"65.005438ms","hash":151243220,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":29921280,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T13:18:10.526077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":151243220,"revision":8120,"compact-revision":6004} {"level":"info","ts":"2026-08-22T13:18:52.149183Z","caller":"traceutil/trace.go:171","msg":"trace[1725444886] transaction","detail":"{read_only:false; response_revision:11167; number_of_response:1; }","duration":"103.586386ms","start":"2026-08-22T13:18:52.045565Z","end":"2026-08-22T13:18:52.149152Z","steps":["trace[1725444886] 'process raft request' (duration: 103.360862ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:18:52.149642Z","caller":"traceutil/trace.go:171","msg":"trace[809815770] transaction","detail":"{read_only:false; response_revision:11168; number_of_response:1; }","duration":"101.410413ms","start":"2026-08-22T13:18:52.048215Z","end":"2026-08-22T13:18:52.149625Z","steps":["trace[809815770] 'process raft request' (duration: 101.340852ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:19:13.791033Z","caller":"traceutil/trace.go:171","msg":"trace[1583526180] transaction","detail":"{read_only:false; response_revision:11289; number_of_response:1; }","duration":"133.80559ms","start":"2026-08-22T13:19:13.657192Z","end":"2026-08-22T13:19:13.790998Z","steps":["trace[1583526180] 'process raft request' (duration: 109.203525ms)","trace[1583526180] 'compare' (duration: 24.465823ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:23:10.465666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10726} {"level":"info","ts":"2026-08-22T13:23:10.550852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10726,"took":"81.045447ms","hash":1637307809,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":27901952,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T13:23:10.550916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1637307809,"revision":10726,"compact-revision":8120} {"level":"info","ts":"2026-08-22T13:25:47.755954Z","caller":"traceutil/trace.go:171","msg":"trace[91914837] transaction","detail":"{read_only:false; response_revision:13925; number_of_response:1; }","duration":"274.489205ms","start":"2026-08-22T13:25:47.481427Z","end":"2026-08-22T13:25:47.755916Z","steps":["trace[91914837] 'process raft request' (duration: 274.137078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:25:56.387063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.434511ms","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-22T13:25:56.38681Z","caller":"traceutil/trace.go:171","msg":"trace[984551902] transaction","detail":"{read_only:false; response_revision:14083; number_of_response:1; }","duration":"112.672906ms","start":"2026-08-22T13:25:56.274074Z","end":"2026-08-22T13:25:56.386746Z","steps":["trace[984551902] 'process raft request' (duration: 109.949393ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:25:56.387183Z","caller":"traceutil/trace.go:171","msg":"trace[255551146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14084; }","duration":"111.568194ms","start":"2026-08-22T13:25:56.275571Z","end":"2026-08-22T13:25:56.387139Z","steps":["trace[255551146] 'agreement among raft nodes before linearized reading' (duration: 111.244948ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:27:35.023395Z","caller":"traceutil/trace.go:171","msg":"trace[485831723] linearizableReadLoop","detail":"{readStateIndex:15687; appliedIndex:15686; }","duration":"150.785808ms","start":"2026-08-22T13:27:34.872577Z","end":"2026-08-22T13:27:35.023363Z","steps":["trace[485831723] 'read index received' (duration: 150.590984ms)","trace[485831723] 'applied index is now lower than readState.Index' (duration: 193.274µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:27:35.023582Z","caller":"traceutil/trace.go:171","msg":"trace[1896651771] transaction","detail":"{read_only:false; response_revision:15168; number_of_response:1; }","duration":"154.811234ms","start":"2026-08-22T13:27:34.868702Z","end":"2026-08-22T13:27:35.023513Z","steps":["trace[1896651771] 'process raft request' (duration: 154.366856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:27:35.023811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.209315ms","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-22T13:27:35.023898Z","caller":"traceutil/trace.go:171","msg":"trace[2082991932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15168; }","duration":"151.337898ms","start":"2026-08-22T13:27:34.872543Z","end":"2026-08-22T13:27:35.023881Z","steps":["trace[2082991932] 'agreement among raft nodes before linearized reading' (duration: 150.971481ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:27:35.177688Z","caller":"traceutil/trace.go:171","msg":"trace[499025079] transaction","detail":"{read_only:false; response_revision:15169; number_of_response:1; }","duration":"301.390442ms","start":"2026-08-22T13:27:34.87625Z","end":"2026-08-22T13:27:35.177641Z","steps":["trace[499025079] 'process raft request' (duration: 253.91943ms)","trace[499025079] 'compare' (duration: 47.156416ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:27:35.177935Z","caller":"traceutil/trace.go:171","msg":"trace[1946701263] transaction","detail":"{read_only:false; response_revision:15170; number_of_response:1; }","duration":"294.403707ms","start":"2026-08-22T13:27:34.883491Z","end":"2026-08-22T13:27:35.177894Z","steps":["trace[1946701263] 'process raft request' (duration: 294.086191ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:27:35.178039Z","caller":"traceutil/trace.go:171","msg":"trace[1154192478] linearizableReadLoop","detail":"{readStateIndex:15689; appliedIndex:15687; }","duration":"154.515119ms","start":"2026-08-22T13:27:35.023487Z","end":"2026-08-22T13:27:35.178002Z","steps":["trace[1154192478] 'read index received' (duration: 106.749481ms)","trace[1154192478] 'applied index is now lower than readState.Index' (duration: 47.764478ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:27:35.178596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:27:34.876224Z","time spent":"301.603896ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:27:35.178833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.90097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:27:35.178873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.283798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T13:27:35.178935Z","caller":"traceutil/trace.go:171","msg":"trace[1374765747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15171; }","duration":"289.37746ms","start":"2026-08-22T13:27:34.889541Z","end":"2026-08-22T13:27:35.178919Z","steps":["trace[1374765747] 'agreement among raft nodes before linearized reading' (duration: 288.482123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:27:35.178945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.069464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-22T13:27:35.178941Z","caller":"traceutil/trace.go:171","msg":"trace[1237640045] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15171; }","duration":"253.060292ms","start":"2026-08-22T13:27:34.925855Z","end":"2026-08-22T13:27:35.178915Z","steps":["trace[1237640045] 'agreement among raft nodes before linearized reading' (duration: 252.197505ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:27:35.179006Z","caller":"traceutil/trace.go:171","msg":"trace[38276942] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15171; }","duration":"149.182076ms","start":"2026-08-22T13:27:35.029814Z","end":"2026-08-22T13:27:35.178996Z","steps":["trace[38276942] 'agreement among raft nodes before linearized reading' (duration: 148.872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:27:35.17912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.220046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-08-22T13:27:35.17918Z","caller":"traceutil/trace.go:171","msg":"trace[1182490745] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15171; }","duration":"149.316829ms","start":"2026-08-22T13:27:35.029854Z","end":"2026-08-22T13:27:35.179171Z","steps":["trace[1182490745] 'agreement among raft nodes before linearized reading' (duration: 149.183396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:27:35.179265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T13:27:35.179248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.320679ms","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-22T13:27:35.179302Z","caller":"traceutil/trace.go:171","msg":"trace[1308314283] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15171; }","duration":"149.463643ms","start":"2026-08-22T13:27:35.029829Z","end":"2026-08-22T13:27:35.179293Z","steps":["trace[1308314283] 'agreement among raft nodes before linearized reading' (duration: 149.38801ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:27:35.179333Z","caller":"traceutil/trace.go:171","msg":"trace[1186394475] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15171; }","duration":"149.435092ms","start":"2026-08-22T13:27:35.029876Z","end":"2026-08-22T13:27:35.179311Z","steps":["trace[1186394475] 'agreement among raft nodes before linearized reading' (duration: 149.247388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:27:35.179414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.837888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-08-22T13:27:35.179514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.894909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-22T13:27:35.179565Z","caller":"traceutil/trace.go:171","msg":"trace[1100844481] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15171; }","duration":"138.965621ms","start":"2026-08-22T13:27:35.040585Z","end":"2026-08-22T13:27:35.17955Z","steps":["trace[1100844481] 'agreement among raft nodes before linearized reading' (duration: 138.814058ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:27:35.179431Z","caller":"traceutil/trace.go:171","msg":"trace[362653212] transaction","detail":"{read_only:false; response_revision:15171; number_of_response:1; }","duration":"150.730466ms","start":"2026-08-22T13:27:35.028596Z","end":"2026-08-22T13:27:35.179326Z","steps":["trace[362653212] 'process raft request' (duration: 149.250528ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:27:35.179581Z","caller":"traceutil/trace.go:171","msg":"trace[612800009] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15171; }","duration":"291.025952ms","start":"2026-08-22T13:27:34.888502Z","end":"2026-08-22T13:27:35.179528Z","steps":["trace[612800009] 'agreement among raft nodes before linearized reading' (duration: 289.562874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:27:35.179855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.711177ms","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-22T13:27:35.179954Z","caller":"traceutil/trace.go:171","msg":"trace[1974990071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15171; }","duration":"303.830709ms","start":"2026-08-22T13:27:34.8761Z","end":"2026-08-22T13:27:35.179931Z","steps":["trace[1974990071] 'agreement among raft nodes before linearized reading' (duration: 302.016794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:27:35.179999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:27:34.876092Z","time spent":"303.89738ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T13:27:36.306408Z","caller":"traceutil/trace.go:171","msg":"trace[2013822779] transaction","detail":"{read_only:false; response_revision:15174; number_of_response:1; }","duration":"113.364449ms","start":"2026-08-22T13:27:36.193026Z","end":"2026-08-22T13:27:36.306391Z","steps":["trace[2013822779] 'process raft request' (duration: 113.196145ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:28:10.471508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12821} {"level":"info","ts":"2026-08-22T13:28:10.548493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12821,"took":"73.008532ms","hash":3320443018,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":31477760,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T13:28:10.548552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3320443018,"revision":12821,"compact-revision":10726} {"level":"info","ts":"2026-08-22T13:33:10.478754Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15390} {"level":"info","ts":"2026-08-22T13:33:10.58096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15390,"took":"98.705024ms","hash":673755394,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":32759808,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T13:33:10.581015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":673755394,"revision":15390,"compact-revision":12821} {"level":"info","ts":"2026-08-22T13:33:12.571333Z","caller":"traceutil/trace.go:171","msg":"trace[948002413] transaction","detail":"{read_only:false; response_revision:17759; number_of_response:1; }","duration":"137.694657ms","start":"2026-08-22T13:33:12.433608Z","end":"2026-08-22T13:33:12.571303Z","steps":["trace[948002413] 'process raft request' (duration: 137.35129ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:33:18.580408Z","caller":"traceutil/trace.go:171","msg":"trace[1372537586] transaction","detail":"{read_only:false; response_revision:17801; number_of_response:1; }","duration":"263.832493ms","start":"2026-08-22T13:33:18.316555Z","end":"2026-08-22T13:33:18.580387Z","steps":["trace[1372537586] 'process raft request' (duration: 263.66622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:33:19.00671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.791209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:33:19.006917Z","caller":"traceutil/trace.go:171","msg":"trace[1024880387] linearizableReadLoop","detail":"{readStateIndex:18408; appliedIndex:18406; }","duration":"637.637797ms","start":"2026-08-22T13:33:18.369259Z","end":"2026-08-22T13:33:19.006897Z","steps":["trace[1024880387] 'read index received' (duration: 210.937595ms)","trace[1024880387] 'applied index is now lower than readState.Index' (duration: 426.698992ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:33:19.00746Z","caller":"traceutil/trace.go:171","msg":"trace[1599857205] transaction","detail":"{read_only:false; response_revision:17803; number_of_response:1; }","duration":"431.437522ms","start":"2026-08-22T13:33:18.575982Z","end":"2026-08-22T13:33:19.00742Z","steps":["trace[1599857205] 'process raft request' (duration: 430.83686ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:33:19.007514Z","caller":"traceutil/trace.go:171","msg":"trace[619879696] transaction","detail":"{read_only:false; response_revision:17802; number_of_response:1; }","duration":"644.882026ms","start":"2026-08-22T13:33:18.362584Z","end":"2026-08-22T13:33:19.007466Z","steps":["trace[619879696] 'process raft request' (duration: 474.2385ms)","trace[619879696] 'compare' (duration: 169.090926ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:33:19.007564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.29316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T13:33:19.007623Z","caller":"traceutil/trace.go:171","msg":"trace[484658804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17803; }","duration":"638.381281ms","start":"2026-08-22T13:33:18.369224Z","end":"2026-08-22T13:33:19.007606Z","steps":["trace[484658804] 'agreement among raft nodes before linearized reading' (duration: 638.203057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:33:19.00762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:33:18.575947Z","time spent":"431.595476ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:33:19.007667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:33:18.369207Z","time spent":"638.447103ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T13:33:19.007692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:33:18.362562Z","time spent":"645.041859ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:33:19.037953Z","caller":"traceutil/trace.go:171","msg":"trace[1726436421] transaction","detail":"{read_only:false; response_revision:17804; number_of_response:1; }","duration":"448.669811ms","start":"2026-08-22T13:33:18.589254Z","end":"2026-08-22T13:33:19.037924Z","steps":["trace[1726436421] 'process raft request' (duration: 448.504558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:33:19.038135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:33:18.589226Z","time spent":"448.816643ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:33:19.061742Z","caller":"traceutil/trace.go:171","msg":"trace[1094990018] transaction","detail":"{read_only:false; response_revision:17805; number_of_response:1; }","duration":"163.325956ms","start":"2026-08-22T13:33:18.898388Z","end":"2026-08-22T13:33:19.061714Z","steps":["trace[1094990018] 'process raft request' (duration: 163.118112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:33:19.061973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.708589ms","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-22T13:33:19.062041Z","caller":"traceutil/trace.go:171","msg":"trace[709004888] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17806; }","duration":"440.835051ms","start":"2026-08-22T13:33:18.62119Z","end":"2026-08-22T13:33:19.062025Z","steps":["trace[709004888] 'agreement among raft nodes before linearized reading' (duration: 440.667437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:33:19.062047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.940296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-22T13:33:19.062032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.075752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T13:33:19.062084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:33:18.621183Z","time spent":"440.891593ms","remote":"127.0.0.1:48110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-22T13:33:19.062091Z","caller":"traceutil/trace.go:171","msg":"trace[292924234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17806; }","duration":"133.025928ms","start":"2026-08-22T13:33:18.929051Z","end":"2026-08-22T13:33:19.062077Z","steps":["trace[292924234] 'agreement among raft nodes before linearized reading' (duration: 132.883225ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:33:19.062114Z","caller":"traceutil/trace.go:171","msg":"trace[2012502458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17806; }","duration":"141.197744ms","start":"2026-08-22T13:33:18.9209Z","end":"2026-08-22T13:33:19.062098Z","steps":["trace[2012502458] 'agreement among raft nodes before linearized reading' (duration: 141.037561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:33:19.062325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.751084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T13:33:19.062375Z","caller":"traceutil/trace.go:171","msg":"trace[2103270517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17806; }","duration":"141.842916ms","start":"2026-08-22T13:33:18.92052Z","end":"2026-08-22T13:33:19.062363Z","steps":["trace[2103270517] 'agreement among raft nodes before linearized reading' (duration: 141.684973ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:33:19.167358Z","caller":"traceutil/trace.go:171","msg":"trace[348007009] transaction","detail":"{read_only:false; response_revision:17807; number_of_response:1; }","duration":"101.115669ms","start":"2026-08-22T13:33:19.066218Z","end":"2026-08-22T13:33:19.167334Z","steps":["trace[348007009] 'process raft request' (duration: 100.861034ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:33:19.167722Z","caller":"traceutil/trace.go:171","msg":"trace[1177144851] transaction","detail":"{read_only:false; response_revision:17808; number_of_response:1; }","duration":"101.203141ms","start":"2026-08-22T13:33:19.066481Z","end":"2026-08-22T13:33:19.167684Z","steps":["trace[1177144851] 'process raft request' (duration: 100.812833ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:36:25.742606Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T13:36:25.748459Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T13:36:25.748559Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T13:38:10.485643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17726} {"level":"info","ts":"2026-08-22T13:38:10.584737Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17726,"took":"95.071655ms","hash":3090448405,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":34590720,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-22T13:38:10.584788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3090448405,"revision":17726,"compact-revision":15390} {"level":"info","ts":"2026-08-22T13:38:46.810906Z","caller":"traceutil/trace.go:171","msg":"trace[159335939] transaction","detail":"{read_only:false; response_revision:20301; number_of_response:1; }","duration":"181.485563ms","start":"2026-08-22T13:38:46.629392Z","end":"2026-08-22T13:38:46.810878Z","steps":["trace[159335939] 'process raft request' (duration: 138.438312ms)","trace[159335939] 'compare' (duration: 42.902738ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:38:47.148949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.37661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:38:47.149164Z","caller":"traceutil/trace.go:171","msg":"trace[1032959916] transaction","detail":"{read_only:false; response_revision:20302; number_of_response:1; }","duration":"318.202532ms","start":"2026-08-22T13:38:46.830933Z","end":"2026-08-22T13:38:47.149136Z","steps":["trace[1032959916] 'process raft request' (duration: 168.541647ms)","trace[1032959916] 'compare' (duration: 148.86447ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:38:47.149204Z","caller":"traceutil/trace.go:171","msg":"trace[754995205] transaction","detail":"{read_only:false; response_revision:20304; number_of_response:1; }","duration":"315.667074ms","start":"2026-08-22T13:38:46.833519Z","end":"2026-08-22T13:38:47.149186Z","steps":["trace[754995205] 'process raft request' (duration: 315.624523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:38:47.149293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:38:46.833511Z","time spent":"315.748065ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:38:47.149314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:38:46.830917Z","time spent":"318.321505ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:38:47.149509Z","caller":"traceutil/trace.go:171","msg":"trace[133394732] transaction","detail":"{read_only:false; response_revision:20303; number_of_response:1; }","duration":"316.832666ms","start":"2026-08-22T13:38:46.832663Z","end":"2026-08-22T13:38:47.149495Z","steps":["trace[133394732] 'process raft request' (duration: 316.398088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:38:47.149611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:38:46.832647Z","time spent":"316.935587ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:43:10.491338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20112} {"level":"info","ts":"2026-08-22T13:43:10.60283Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20112,"took":"107.262256ms","hash":3398284574,"current-db-size-bytes":59502592,"current-db-size":"60 MB","current-db-size-in-use-bytes":42573824,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-22T13:43:10.602904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3398284574,"revision":20112,"compact-revision":17726} {"level":"info","ts":"2026-08-22T13:46:07.868053Z","caller":"traceutil/trace.go:171","msg":"trace[1960968021] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"108.511147ms","start":"2026-08-22T13:46:07.759517Z","end":"2026-08-22T13:46:07.868028Z","steps":["trace[1960968021] 'process raft request' (duration: 108.358444ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:08.075449Z","caller":"traceutil/trace.go:171","msg":"trace[914611113] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"135.55416ms","start":"2026-08-22T13:46:07.939851Z","end":"2026-08-22T13:46:08.075405Z","steps":["trace[914611113] 'process raft request' (duration: 134.686044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:08.462412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.95151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:46:08.462804Z","caller":"traceutil/trace.go:171","msg":"trace[1413013056] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"520.312937ms","start":"2026-08-22T13:46:07.942457Z","end":"2026-08-22T13:46:08.46277Z","steps":["trace[1413013056] 'process raft request' (duration: 249.875628ms)","trace[1413013056] 'compare' (duration: 269.625314ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:08.462845Z","caller":"traceutil/trace.go:171","msg":"trace[1607288502] linearizableReadLoop","detail":"{readStateIndex:25678; appliedIndex:25675; }","duration":"186.039862ms","start":"2026-08-22T13:46:08.276765Z","end":"2026-08-22T13:46:08.462805Z","steps":["trace[1607288502] 'read index received' (duration: 24.632139ms)","trace[1607288502] 'applied index is now lower than readState.Index' (duration: 161.406373ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:08.46284Z","caller":"traceutil/trace.go:171","msg":"trace[1678597866] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"449.205563ms","start":"2026-08-22T13:46:08.013609Z","end":"2026-08-22T13:46:08.462814Z","steps":["trace[1678597866] 'process raft request' (duration: 449.017669ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:08.462849Z","caller":"traceutil/trace.go:171","msg":"trace[682043544] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"367.964986ms","start":"2026-08-22T13:46:08.094856Z","end":"2026-08-22T13:46:08.462821Z","steps":["trace[682043544] 'process raft request' (duration: 367.881905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:08.462965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:07.942446Z","time spent":"520.428059ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:08.46298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.214925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T13:46:08.462971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:08.013584Z","time spent":"449.322646ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:08.463001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:08.094826Z","time spent":"368.112389ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:08.463018Z","caller":"traceutil/trace.go:171","msg":"trace[722223873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24867; }","duration":"186.291757ms","start":"2026-08-22T13:46:08.276714Z","end":"2026-08-22T13:46:08.463005Z","steps":["trace[722223873] 'agreement among raft nodes before linearized reading' (duration: 186.179884ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:08.605618Z","caller":"traceutil/trace.go:171","msg":"trace[53061238] linearizableReadLoop","detail":"{readStateIndex:25679; appliedIndex:25678; }","duration":"142.698737ms","start":"2026-08-22T13:46:08.462893Z","end":"2026-08-22T13:46:08.605592Z","steps":["trace[53061238] 'read index received' (duration: 141.062977ms)","trace[53061238] 'applied index is now lower than readState.Index' (duration: 1.63486ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:08.60574Z","caller":"traceutil/trace.go:171","msg":"trace[298501535] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"185.875989ms","start":"2026-08-22T13:46:08.419798Z","end":"2026-08-22T13:46:08.605674Z","steps":["trace[298501535] 'process raft request' (duration: 184.210888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:08.605767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.992278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:08.605985Z","caller":"traceutil/trace.go:171","msg":"trace[1951851095] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24868; }","duration":"283.248913ms","start":"2026-08-22T13:46:08.322711Z","end":"2026-08-22T13:46:08.60596Z","steps":["trace[1951851095] 'agreement among raft nodes before linearized reading' (duration: 282.968468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:08.606874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.732408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:08.606962Z","caller":"traceutil/trace.go:171","msg":"trace[21495116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24868; }","duration":"142.84747ms","start":"2026-08-22T13:46:08.464101Z","end":"2026-08-22T13:46:08.606949Z","steps":["trace[21495116] 'agreement among raft nodes before linearized reading' (duration: 142.726958ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:13.408318Z","caller":"traceutil/trace.go:171","msg":"trace[951050122] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"251.01768ms","start":"2026-08-22T13:46:13.15728Z","end":"2026-08-22T13:46:13.408297Z","steps":["trace[951050122] 'process raft request' (duration: 213.037546ms)","trace[951050122] 'compare' (duration: 37.891763ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:14.141431Z","caller":"traceutil/trace.go:171","msg":"trace[1958835430] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"238.530692ms","start":"2026-08-22T13:46:13.902881Z","end":"2026-08-22T13:46:14.141412Z","steps":["trace[1958835430] 'process raft request' (duration: 233.716581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:14.472276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.755995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:46:14.47249Z","caller":"traceutil/trace.go:171","msg":"trace[195588437] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"440.051419ms","start":"2026-08-22T13:46:14.032418Z","end":"2026-08-22T13:46:14.47247Z","steps":["trace[195588437] 'process raft request' (duration: 209.992939ms)","trace[195588437] 'compare' (duration: 229.596731ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:46:14.472561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:14.032395Z","time spent":"440.134271ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:14.472697Z","caller":"traceutil/trace.go:171","msg":"trace[940450691] linearizableReadLoop","detail":"{readStateIndex:25727; appliedIndex:25725; }","duration":"296.947484ms","start":"2026-08-22T13:46:14.17572Z","end":"2026-08-22T13:46:14.472668Z","steps":["trace[940450691] 'read index received' (duration: 66.652108ms)","trace[940450691] 'applied index is now lower than readState.Index' (duration: 230.293226ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:14.472831Z","caller":"traceutil/trace.go:171","msg":"trace[1069564066] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"377.518159ms","start":"2026-08-22T13:46:14.095279Z","end":"2026-08-22T13:46:14.472797Z","steps":["trace[1069564066] 'process raft request' (duration: 377.120651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:14.472901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.220868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T13:46:14.472905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.165059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:14.472978Z","caller":"traceutil/trace.go:171","msg":"trace[2038563848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24914; }","duration":"195.35362ms","start":"2026-08-22T13:46:14.277607Z","end":"2026-08-22T13:46:14.47296Z","steps":["trace[2038563848] 'agreement among raft nodes before linearized reading' (duration: 195.209947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:14.472988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:14.09526Z","time spent":"377.63829ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:14.473012Z","caller":"traceutil/trace.go:171","msg":"trace[970819518] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:24914; }","duration":"297.334972ms","start":"2026-08-22T13:46:14.175653Z","end":"2026-08-22T13:46:14.472988Z","steps":["trace[970819518] 'agreement among raft nodes before linearized reading' (duration: 297.145889ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:14.700569Z","caller":"traceutil/trace.go:171","msg":"trace[456548335] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"216.36823ms","start":"2026-08-22T13:46:14.484182Z","end":"2026-08-22T13:46:14.70055Z","steps":["trace[456548335] 'process raft request' (duration: 216.270208ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:14.700655Z","caller":"traceutil/trace.go:171","msg":"trace[1832119044] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"216.670236ms","start":"2026-08-22T13:46:14.483922Z","end":"2026-08-22T13:46:14.700593Z","steps":["trace[1832119044] 'process raft request' (duration: 216.33984ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:14.700364Z","caller":"traceutil/trace.go:171","msg":"trace[475699682] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"217.050494ms","start":"2026-08-22T13:46:14.48329Z","end":"2026-08-22T13:46:14.700341Z","steps":["trace[475699682] 'process raft request' (duration: 130.680729ms)","trace[475699682] 'compare' (duration: 85.874946ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:14.895973Z","caller":"traceutil/trace.go:171","msg":"trace[1670371556] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"267.642197ms","start":"2026-08-22T13:46:14.628311Z","end":"2026-08-22T13:46:14.895953Z","steps":["trace[1670371556] 'process raft request' (duration: 246.662877ms)","trace[1670371556] 'compare' (duration: 20.486471ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:14.896151Z","caller":"traceutil/trace.go:171","msg":"trace[2107101897] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"236.185737ms","start":"2026-08-22T13:46:14.659921Z","end":"2026-08-22T13:46:14.896106Z","steps":["trace[2107101897] 'process raft request' (duration: 235.714708ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:14.896337Z","caller":"traceutil/trace.go:171","msg":"trace[1744565445] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"246.772209ms","start":"2026-08-22T13:46:14.649557Z","end":"2026-08-22T13:46:14.89633Z","steps":["trace[1744565445] 'process raft request' (duration: 246.046735ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:15.31117Z","caller":"traceutil/trace.go:171","msg":"trace[983369498] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"113.097383ms","start":"2026-08-22T13:46:15.198034Z","end":"2026-08-22T13:46:15.311131Z","steps":["trace[983369498] 'process raft request' (duration: 112.545063ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:15.878619Z","caller":"traceutil/trace.go:171","msg":"trace[1511840402] linearizableReadLoop","detail":"{readStateIndex:25737; appliedIndex:25736; }","duration":"197.46345ms","start":"2026-08-22T13:46:15.681136Z","end":"2026-08-22T13:46:15.8786Z","steps":["trace[1511840402] 'read index received' (duration: 197.333598ms)","trace[1511840402] 'applied index is now lower than readState.Index' (duration: 128.852µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:15.878714Z","caller":"traceutil/trace.go:171","msg":"trace[1950940700] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"206.979602ms","start":"2026-08-22T13:46:15.671704Z","end":"2026-08-22T13:46:15.878684Z","steps":["trace[1950940700] 'process raft request' (duration: 206.769618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:15.878823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.664524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T13:46:15.878845Z","caller":"traceutil/trace.go:171","msg":"trace[111640832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24924; }","duration":"197.711065ms","start":"2026-08-22T13:46:15.681125Z","end":"2026-08-22T13:46:15.878836Z","steps":["trace[111640832] 'agreement among raft nodes before linearized reading' (duration: 197.579693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:15.878924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.648854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T13:46:15.87901Z","caller":"traceutil/trace.go:171","msg":"trace[1804785329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24924; }","duration":"197.770016ms","start":"2026-08-22T13:46:15.681218Z","end":"2026-08-22T13:46:15.878988Z","steps":["trace[1804785329] 'agreement among raft nodes before linearized reading' (duration: 197.527242ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:16.120533Z","caller":"traceutil/trace.go:171","msg":"trace[1293650218] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"233.082708ms","start":"2026-08-22T13:46:15.887429Z","end":"2026-08-22T13:46:16.120511Z","steps":["trace[1293650218] 'process raft request' (duration: 232.921145ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:16.120594Z","caller":"traceutil/trace.go:171","msg":"trace[735060168] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"236.692157ms","start":"2026-08-22T13:46:15.883874Z","end":"2026-08-22T13:46:16.120566Z","steps":["trace[735060168] 'process raft request' (duration: 217.246526ms)","trace[735060168] 'compare' (duration: 18.84356ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:16.120595Z","caller":"traceutil/trace.go:171","msg":"trace[1985154984] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"197.163104ms","start":"2026-08-22T13:46:15.92341Z","end":"2026-08-22T13:46:16.120573Z","steps":["trace[1985154984] 'process raft request' (duration: 197.056942ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:16.120681Z","caller":"traceutil/trace.go:171","msg":"trace[292018372] linearizableReadLoop","detail":"{readStateIndex:25738; appliedIndex:25737; }","duration":"233.389284ms","start":"2026-08-22T13:46:15.887282Z","end":"2026-08-22T13:46:16.120672Z","steps":["trace[292018372] 'read index received' (duration: 213.852042ms)","trace[292018372] 'applied index is now lower than readState.Index' (duration: 19.536412ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:46:16.120782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.485715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:16.120812Z","caller":"traceutil/trace.go:171","msg":"trace[325034769] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24927; }","duration":"233.561657ms","start":"2026-08-22T13:46:15.887241Z","end":"2026-08-22T13:46:16.120802Z","steps":["trace[325034769] 'agreement among raft nodes before linearized reading' (duration: 233.465536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:16.401713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.362148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:16.401796Z","caller":"traceutil/trace.go:171","msg":"trace[114473474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24928; }","duration":"124.49627ms","start":"2026-08-22T13:46:16.27728Z","end":"2026-08-22T13:46:16.401776Z","steps":["trace[114473474] 'range keys from in-memory index tree' (duration: 124.281736ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:16.58414Z","caller":"traceutil/trace.go:171","msg":"trace[944955632] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"104.130462ms","start":"2026-08-22T13:46:16.479943Z","end":"2026-08-22T13:46:16.584073Z","steps":["trace[944955632] 'process raft request' (duration: 103.875518ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:16.913779Z","caller":"traceutil/trace.go:171","msg":"trace[911391937] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"208.226834ms","start":"2026-08-22T13:46:16.705532Z","end":"2026-08-22T13:46:16.913759Z","steps":["trace[911391937] 'process raft request' (duration: 208.090702ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:17.303227Z","caller":"traceutil/trace.go:171","msg":"trace[374236600] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"399.921765ms","start":"2026-08-22T13:46:16.903203Z","end":"2026-08-22T13:46:17.303125Z","steps":["trace[374236600] 'process raft request' (duration: 399.883815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:17.303354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:16.903195Z","time spent":"400.091668ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:17.303626Z","caller":"traceutil/trace.go:171","msg":"trace[693599825] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"597.355565ms","start":"2026-08-22T13:46:16.706262Z","end":"2026-08-22T13:46:17.303618Z","steps":["trace[693599825] 'process raft request' (duration: 596.56181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:17.303675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:16.706254Z","time spent":"597.395535ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:17.303767Z","caller":"traceutil/trace.go:171","msg":"trace[111803867] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"597.047358ms","start":"2026-08-22T13:46:16.706713Z","end":"2026-08-22T13:46:17.30376Z","steps":["trace[111803867] 'process raft request' (duration: 596.255553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:17.303822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:16.706706Z","time spent":"597.082839ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:17.303914Z","caller":"traceutil/trace.go:171","msg":"trace[699066326] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"402.562985ms","start":"2026-08-22T13:46:16.901342Z","end":"2026-08-22T13:46:17.303905Z","steps":["trace[699066326] 'process raft request' (duration: 401.664778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:17.303952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:16.901316Z","time spent":"402.617535ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:17.30404Z","caller":"traceutil/trace.go:171","msg":"trace[1943589101] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"400.933724ms","start":"2026-08-22T13:46:16.9031Z","end":"2026-08-22T13:46:17.304033Z","steps":["trace[1943589101] 'process raft request' (duration: 399.943136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:17.304077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:16.903086Z","time spent":"400.971835ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:17.304627Z","caller":"traceutil/trace.go:171","msg":"trace[1923871519] linearizableReadLoop","detail":"{readStateIndex:25748; appliedIndex:25748; }","duration":"353.000011ms","start":"2026-08-22T13:46:16.951619Z","end":"2026-08-22T13:46:17.304619Z","steps":["trace[1923871519] 'read index received' (duration: 352.997301ms)","trace[1923871519] 'applied index is now lower than readState.Index' (duration: 2.23µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:46:17.304747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.135364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T13:46:17.304772Z","caller":"traceutil/trace.go:171","msg":"trace[1100066768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24935; }","duration":"353.197815ms","start":"2026-08-22T13:46:16.951568Z","end":"2026-08-22T13:46:17.304766Z","steps":["trace[1100066768] 'agreement among raft nodes before linearized reading' (duration: 353.102713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:17.304792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:16.95155Z","time spent":"353.237906ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T13:46:17.330155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.802718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-08-22T13:46:17.330219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.570504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T13:46:17.330287Z","caller":"traceutil/trace.go:171","msg":"trace[111784299] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:24936; }","duration":"153.685446ms","start":"2026-08-22T13:46:17.176578Z","end":"2026-08-22T13:46:17.330264Z","steps":["trace[111784299] 'agreement among raft nodes before linearized reading' (duration: 153.584134ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:17.330281Z","caller":"traceutil/trace.go:171","msg":"trace[22484361] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24936; }","duration":"185.999652ms","start":"2026-08-22T13:46:17.144254Z","end":"2026-08-22T13:46:17.330254Z","steps":["trace[22484361] 'agreement among raft nodes before linearized reading' (duration: 185.601735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:17.591181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.464571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:46:17.591367Z","caller":"traceutil/trace.go:171","msg":"trace[927846702] linearizableReadLoop","detail":"{readStateIndex:25751; appliedIndex:25749; }","duration":"255.640478ms","start":"2026-08-22T13:46:17.335714Z","end":"2026-08-22T13:46:17.591354Z","steps":["trace[927846702] 'read index received' (duration: 111.88486ms)","trace[927846702] 'applied index is now lower than readState.Index' (duration: 143.754068ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:17.591376Z","caller":"traceutil/trace.go:171","msg":"trace[143425897] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"283.5574ms","start":"2026-08-22T13:46:17.30779Z","end":"2026-08-22T13:46:17.591347Z","steps":["trace[143425897] 'process raft request' (duration: 139.828903ms)","trace[143425897] 'compare' (duration: 143.088245ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:46:17.59151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.79656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-22T13:46:17.591545Z","caller":"traceutil/trace.go:171","msg":"trace[1990571998] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24938; }","duration":"255.871452ms","start":"2026-08-22T13:46:17.335668Z","end":"2026-08-22T13:46:17.59154Z","steps":["trace[1990571998] 'agreement among raft nodes before linearized reading' (duration: 255.727379ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:17.591762Z","caller":"traceutil/trace.go:171","msg":"trace[1401179290] transaction","detail":"{read_only:false; response_revision:24938; number_of_response:1; }","duration":"273.254792ms","start":"2026-08-22T13:46:17.318501Z","end":"2026-08-22T13:46:17.591755Z","steps":["trace[1401179290] 'process raft request' (duration: 272.765563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:17.885302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.128419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:17.885373Z","caller":"traceutil/trace.go:171","msg":"trace[133518950] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24938; }","duration":"160.246351ms","start":"2026-08-22T13:46:17.725111Z","end":"2026-08-22T13:46:17.885357Z","steps":["trace[133518950] 'range keys from in-memory index tree' (duration: 160.056247ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:18.391062Z","caller":"traceutil/trace.go:171","msg":"trace[572581011] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"262.239022ms","start":"2026-08-22T13:46:18.12877Z","end":"2026-08-22T13:46:18.391009Z","steps":["trace[572581011] 'process raft request' (duration: 262.013648ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:18.443865Z","caller":"traceutil/trace.go:171","msg":"trace[1422140337] linearizableReadLoop","detail":"{readStateIndex:25754; appliedIndex:25752; }","duration":"245.941354ms","start":"2026-08-22T13:46:18.197899Z","end":"2026-08-22T13:46:18.44384Z","steps":["trace[1422140337] 'read index received' (duration: 192.875113ms)","trace[1422140337] 'applied index is now lower than readState.Index' (duration: 53.065231ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:18.443985Z","caller":"traceutil/trace.go:171","msg":"trace[1730088746] transaction","detail":"{read_only:false; response_revision:24941; number_of_response:1; }","duration":"314.195943ms","start":"2026-08-22T13:46:18.129758Z","end":"2026-08-22T13:46:18.443954Z","steps":["trace[1730088746] 'process raft request' (duration: 313.082902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:18.44415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.241449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T13:46:18.444194Z","caller":"traceutil/trace.go:171","msg":"trace[1840812444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24941; }","duration":"246.429352ms","start":"2026-08-22T13:46:18.197753Z","end":"2026-08-22T13:46:18.444183Z","steps":["trace[1840812444] 'agreement among raft nodes before linearized reading' (duration: 246.225818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:18.444144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:18.129747Z","time spent":"314.307705ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:18.444357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.877647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:18.444504Z","caller":"traceutil/trace.go:171","msg":"trace[1084622346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24941; }","duration":"167.066322ms","start":"2026-08-22T13:46:18.277421Z","end":"2026-08-22T13:46:18.444487Z","steps":["trace[1084622346] 'agreement among raft nodes before linearized reading' (duration: 166.899698ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:19.52531Z","caller":"traceutil/trace.go:171","msg":"trace[875187185] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"216.147156ms","start":"2026-08-22T13:46:19.309139Z","end":"2026-08-22T13:46:19.525286Z","steps":["trace[875187185] 'process raft request' (duration: 215.686067ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:19.525534Z","caller":"traceutil/trace.go:171","msg":"trace[1910733049] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"215.225148ms","start":"2026-08-22T13:46:19.310284Z","end":"2026-08-22T13:46:19.525509Z","steps":["trace[1910733049] 'process raft request' (duration: 214.914392ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:19.525798Z","caller":"traceutil/trace.go:171","msg":"trace[1213064111] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"214.916803ms","start":"2026-08-22T13:46:19.310869Z","end":"2026-08-22T13:46:19.525786Z","steps":["trace[1213064111] 'process raft request' (duration: 214.563375ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:19.52596Z","caller":"traceutil/trace.go:171","msg":"trace[2065265828] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"214.973604ms","start":"2026-08-22T13:46:19.310977Z","end":"2026-08-22T13:46:19.525951Z","steps":["trace[2065265828] 'process raft request' (duration: 214.767459ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:25.728393Z","caller":"traceutil/trace.go:171","msg":"trace[445198266] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"333.309467ms","start":"2026-08-22T13:46:25.395054Z","end":"2026-08-22T13:46:25.728363Z","steps":["trace[445198266] 'process raft request' (duration: 333.058422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:25.728575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.395025Z","time spent":"333.454629ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:26.232831Z","caller":"traceutil/trace.go:171","msg":"trace[375913328] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"687.602523ms","start":"2026-08-22T13:46:25.545198Z","end":"2026-08-22T13:46:26.232801Z","steps":["trace[375913328] 'process raft request' (duration: 687.000521ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:26.232912Z","caller":"traceutil/trace.go:171","msg":"trace[1113855075] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"687.135884ms","start":"2026-08-22T13:46:25.545743Z","end":"2026-08-22T13:46:26.232879Z","steps":["trace[1113855075] 'process raft request' (duration: 686.972591ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:26.232951Z","caller":"traceutil/trace.go:171","msg":"trace[27800927] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"687.178915ms","start":"2026-08-22T13:46:25.54574Z","end":"2026-08-22T13:46:26.232919Z","steps":["trace[27800927] 'process raft request' (duration: 687.084723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.233004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.545169Z","time spent":"687.737846ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:26.232983Z","caller":"traceutil/trace.go:171","msg":"trace[104876712] linearizableReadLoop","detail":"{readStateIndex:25809; appliedIndex:25803; }","duration":"600.953304ms","start":"2026-08-22T13:46:25.632004Z","end":"2026-08-22T13:46:26.232957Z","steps":["trace[104876712] 'read index received' (duration: 96.233913ms)","trace[104876712] 'applied index is now lower than readState.Index' (duration: 504.718321ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:26.233Z","caller":"traceutil/trace.go:171","msg":"trace[908038727] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"685.357821ms","start":"2026-08-22T13:46:25.547602Z","end":"2026-08-22T13:46:26.23296Z","steps":["trace[908038727] 'process raft request' (duration: 685.271249ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:26.233022Z","caller":"traceutil/trace.go:171","msg":"trace[598002331] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"603.577983ms","start":"2026-08-22T13:46:25.629393Z","end":"2026-08-22T13:46:26.232971Z","steps":["trace[598002331] 'process raft request' (duration: 603.517202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.2331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.545688Z","time spent":"687.327968ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:26.233125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.545715Z","time spent":"687.334419ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:26.233158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.547586Z","time spent":"685.487603ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:26.23319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.629364Z","time spent":"603.741767ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:26.233258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.231579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-22T13:46:26.233272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.118397ms","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-22T13:46:26.233297Z","caller":"traceutil/trace.go:171","msg":"trace[1910155564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24994; }","duration":"601.331131ms","start":"2026-08-22T13:46:25.631952Z","end":"2026-08-22T13:46:26.233283Z","steps":["trace[1910155564] 'agreement among raft nodes before linearized reading' (duration: 601.152858ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:26.233301Z","caller":"traceutil/trace.go:171","msg":"trace[1681331889] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24994; }","duration":"308.200769ms","start":"2026-08-22T13:46:25.92509Z","end":"2026-08-22T13:46:26.233291Z","steps":["trace[1681331889] 'agreement among raft nodes before linearized reading' (duration: 308.138508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.233298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.760889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T13:46:26.233331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.925063Z","time spent":"308.26213ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-22T13:46:26.233333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.631936Z","time spent":"601.388872ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T13:46:26.233342Z","caller":"traceutil/trace.go:171","msg":"trace[1246472424] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:24994; }","duration":"340.851441ms","start":"2026-08-22T13:46:25.892479Z","end":"2026-08-22T13:46:26.23333Z","steps":["trace[1246472424] 'agreement among raft nodes before linearized reading' (duration: 340.677608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.233337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.363209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T13:46:26.23337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.892456Z","time spent":"340.907091ms","remote":"127.0.0.1:41352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":76,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-08-22T13:46:26.233398Z","caller":"traceutil/trace.go:171","msg":"trace[347081395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24994; }","duration":"301.452851ms","start":"2026-08-22T13:46:25.93193Z","end":"2026-08-22T13:46:26.233383Z","steps":["trace[347081395] 'agreement among raft nodes before linearized reading' (duration: 301.291728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.233426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:25.931916Z","time spent":"301.504851ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T13:46:26.233543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.248055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:26.233571Z","caller":"traceutil/trace.go:171","msg":"trace[935253461] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24994; }","duration":"282.339786ms","start":"2026-08-22T13:46:25.951223Z","end":"2026-08-22T13:46:26.233563Z","steps":["trace[935253461] 'agreement among raft nodes before linearized reading' (duration: 281.958359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.606289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.487114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:46:26.606564Z","caller":"traceutil/trace.go:171","msg":"trace[1773724149] linearizableReadLoop","detail":"{readStateIndex:25811; appliedIndex:25809; }","duration":"367.960246ms","start":"2026-08-22T13:46:26.238585Z","end":"2026-08-22T13:46:26.606545Z","steps":["trace[1773724149] 'read index received' (duration: 132.088905ms)","trace[1773724149] 'applied index is now lower than readState.Index' (duration: 235.870271ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:26.60671Z","caller":"traceutil/trace.go:171","msg":"trace[1456206668] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"369.100709ms","start":"2026-08-22T13:46:26.237596Z","end":"2026-08-22T13:46:26.606697Z","steps":["trace[1456206668] 'process raft request' (duration: 133.094865ms)","trace[1456206668] 'compare' (duration: 235.355522ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:46:26.606811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.237573Z","time spent":"369.19413ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:26.607059Z","caller":"traceutil/trace.go:171","msg":"trace[496347544] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"369.356853ms","start":"2026-08-22T13:46:26.23767Z","end":"2026-08-22T13:46:26.607027Z","steps":["trace[496347544] 'process raft request' (duration: 368.812403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.6074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.790082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:26.60749Z","caller":"traceutil/trace.go:171","msg":"trace[2023085515] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24996; }","duration":"368.931464ms","start":"2026-08-22T13:46:26.238539Z","end":"2026-08-22T13:46:26.60747Z","steps":["trace[2023085515] 'agreement among raft nodes before linearized reading' (duration: 368.270142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.607559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.238528Z","time spent":"368.992845ms","remote":"127.0.0.1:40454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-08-22T13:46:26.607534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.761124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T13:46:26.607623Z","caller":"traceutil/trace.go:171","msg":"trace[1671805331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24996; }","duration":"126.907436ms","start":"2026-08-22T13:46:26.480694Z","end":"2026-08-22T13:46:26.607602Z","steps":["trace[1671805331] 'agreement among raft nodes before linearized reading' (duration: 126.693832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.607633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.857611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T13:46:26.607718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.237645Z","time spent":"369.573377ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:26.607777Z","caller":"traceutil/trace.go:171","msg":"trace[466199306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24996; }","duration":"329.071316ms","start":"2026-08-22T13:46:26.278687Z","end":"2026-08-22T13:46:26.607759Z","steps":["trace[466199306] 'agreement among raft nodes before linearized reading' (duration: 328.7582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.607923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.278675Z","time spent":"329.230239ms","remote":"127.0.0.1:40268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T13:46:26.607842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.855258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:26.608098Z","caller":"traceutil/trace.go:171","msg":"trace[818671867] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24996; }","duration":"365.150833ms","start":"2026-08-22T13:46:26.242925Z","end":"2026-08-22T13:46:26.608076Z","steps":["trace[818671867] 'agreement among raft nodes before linearized reading' (duration: 364.245905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:26.608193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.242912Z","time spent":"365.249545ms","remote":"127.0.0.1:43922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-22T13:46:26.607947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.995274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T13:46:26.608332Z","caller":"traceutil/trace.go:171","msg":"trace[294660071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24996; }","duration":"184.503093ms","start":"2026-08-22T13:46:26.42381Z","end":"2026-08-22T13:46:26.608313Z","steps":["trace[294660071] 'agreement among raft nodes before linearized reading' (duration: 183.420122ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:27.124796Z","caller":"traceutil/trace.go:171","msg":"trace[974104801] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"509.928569ms","start":"2026-08-22T13:46:26.614826Z","end":"2026-08-22T13:46:27.124755Z","steps":["trace[974104801] 'process raft request' (duration: 509.813197ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:27.124815Z","caller":"traceutil/trace.go:171","msg":"trace[674543200] linearizableReadLoop","detail":"{readStateIndex:25813; appliedIndex:25811; }","duration":"510.208425ms","start":"2026-08-22T13:46:26.614563Z","end":"2026-08-22T13:46:27.124771Z","steps":["trace[674543200] 'read index received' (duration: 38.500403ms)","trace[674543200] 'applied index is now lower than readState.Index' (duration: 471.705822ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:27.124879Z","caller":"traceutil/trace.go:171","msg":"trace[1827654479] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"485.200828ms","start":"2026-08-22T13:46:26.639665Z","end":"2026-08-22T13:46:27.124866Z","steps":["trace[1827654479] 'process raft request' (duration: 485.045306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:27.124971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.614816Z","time spent":"510.057812ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:27.124981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.639641Z","time spent":"485.288431ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:27.124805Z","caller":"traceutil/trace.go:171","msg":"trace[363351353] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"510.873527ms","start":"2026-08-22T13:46:26.613906Z","end":"2026-08-22T13:46:27.12478Z","steps":["trace[363351353] 'process raft request' (duration: 510.461129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:27.125126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.613886Z","time spent":"511.211064ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:27.125161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.5121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:27.125285Z","caller":"traceutil/trace.go:171","msg":"trace[966944050] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25000; }","duration":"510.680913ms","start":"2026-08-22T13:46:26.614556Z","end":"2026-08-22T13:46:27.125237Z","steps":["trace[966944050] 'agreement among raft nodes before linearized reading' (duration: 510.396238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:27.125377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.614524Z","time spent":"510.808986ms","remote":"127.0.0.1:40548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-08-22T13:46:27.238745Z","caller":"traceutil/trace.go:171","msg":"trace[768129697] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25815; }","duration":"113.801877ms","start":"2026-08-22T13:46:27.124907Z","end":"2026-08-22T13:46:27.238709Z","steps":["trace[768129697] 'read index received' (duration: 110.875292ms)","trace[768129697] 'applied index is now lower than readState.Index' (duration: 2.924945ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:27.238781Z","caller":"traceutil/trace.go:171","msg":"trace[1156771821] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"209.956227ms","start":"2026-08-22T13:46:27.028795Z","end":"2026-08-22T13:46:27.238751Z","steps":["trace[1156771821] 'process raft request' (duration: 206.96364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:27.238988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.749943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T13:46:27.238889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.792208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:27.239047Z","caller":"traceutil/trace.go:171","msg":"trace[1202531760] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25001; }","duration":"106.826164ms","start":"2026-08-22T13:46:27.132186Z","end":"2026-08-22T13:46:27.239012Z","steps":["trace[1202531760] 'agreement among raft nodes before linearized reading' (duration: 106.771253ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:27.239051Z","caller":"traceutil/trace.go:171","msg":"trace[2003806343] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:25001; }","duration":"561.018323ms","start":"2026-08-22T13:46:26.678014Z","end":"2026-08-22T13:46:27.239033Z","steps":["trace[2003806343] 'agreement among raft nodes before linearized reading' (duration: 560.798578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:27.239183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:26.677998Z","time spent":"561.096844ms","remote":"127.0.0.1:40676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":30,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-08-22T13:46:33.938695Z","caller":"traceutil/trace.go:171","msg":"trace[654542431] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"186.818606ms","start":"2026-08-22T13:46:33.75183Z","end":"2026-08-22T13:46:33.938649Z","steps":["trace[654542431] 'process raft request' (duration: 186.567161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.19627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:33.791423Z","time spent":"404.839258ms","remote":"127.0.0.1:40286","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-22T13:46:34.196428Z","caller":"traceutil/trace.go:171","msg":"trace[1735164140] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"400.559297ms","start":"2026-08-22T13:46:33.795844Z","end":"2026-08-22T13:46:34.196404Z","steps":["trace[1735164140] 'process raft request' (duration: 400.342073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.196585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:33.795823Z","time spent":"400.672869ms","remote":"127.0.0.1:40440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:34.196663Z","caller":"traceutil/trace.go:171","msg":"trace[223576343] linearizableReadLoop","detail":"{readStateIndex:25930; appliedIndex:25927; }","duration":"211.010658ms","start":"2026-08-22T13:46:33.985621Z","end":"2026-08-22T13:46:34.196632Z","steps":["trace[223576343] 'read index received' (duration: 210.475707ms)","trace[223576343] 'applied index is now lower than readState.Index' (duration: 532.281µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:34.196805Z","caller":"traceutil/trace.go:171","msg":"trace[1833748408] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"344.99464ms","start":"2026-08-22T13:46:33.851798Z","end":"2026-08-22T13:46:34.196793Z","steps":["trace[1833748408] 'process raft request' (duration: 344.541381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.196917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:33.85177Z","time spent":"345.061651ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:46:34.197078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.466297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18ce24a4e36251fd\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-08-22T13:46:34.197119Z","caller":"traceutil/trace.go:171","msg":"trace[434844957] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18ce24a4e36251fd; range_end:; response_count:1; response_revision:25113; }","duration":"211.552129ms","start":"2026-08-22T13:46:33.985553Z","end":"2026-08-22T13:46:34.197105Z","steps":["trace[434844957] 'agreement among raft nodes before linearized reading' (duration: 211.414276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.544708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.121338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:46:34.545004Z","caller":"traceutil/trace.go:171","msg":"trace[1040877100] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"277.658787ms","start":"2026-08-22T13:46:34.267328Z","end":"2026-08-22T13:46:34.544987Z","steps":["trace[1040877100] 'process raft request' (duration: 277.620356ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:34.545169Z","caller":"traceutil/trace.go:171","msg":"trace[2050878130] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"346.09534ms","start":"2026-08-22T13:46:34.199066Z","end":"2026-08-22T13:46:34.545161Z","steps":["trace[2050878130] 'process raft request' (duration: 142.451282ms)","trace[2050878130] 'compare' (duration: 203.021377ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:46:34.545212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:34.199034Z","time spent":"346.152652ms","remote":"127.0.0.1:40332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:46:34.545323Z","caller":"traceutil/trace.go:171","msg":"trace[1185068311] linearizableReadLoop","detail":"{readStateIndex:25932; appliedIndex:25931; }","duration":"318.380023ms","start":"2026-08-22T13:46:34.226936Z","end":"2026-08-22T13:46:34.545316Z","steps":["trace[1185068311] 'read index received' (duration: 114.605843ms)","trace[1185068311] 'applied index is now lower than readState.Index' (duration: 203.77338ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:34.545515Z","caller":"traceutil/trace.go:171","msg":"trace[1130475627] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"282.502929ms","start":"2026-08-22T13:46:34.262981Z","end":"2026-08-22T13:46:34.545484Z","steps":["trace[1130475627] 'process raft request' (duration: 281.831267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.545594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.665128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-22T13:46:34.545618Z","caller":"traceutil/trace.go:171","msg":"trace[2133564779] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25120; }","duration":"318.716259ms","start":"2026-08-22T13:46:34.226893Z","end":"2026-08-22T13:46:34.54561Z","steps":["trace[2133564779] 'agreement among raft nodes before linearized reading' (duration: 318.625377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.545637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:46:34.226883Z","time spent":"318.74974ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":447,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-22T13:46:34.545739Z","caller":"traceutil/trace.go:171","msg":"trace[650051600] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"282.410128ms","start":"2026-08-22T13:46:34.263318Z","end":"2026-08-22T13:46:34.545728Z","steps":["trace[650051600] 'process raft request' (duration: 281.541552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.545753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.626398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:34.545771Z","caller":"traceutil/trace.go:171","msg":"trace[1586339750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25120; }","duration":"265.680919ms","start":"2026-08-22T13:46:34.280084Z","end":"2026-08-22T13:46:34.545765Z","steps":["trace[1586339750] 'agreement among raft nodes before linearized reading' (duration: 265.651578ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:34.545858Z","caller":"traceutil/trace.go:171","msg":"trace[1666090083] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"280.841697ms","start":"2026-08-22T13:46:34.265007Z","end":"2026-08-22T13:46:34.545849Z","steps":["trace[1666090083] 'process raft request' (duration: 279.892469ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:34.545976Z","caller":"traceutil/trace.go:171","msg":"trace[1902840289] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"280.881378ms","start":"2026-08-22T13:46:34.265082Z","end":"2026-08-22T13:46:34.545964Z","steps":["trace[1902840289] 'process raft request' (duration: 279.842959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.891015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.018414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-22T13:46:34.891051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.04023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T13:46:34.891083Z","caller":"traceutil/trace.go:171","msg":"trace[2053278238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25120; }","duration":"205.117075ms","start":"2026-08-22T13:46:34.685951Z","end":"2026-08-22T13:46:34.891068Z","steps":["trace[2053278238] 'range keys from in-memory index tree' (duration: 204.893481ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:34.891099Z","caller":"traceutil/trace.go:171","msg":"trace[377917665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25120; }","duration":"240.135652ms","start":"2026-08-22T13:46:34.65095Z","end":"2026-08-22T13:46:34.891086Z","steps":["trace[377917665] 'range keys from in-memory index tree' (duration: 239.896377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.891168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.442009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T13:46:34.891248Z","caller":"traceutil/trace.go:171","msg":"trace[777791705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25120; }","duration":"239.605731ms","start":"2026-08-22T13:46:34.651624Z","end":"2026-08-22T13:46:34.891229Z","steps":["trace[777791705] 'range keys from in-memory index tree' (duration: 239.258945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:34.891233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.056755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:34.891329Z","caller":"traceutil/trace.go:171","msg":"trace[1772611618] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:25120; }","duration":"235.188519ms","start":"2026-08-22T13:46:34.656116Z","end":"2026-08-22T13:46:34.891304Z","steps":["trace[1772611618] 'count revisions from in-memory index tree' (duration: 234.732809ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:35.124462Z","caller":"traceutil/trace.go:171","msg":"trace[444746637] linearizableReadLoop","detail":"{readStateIndex:25941; appliedIndex:25938; }","duration":"172.788881ms","start":"2026-08-22T13:46:34.951656Z","end":"2026-08-22T13:46:35.124444Z","steps":["trace[444746637] 'read index received' (duration: 159.311234ms)","trace[444746637] 'applied index is now lower than readState.Index' (duration: 13.477037ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:35.124498Z","caller":"traceutil/trace.go:171","msg":"trace[1264215860] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"229.703324ms","start":"2026-08-22T13:46:34.89475Z","end":"2026-08-22T13:46:35.124453Z","steps":["trace[1264215860] 'process raft request' (duration: 216.344129ms)","trace[1264215860] 'compare' (duration: 12.973008ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:35.124496Z","caller":"traceutil/trace.go:171","msg":"trace[2045937241] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"228.698365ms","start":"2026-08-22T13:46:34.895765Z","end":"2026-08-22T13:46:35.124463Z","steps":["trace[2045937241] 'process raft request' (duration: 228.592653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:35.124553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.887582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T13:46:35.124579Z","caller":"traceutil/trace.go:171","msg":"trace[2097917876] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25124; }","duration":"172.926133ms","start":"2026-08-22T13:46:34.951643Z","end":"2026-08-22T13:46:35.124569Z","steps":["trace[2097917876] 'agreement among raft nodes before linearized reading' (duration: 172.872282ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:35.124564Z","caller":"traceutil/trace.go:171","msg":"trace[772704236] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"227.699896ms","start":"2026-08-22T13:46:34.896812Z","end":"2026-08-22T13:46:35.124512Z","steps":["trace[772704236] 'process raft request' (duration: 227.605344ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:42.140226Z","caller":"traceutil/trace.go:171","msg":"trace[1932860888] linearizableReadLoop","detail":"{readStateIndex:26013; appliedIndex:26012; }","duration":"153.678826ms","start":"2026-08-22T13:46:41.986522Z","end":"2026-08-22T13:46:42.140201Z","steps":["trace[1932860888] 'read index received' (duration: 153.506493ms)","trace[1932860888] 'applied index is now lower than readState.Index' (duration: 171.203µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:42.140379Z","caller":"traceutil/trace.go:171","msg":"trace[185174826] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"178.55209ms","start":"2026-08-22T13:46:41.961794Z","end":"2026-08-22T13:46:42.140346Z","steps":["trace[185174826] 'process raft request' (duration: 178.257394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:42.140524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.976532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T13:46:42.140561Z","caller":"traceutil/trace.go:171","msg":"trace[730900922] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25194; }","duration":"154.035833ms","start":"2026-08-22T13:46:41.986514Z","end":"2026-08-22T13:46:42.14055Z","steps":["trace[730900922] 'agreement among raft nodes before linearized reading' (duration: 153.822599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:44.723262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.511467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:46:44.723959Z","caller":"traceutil/trace.go:171","msg":"trace[238638923] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"236.8901ms","start":"2026-08-22T13:46:44.487015Z","end":"2026-08-22T13:46:44.723905Z","steps":["trace[238638923] 'process raft request' (duration: 96.618279ms)","trace[238638923] 'compare' (duration: 139.195961ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:44.724057Z","caller":"traceutil/trace.go:171","msg":"trace[2018599807] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"145.544781ms","start":"2026-08-22T13:46:44.578491Z","end":"2026-08-22T13:46:44.724036Z","steps":["trace[2018599807] 'process raft request' (duration: 145.310916ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:44.724309Z","caller":"traceutil/trace.go:171","msg":"trace[387534805] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"144.295078ms","start":"2026-08-22T13:46:44.579974Z","end":"2026-08-22T13:46:44.724269Z","steps":["trace[387534805] 'process raft request' (duration: 143.885069ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:44.724485Z","caller":"traceutil/trace.go:171","msg":"trace[6742261] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"142.887381ms","start":"2026-08-22T13:46:44.58135Z","end":"2026-08-22T13:46:44.724237Z","steps":["trace[6742261] 'process raft request' (duration: 142.642886ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:44.813577Z","caller":"traceutil/trace.go:171","msg":"trace[1878184975] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"227.031883ms","start":"2026-08-22T13:46:44.586521Z","end":"2026-08-22T13:46:44.813553Z","steps":["trace[1878184975] 'process raft request' (duration: 226.991592ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:44.813641Z","caller":"traceutil/trace.go:171","msg":"trace[2114126697] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"228.654693ms","start":"2026-08-22T13:46:44.584957Z","end":"2026-08-22T13:46:44.813611Z","steps":["trace[2114126697] 'process raft request' (duration: 228.382438ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:46:50.355917Z","caller":"traceutil/trace.go:171","msg":"trace[675132842] linearizableReadLoop","detail":"{readStateIndex:26084; appliedIndex:26083; }","duration":"187.921129ms","start":"2026-08-22T13:46:50.167972Z","end":"2026-08-22T13:46:50.355893Z","steps":["trace[675132842] 'read index received' (duration: 187.655474ms)","trace[675132842] 'applied index is now lower than readState.Index' (duration: 264.995µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:50.356224Z","caller":"traceutil/trace.go:171","msg":"trace[1145903802] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"190.201581ms","start":"2026-08-22T13:46:50.166007Z","end":"2026-08-22T13:46:50.356208Z","steps":["trace[1145903802] 'process raft request' (duration: 189.677992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:50.356524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.52162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:50.356595Z","caller":"traceutil/trace.go:171","msg":"trace[614388095] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25263; }","duration":"188.631091ms","start":"2026-08-22T13:46:50.167947Z","end":"2026-08-22T13:46:50.356578Z","steps":["trace[614388095] 'agreement among raft nodes before linearized reading' (duration: 188.526849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:59.236302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.829807ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-08-22T13:46:59.42209Z","caller":"traceutil/trace.go:171","msg":"trace[801838324] linearizableReadLoop","detail":"{readStateIndex:26151; appliedIndex:26150; }","duration":"143.521532ms","start":"2026-08-22T13:46:59.278539Z","end":"2026-08-22T13:46:59.422061Z","steps":["trace[801838324] 'read index received' (duration: 143.36953ms)","trace[801838324] 'applied index is now lower than readState.Index' (duration: 150.732µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:46:59.422204Z","caller":"traceutil/trace.go:171","msg":"trace[1598367372] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"149.860863ms","start":"2026-08-22T13:46:59.272301Z","end":"2026-08-22T13:46:59.422161Z","steps":["trace[1598367372] 'process raft request' (duration: 149.547887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:46:59.422267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.694855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:46:59.422314Z","caller":"traceutil/trace.go:171","msg":"trace[1110421783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25328; }","duration":"143.814538ms","start":"2026-08-22T13:46:59.278487Z","end":"2026-08-22T13:46:59.422301Z","steps":["trace[1110421783] 'agreement among raft nodes before linearized reading' (duration: 143.700616ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:05.632414Z","caller":"traceutil/trace.go:171","msg":"trace[333511813] linearizableReadLoop","detail":"{readStateIndex:26211; appliedIndex:26209; }","duration":"156.039272ms","start":"2026-08-22T13:47:05.476333Z","end":"2026-08-22T13:47:05.632372Z","steps":["trace[333511813] 'read index received' (duration: 210.805µs)","trace[333511813] 'applied index is now lower than readState.Index' (duration: 155.827547ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:47:05.632419Z","caller":"traceutil/trace.go:171","msg":"trace[1949196117] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"168.868205ms","start":"2026-08-22T13:47:05.463523Z","end":"2026-08-22T13:47:05.632391Z","steps":["trace[1949196117] 'process raft request' (duration: 117.694801ms)","trace[1949196117] 'compare' (duration: 50.661145ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:47:05.63262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.272616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T13:47:05.633274Z","caller":"traceutil/trace.go:171","msg":"trace[1016545464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25386; }","duration":"156.950679ms","start":"2026-08-22T13:47:05.476291Z","end":"2026-08-22T13:47:05.633241Z","steps":["trace[1016545464] 'agreement among raft nodes before linearized reading' (duration: 156.213595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:05.632902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.768837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T13:47:05.633444Z","caller":"traceutil/trace.go:171","msg":"trace[934205374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25386; }","duration":"155.334918ms","start":"2026-08-22T13:47:05.478081Z","end":"2026-08-22T13:47:05.633416Z","steps":["trace[934205374] 'agreement among raft nodes before linearized reading' (duration: 154.632524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:05.632932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.264651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T13:47:05.633612Z","caller":"traceutil/trace.go:171","msg":"trace[217269477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25386; }","duration":"150.965904ms","start":"2026-08-22T13:47:05.48263Z","end":"2026-08-22T13:47:05.633596Z","steps":["trace[217269477] 'agreement among raft nodes before linearized reading' (duration: 150.136948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:05.632951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.88015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T13:47:05.633733Z","caller":"traceutil/trace.go:171","msg":"trace[882542031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25386; }","duration":"153.699177ms","start":"2026-08-22T13:47:05.480011Z","end":"2026-08-22T13:47:05.63371Z","steps":["trace[882542031] 'agreement among raft nodes before linearized reading' (duration: 152.831139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:05.632984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.74858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T13:47:05.633828Z","caller":"traceutil/trace.go:171","msg":"trace[257383850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25386; }","duration":"151.620537ms","start":"2026-08-22T13:47:05.4822Z","end":"2026-08-22T13:47:05.633821Z","steps":["trace[257383850] 'agreement among raft nodes before linearized reading' (duration: 150.562136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:09.284221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.816713ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-08-22T13:47:09.284301Z","caller":"traceutil/trace.go:171","msg":"trace[1099079258] linearizableReadLoop","detail":"{readStateIndex:26238; appliedIndex:26237; }","duration":"138.711052ms","start":"2026-08-22T13:47:09.145577Z","end":"2026-08-22T13:47:09.284288Z","steps":["trace[1099079258] 'read index received' (duration: 59.381µs)","trace[1099079258] 'applied index is now lower than readState.Index' (duration: 138.650681ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:47:09.284362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.775803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:47:09.284384Z","caller":"traceutil/trace.go:171","msg":"trace[1620804071] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25412; }","duration":"138.845945ms","start":"2026-08-22T13:47:09.145528Z","end":"2026-08-22T13:47:09.284374Z","steps":["trace[1620804071] 'agreement among raft nodes before linearized reading' (duration: 138.793074ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:09.599821Z","caller":"traceutil/trace.go:171","msg":"trace[1271899240] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"111.156556ms","start":"2026-08-22T13:47:09.488639Z","end":"2026-08-22T13:47:09.599796Z","steps":["trace[1271899240] 'process raft request' (duration: 110.906972ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:09.821045Z","caller":"traceutil/trace.go:171","msg":"trace[1505011322] linearizableReadLoop","detail":"{readStateIndex:26240; appliedIndex:26239; }","duration":"168.520918ms","start":"2026-08-22T13:47:09.652498Z","end":"2026-08-22T13:47:09.821019Z","steps":["trace[1505011322] 'read index received' (duration: 159.581508ms)","trace[1505011322] 'applied index is now lower than readState.Index' (duration: 8.93838ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:47:09.821399Z","caller":"traceutil/trace.go:171","msg":"trace[1618185143] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"178.253243ms","start":"2026-08-22T13:47:09.643119Z","end":"2026-08-22T13:47:09.821372Z","steps":["trace[1618185143] 'process raft request' (duration: 168.948296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:09.821499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.994439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T13:47:09.821557Z","caller":"traceutil/trace.go:171","msg":"trace[306597157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25414; }","duration":"169.10911ms","start":"2026-08-22T13:47:09.652436Z","end":"2026-08-22T13:47:09.821546Z","steps":["trace[306597157] 'agreement among raft nodes before linearized reading' (duration: 168.948836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:09.821787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.061723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-22T13:47:09.821809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.750759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T13:47:09.821901Z","caller":"traceutil/trace.go:171","msg":"trace[647834002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25414; }","duration":"165.224336ms","start":"2026-08-22T13:47:09.656669Z","end":"2026-08-22T13:47:09.821894Z","steps":["trace[647834002] 'agreement among raft nodes before linearized reading' (duration: 165.009252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:09.821803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.862892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T13:47:09.821934Z","caller":"traceutil/trace.go:171","msg":"trace[608111926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25414; }","duration":"163.949172ms","start":"2026-08-22T13:47:09.657969Z","end":"2026-08-22T13:47:09.821918Z","steps":["trace[608111926] 'agreement among raft nodes before linearized reading' (duration: 163.565065ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:09.822009Z","caller":"traceutil/trace.go:171","msg":"trace[1437976014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25414; }","duration":"163.250249ms","start":"2026-08-22T13:47:09.65875Z","end":"2026-08-22T13:47:09.822Z","steps":["trace[1437976014] 'agreement among raft nodes before linearized reading' (duration: 162.732939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:09.822912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.239223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T13:47:09.823025Z","caller":"traceutil/trace.go:171","msg":"trace[1507285327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25414; }","duration":"168.347675ms","start":"2026-08-22T13:47:09.654603Z","end":"2026-08-22T13:47:09.822951Z","steps":["trace[1507285327] 'agreement among raft nodes before linearized reading' (duration: 168.155712ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:26.036875Z","caller":"traceutil/trace.go:171","msg":"trace[407432518] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"152.556506ms","start":"2026-08-22T13:47:25.884289Z","end":"2026-08-22T13:47:26.036846Z","steps":["trace[407432518] 'process raft request' (duration: 152.389072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:31.521406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.67976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:47:31.521522Z","caller":"traceutil/trace.go:171","msg":"trace[2054736179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25584; }","duration":"243.833103ms","start":"2026-08-22T13:47:31.277662Z","end":"2026-08-22T13:47:31.521495Z","steps":["trace[2054736179] 'range keys from in-memory index tree' (duration: 243.605439ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:32.116246Z","caller":"traceutil/trace.go:171","msg":"trace[1551198734] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"383.162416ms","start":"2026-08-22T13:47:31.73306Z","end":"2026-08-22T13:47:32.116222Z","steps":["trace[1551198734] 'process raft request' (duration: 383.010603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:32.116394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:31.733038Z","time spent":"383.267968ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:47:32.318366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.467369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:47:32.318444Z","caller":"traceutil/trace.go:171","msg":"trace[32570176] linearizableReadLoop","detail":"{readStateIndex:26416; appliedIndex:26415; }","duration":"242.457036ms","start":"2026-08-22T13:47:32.075975Z","end":"2026-08-22T13:47:32.318432Z","steps":["trace[32570176] 'read index received' (duration: 40.299347ms)","trace[32570176] 'applied index is now lower than readState.Index' (duration: 202.156639ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:47:32.318596Z","caller":"traceutil/trace.go:171","msg":"trace[1517693333] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"262.219432ms","start":"2026-08-22T13:47:32.056366Z","end":"2026-08-22T13:47:32.318586Z","steps":["trace[1517693333] 'process raft request' (duration: 126.470368ms)","trace[1517693333] 'compare' (duration: 135.291606ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:47:32.319248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.872452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T13:47:32.319295Z","caller":"traceutil/trace.go:171","msg":"trace[1670340542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25586; }","duration":"193.968304ms","start":"2026-08-22T13:47:32.125315Z","end":"2026-08-22T13:47:32.319283Z","steps":["trace[1670340542] 'agreement among raft nodes before linearized reading' (duration: 193.77911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:32.319364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.561399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-22T13:47:32.319377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.386385ms","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-22T13:47:32.319456Z","caller":"traceutil/trace.go:171","msg":"trace[1370966434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25586; }","duration":"169.691802ms","start":"2026-08-22T13:47:32.149731Z","end":"2026-08-22T13:47:32.319423Z","steps":["trace[1370966434] 'agreement among raft nodes before linearized reading' (duration: 169.457138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:32.319512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.99226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-22T13:47:32.319626Z","caller":"traceutil/trace.go:171","msg":"trace[80547201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25586; }","duration":"177.155784ms","start":"2026-08-22T13:47:32.142437Z","end":"2026-08-22T13:47:32.319593Z","steps":["trace[80547201] 'agreement among raft nodes before linearized reading' (duration: 176.759217ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:32.319552Z","caller":"traceutil/trace.go:171","msg":"trace[1938304691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25586; }","duration":"243.552098ms","start":"2026-08-22T13:47:32.075925Z","end":"2026-08-22T13:47:32.319477Z","steps":["trace[1938304691] 'agreement among raft nodes before linearized reading' (duration: 243.307733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:32.319683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.557603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T13:47:32.319747Z","caller":"traceutil/trace.go:171","msg":"trace[411923426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25586; }","duration":"164.667456ms","start":"2026-08-22T13:47:32.155066Z","end":"2026-08-22T13:47:32.319733Z","steps":["trace[411923426] 'agreement among raft nodes before linearized reading' (duration: 164.504293ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:32.782582Z","caller":"traceutil/trace.go:171","msg":"trace[1660530750] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"458.49151ms","start":"2026-08-22T13:47:32.324065Z","end":"2026-08-22T13:47:32.782556Z","steps":["trace[1660530750] 'process raft request' (duration: 458.272716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:32.782898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:32.324035Z","time spent":"458.625823ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:47:32.782864Z","caller":"traceutil/trace.go:171","msg":"trace[1462354115] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"458.560832ms","start":"2026-08-22T13:47:32.324267Z","end":"2026-08-22T13:47:32.782828Z","steps":["trace[1462354115] 'process raft request' (duration: 458.232205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:32.783059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:32.324243Z","time spent":"458.714565ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:47:32.988758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.880609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T13:47:32.988979Z","caller":"traceutil/trace.go:171","msg":"trace[1416271090] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"664.347801ms","start":"2026-08-22T13:47:32.324613Z","end":"2026-08-22T13:47:32.988961Z","steps":["trace[1416271090] 'process raft request' (duration: 664.240609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:32.989042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:32.324595Z","time spent":"664.417432ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:47:32.989019Z","caller":"traceutil/trace.go:171","msg":"trace[356980333] transaction","detail":"{read_only:false; response_revision:25590; number_of_response:1; }","duration":"664.653606ms","start":"2026-08-22T13:47:32.324331Z","end":"2026-08-22T13:47:32.988985Z","steps":["trace[356980333] 'process raft request' (duration: 458.45789ms)","trace[356980333] 'compare' (duration: 205.473212ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T13:47:32.989211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:32.324291Z","time spent":"664.812649ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:47:33.660263Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006738.wal"} {"level":"info","ts":"2026-08-22T13:47:33.660741Z","caller":"traceutil/trace.go:171","msg":"trace[952675040] linearizableReadLoop","detail":"{readStateIndex:26423; appliedIndex:26417; }","duration":"906.159314ms","start":"2026-08-22T13:47:32.754552Z","end":"2026-08-22T13:47:33.660711Z","steps":["trace[952675040] 'read index received' (duration: 27.730768ms)","trace[952675040] 'applied index is now lower than readState.Index' (duration: 878.427666ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T13:47:33.660785Z","caller":"traceutil/trace.go:171","msg":"trace[959099721] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"1.193534257s","start":"2026-08-22T13:47:32.467209Z","end":"2026-08-22T13:47:33.660743Z","steps":["trace[959099721] 'process raft request' (duration: 1.193291502s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.660937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.350357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:47:33.660993Z","caller":"traceutil/trace.go:171","msg":"trace[472923487] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25593; }","duration":"906.46694ms","start":"2026-08-22T13:47:32.754509Z","end":"2026-08-22T13:47:33.660976Z","steps":["trace[472923487] 'agreement among raft nodes before linearized reading' (duration: 906.353258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.661049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:32.754492Z","time spent":"906.543772ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-08-22T13:47:33.661038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:32.467189Z","time spent":"1.193673889s","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:47:33.660859Z","caller":"traceutil/trace.go:171","msg":"trace[1799946908] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"1.159264963s","start":"2026-08-22T13:47:32.501537Z","end":"2026-08-22T13:47:33.660802Z","steps":["trace[1799946908] 'process raft request' (duration: 1.15912712s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.662019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:32.501515Z","time spent":"1.160382495s","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:47:33.709263Z","caller":"traceutil/trace.go:171","msg":"trace[369929901] transaction","detail":"{read_only:false; response_revision:25594; number_of_response:1; }","duration":"721.886346ms","start":"2026-08-22T13:47:32.987349Z","end":"2026-08-22T13:47:33.709235Z","steps":["trace[369929901] 'process raft request' (duration: 721.719773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.709418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:32.987323Z","time spent":"722.014579ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:47:33.709571Z","caller":"traceutil/trace.go:171","msg":"trace[1463945901] transaction","detail":"{read_only:false; response_revision:25595; number_of_response:1; }","duration":"665.465472ms","start":"2026-08-22T13:47:33.044091Z","end":"2026-08-22T13:47:33.709557Z","steps":["trace[1463945901] 'process raft request' (duration: 665.099965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.709635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:33.044073Z","time spent":"665.526833ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:47:33.70967Z","caller":"traceutil/trace.go:171","msg":"trace[2125795582] transaction","detail":"{read_only:false; response_revision:25596; number_of_response:1; }","duration":"665.41084ms","start":"2026-08-22T13:47:33.044236Z","end":"2026-08-22T13:47:33.709646Z","steps":["trace[2125795582] 'process raft request' (duration: 665.193326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.70981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:33.044205Z","time spent":"665.529853ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:47:33.709899Z","caller":"traceutil/trace.go:171","msg":"trace[254745158] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"664.513593ms","start":"2026-08-22T13:47:33.045351Z","end":"2026-08-22T13:47:33.709865Z","steps":["trace[254745158] 'process raft request' (duration: 664.196328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.709989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.58774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T13:47:33.710037Z","caller":"traceutil/trace.go:171","msg":"trace[557623329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25598; }","duration":"430.674241ms","start":"2026-08-22T13:47:33.279352Z","end":"2026-08-22T13:47:33.710027Z","steps":["trace[557623329] 'agreement among raft nodes before linearized reading' (duration: 430.605019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.710075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:33.279331Z","time spent":"430.734512ms","remote":"127.0.0.1:40266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T13:47:33.710089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.003033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T13:47:33.710101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.612291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-22T13:47:33.710143Z","caller":"traceutil/trace.go:171","msg":"trace[1281852339] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25598; }","duration":"685.066914ms","start":"2026-08-22T13:47:33.025064Z","end":"2026-08-22T13:47:33.710131Z","steps":["trace[1281852339] 'agreement among raft nodes before linearized reading' (duration: 684.957292ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:47:33.710151Z","caller":"traceutil/trace.go:171","msg":"trace[797205099] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25598; }","duration":"689.705503ms","start":"2026-08-22T13:47:33.020432Z","end":"2026-08-22T13:47:33.710138Z","steps":["trace[797205099] 'agreement among raft nodes before linearized reading' (duration: 689.522839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.710094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.22506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T13:47:33.710186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:33.020412Z","time spent":"689.765714ms","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":185,"response size":33,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-22T13:47:33.710209Z","caller":"traceutil/trace.go:171","msg":"trace[1709310889] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25598; }","duration":"505.386873ms","start":"2026-08-22T13:47:33.204802Z","end":"2026-08-22T13:47:33.710188Z","steps":["trace[1709310889] 'agreement among raft nodes before linearized reading' (duration: 505.210659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.710228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:33.045338Z","time spent":"664.640106ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T13:47:33.710182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:33.025009Z","time spent":"685.164567ms","remote":"127.0.0.1:40778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":32,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-22T13:47:33.710308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:33.20477Z","time spent":"505.500935ms","remote":"127.0.0.1:33682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"info","ts":"2026-08-22T13:47:33.709978Z","caller":"traceutil/trace.go:171","msg":"trace[1711358403] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"649.983376ms","start":"2026-08-22T13:47:33.059976Z","end":"2026-08-22T13:47:33.709959Z","steps":["trace[1711358403] 'process raft request' (duration: 649.602009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T13:47:33.710793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T13:47:33.059957Z","time spent":"650.570478ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T13:48:10.498589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23056} {"level":"info","ts":"2026-08-22T13:48:10.627943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23056,"took":"125.914364ms","hash":151285127,"current-db-size-bytes":62042112,"current-db-size":"62 MB","current-db-size-in-use-bytes":36737024,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-22T13:48:10.627985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":151285127,"revision":23056,"compact-revision":20112} {"level":"info","ts":"2026-08-22T13:50:58.606207Z","caller":"traceutil/trace.go:171","msg":"trace[365258145] transaction","detail":"{read_only:false; response_revision:27641; number_of_response:1; }","duration":"103.922708ms","start":"2026-08-22T13:50:58.502243Z","end":"2026-08-22T13:50:58.606166Z","steps":["trace[365258145] 'process raft request' (duration: 103.613641ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:50:58.60648Z","caller":"traceutil/trace.go:171","msg":"trace[2105836317] transaction","detail":"{read_only:false; response_revision:27642; number_of_response:1; }","duration":"101.062712ms","start":"2026-08-22T13:50:58.505382Z","end":"2026-08-22T13:50:58.606445Z","steps":["trace[2105836317] 'process raft request' (duration: 100.724116ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:50:58.606572Z","caller":"traceutil/trace.go:171","msg":"trace[638029855] transaction","detail":"{read_only:false; response_revision:27643; number_of_response:1; }","duration":"100.479672ms","start":"2026-08-22T13:50:58.506066Z","end":"2026-08-22T13:50:58.606546Z","steps":["trace[638029855] 'process raft request' (duration: 100.290908ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T13:53:10.50479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26038} {"level":"info","ts":"2026-08-22T13:53:10.648585Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26038,"took":"139.593461ms","hash":1531452920,"current-db-size-bytes":62042112,"current-db-size":"62 MB","current-db-size-in-use-bytes":35332096,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-22T13:53:10.648665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1531452920,"revision":26038,"compact-revision":23056} {"level":"info","ts":"2026-08-22T13:53:57.241839Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":30004,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T13:53:57.24711Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-08-22T13:53:57.247205Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-08-22T13:58:10.510826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28734} {"level":"info","ts":"2026-08-22T13:58:10.645345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28734,"took":"130.027622ms","hash":3490713383,"current-db-size-bytes":62042112,"current-db-size":"62 MB","current-db-size-in-use-bytes":31346688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T13:58:10.645511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3490713383,"revision":28734,"compact-revision":26038} {"level":"info","ts":"2026-08-22T14:03:10.516741Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30965} {"level":"info","ts":"2026-08-22T14:03:10.642157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30965,"took":"120.829067ms","hash":2306124237,"current-db-size-bytes":62042112,"current-db-size":"62 MB","current-db-size-in-use-bytes":31727616,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T14:03:10.642236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2306124237,"revision":30965,"compact-revision":28734} {"level":"info","ts":"2026-08-22T14:08:10.53557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33291} {"level":"info","ts":"2026-08-22T14:08:10.683704Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33291,"took":"144.119019ms","hash":1446215444,"current-db-size-bytes":63504384,"current-db-size":"64 MB","current-db-size-in-use-bytes":32620544,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T14:08:10.683787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1446215444,"revision":33291,"compact-revision":30965} {"level":"info","ts":"2026-08-22T14:13:10.543008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35618} {"level":"info","ts":"2026-08-22T14:13:10.687685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35618,"took":"138.463ms","hash":3723853631,"current-db-size-bytes":64397312,"current-db-size":"64 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T14:13:10.687759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3723853631,"revision":35618,"compact-revision":33291} {"level":"info","ts":"2026-08-22T14:15:11.775318Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T14:15:11.781909Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-08-22T14:15:11.782032Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-08-22T14:18:10.547892Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37845} {"level":"info","ts":"2026-08-22T14:18:10.659741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37845,"took":"107.582648ms","hash":806116428,"current-db-size-bytes":64397312,"current-db-size":"64 MB","current-db-size-in-use-bytes":31756288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T14:18:10.65981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":806116428,"revision":37845,"compact-revision":35618}