{"level":"warn","ts":"2026-04-07T00:06:36.535238Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:06:36.535395Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-04-07T00:06:36.535553Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:06:36.535571Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-07T00:06:36.535596Z","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-04-07T00:06:36.536498Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-04-07T00:06:36.536605Z","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.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-04-07T00:06:36.541066Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.134635ms"} {"level":"info","ts":"2026-04-07T00:06:36.549115Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-04-07T00:06:36.54929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:06:36.549337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-04-07T00:06:36.549356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:06:36.549381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-04-07T00:06:36.549455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-04-07T00:06:36.560921Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:06:36.568088Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:06:36.583493Z","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-04-07T00:06:36.587015Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:06:36.587382Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:06:36.587496Z","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-04-07T00:06:36.58754Z","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-04-07T00:06:36.587554Z","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-04-07T00:06:36.588243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-04-07T00:06:36.589175Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-07T00:06:36.593147Z","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-04-07T00:06:36.593215Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-07T00:06:36.593341Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-07T00:06:36.593465Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:06:36.593511Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:06:37.150476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:06:37.150547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:06:37.150584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-04-07T00:06:37.150604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:06:37.150616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-07T00:06:37.150633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-04-07T00:06:37.150648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-07T00:06:37.151561Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:37.152531Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:06:37.152522Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:06:37.152563Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:06:37.152959Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:06:37.152994Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:06:37.153071Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:37.153217Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:37.153257Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:37.154997Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:06:37.155269Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"warn","ts":"2026-04-07T00:06:45.622521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.017695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-07T00:06:45.62266Z","caller":"traceutil/trace.go:171","msg":"trace[1539428604] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:290; }","duration":"118.173939ms","start":"2026-04-07T00:06:45.504465Z","end":"2026-04-07T00:06:45.622639Z","steps":["trace[1539428604] 'range keys from in-memory index tree' (duration: 117.864591ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.656097Z","caller":"traceutil/trace.go:171","msg":"trace[756561918] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"126.12225ms","start":"2026-04-07T00:07:06.529939Z","end":"2026-04-07T00:07:06.656061Z","steps":["trace[756561918] 'process raft request' (duration: 125.867273ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.663047Z","caller":"traceutil/trace.go:171","msg":"trace[363698141] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"128.730433ms","start":"2026-04-07T00:07:06.534287Z","end":"2026-04-07T00:07:06.663018Z","steps":["trace[363698141] 'process raft request' (duration: 128.040243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.663141Z","caller":"traceutil/trace.go:171","msg":"trace[1401599984] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"114.598959ms","start":"2026-04-07T00:07:06.548458Z","end":"2026-04-07T00:07:06.663057Z","steps":["trace[1401599984] 'process raft request' (duration: 114.161937ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.663284Z","caller":"traceutil/trace.go:171","msg":"trace[1617478185] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"126.035758ms","start":"2026-04-07T00:07:06.537234Z","end":"2026-04-07T00:07:06.66327Z","steps":["trace[1617478185] 'process raft request' (duration: 125.231555ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.663292Z","caller":"traceutil/trace.go:171","msg":"trace[20081625] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"117.48621ms","start":"2026-04-07T00:07:06.54577Z","end":"2026-04-07T00:07:06.663256Z","steps":["trace[20081625] 'process raft request' (duration: 116.755839ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.663152Z","caller":"traceutil/trace.go:171","msg":"trace[2097364308] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"109.201469ms","start":"2026-04-07T00:07:06.553886Z","end":"2026-04-07T00:07:06.663088Z","steps":["trace[2097364308] 'process raft request' (duration: 108.806128ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.663299Z","caller":"traceutil/trace.go:171","msg":"trace[1934448709] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"113.837678ms","start":"2026-04-07T00:07:06.549377Z","end":"2026-04-07T00:07:06.663214Z","steps":["trace[1934448709] 'process raft request' (duration: 113.278193ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.663446Z","caller":"traceutil/trace.go:171","msg":"trace[1788313005] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"114.935049ms","start":"2026-04-07T00:07:06.548357Z","end":"2026-04-07T00:07:06.663292Z","steps":["trace[1788313005] 'process raft request' (duration: 114.214628ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:13.262885Z","caller":"traceutil/trace.go:171","msg":"trace[1812446219] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"223.872181ms","start":"2026-04-07T00:07:13.038988Z","end":"2026-04-07T00:07:13.26286Z","steps":["trace[1812446219] 'process raft request' (duration: 223.675095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:13.477309Z","caller":"traceutil/trace.go:171","msg":"trace[684976323] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"237.149721ms","start":"2026-04-07T00:07:13.240132Z","end":"2026-04-07T00:07:13.477282Z","steps":["trace[684976323] 'read index received' (duration: 22.434175ms)","trace[684976323] 'applied index is now lower than readState.Index' (duration: 214.714726ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:13.477434Z","caller":"traceutil/trace.go:171","msg":"trace[1708070898] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"306.359767ms","start":"2026-04-07T00:07:13.171054Z","end":"2026-04-07T00:07:13.477413Z","steps":["trace[1708070898] 'process raft request' (duration: 305.632567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:13.477619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.477189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T00:07:13.47791Z","caller":"traceutil/trace.go:171","msg":"trace[5639807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"237.772628ms","start":"2026-04-07T00:07:13.240069Z","end":"2026-04-07T00:07:13.477842Z","steps":["trace[5639807] 'agreement among raft nodes before linearized reading' (duration: 237.409008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:13.479121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:13.171017Z","time spent":"306.705326ms","remote":"127.0.0.1:41302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:07:13.687573Z","caller":"traceutil/trace.go:171","msg":"trace[2134819740] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"102.464661ms","start":"2026-04-07T00:07:13.585081Z","end":"2026-04-07T00:07:13.687546Z","steps":["trace[2134819740] 'read index received' (duration: 69.113923ms)","trace[2134819740] 'applied index is now lower than readState.Index' (duration: 33.349808ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:13.687736Z","caller":"traceutil/trace.go:171","msg":"trace[358245215] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"147.288079ms","start":"2026-04-07T00:07:13.540431Z","end":"2026-04-07T00:07:13.68772Z","steps":["trace[358245215] 'process raft request' (duration: 113.770236ms)","trace[358245215] 'compare' (duration: 33.176513ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:13.687815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.716399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:07:13.687858Z","caller":"traceutil/trace.go:171","msg":"trace[1936972292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"102.819561ms","start":"2026-04-07T00:07:13.585027Z","end":"2026-04-07T00:07:13.687847Z","steps":["trace[1936972292] 'agreement among raft nodes before linearized reading' (duration: 102.715638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:14.366373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.056247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:07:14.366537Z","caller":"traceutil/trace.go:171","msg":"trace[1447085269] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"656.383399ms","start":"2026-04-07T00:07:13.710129Z","end":"2026-04-07T00:07:14.366513Z","steps":["trace[1447085269] 'process raft request' (duration: 464.814907ms)","trace[1447085269] 'compare' (duration: 190.737748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:14.36661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:13.7101Z","time spent":"656.485071ms","remote":"127.0.0.1:41696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:07:29.905136Z","caller":"traceutil/trace.go:171","msg":"trace[1131149418] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"140.726516ms","start":"2026-04-07T00:07:29.764265Z","end":"2026-04-07T00:07:29.904992Z","steps":["trace[1131149418] 'process raft request' (duration: 127.359044ms)","trace[1131149418] 'compare' (duration: 13.223478ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:30.077135Z","caller":"traceutil/trace.go:171","msg":"trace[272677952] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"110.136166ms","start":"2026-04-07T00:07:29.966974Z","end":"2026-04-07T00:07:30.07711Z","steps":["trace[272677952] 'process raft request' (duration: 108.963533ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:56.88635Z","caller":"traceutil/trace.go:171","msg":"trace[946099428] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"134.902794ms","start":"2026-04-07T00:07:56.751421Z","end":"2026-04-07T00:07:56.886323Z","steps":["trace[946099428] 'process raft request' (duration: 134.733099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:56.986857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.542423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-07T00:10:56.986992Z","caller":"traceutil/trace.go:171","msg":"trace[380470359] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2761; }","duration":"188.729789ms","start":"2026-04-07T00:10:56.798242Z","end":"2026-04-07T00:10:56.986972Z","steps":["trace[380470359] 'range keys from in-memory index tree' (duration: 188.361538ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:56.987173Z","caller":"traceutil/trace.go:171","msg":"trace[1685378565] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"168.169182ms","start":"2026-04-07T00:10:56.818983Z","end":"2026-04-07T00:10:56.987152Z","steps":["trace[1685378565] 'process raft request' (duration: 167.07539ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:46.629732Z","caller":"traceutil/trace.go:171","msg":"trace[444531024] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4861; }","duration":"145.04877ms","start":"2026-04-07T00:14:46.484663Z","end":"2026-04-07T00:14:46.629711Z","steps":["trace[444531024] 'read index received' (duration: 144.629499ms)","trace[444531024] 'applied index is now lower than readState.Index' (duration: 418.021µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:14:46.629785Z","caller":"traceutil/trace.go:171","msg":"trace[680209100] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"153.085236ms","start":"2026-04-07T00:14:46.476671Z","end":"2026-04-07T00:14:46.629757Z","steps":["trace[680209100] 'process raft request' (duration: 152.710905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:46.629871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.192634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:14:46.629915Z","caller":"traceutil/trace.go:171","msg":"trace[392677674] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:0; response_revision:4676; }","duration":"145.269547ms","start":"2026-04-07T00:14:46.484633Z","end":"2026-04-07T00:14:46.629902Z","steps":["trace[392677674] 'agreement among raft nodes before linearized reading' (duration: 145.195935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:46.636355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.044536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T00:14:46.636441Z","caller":"traceutil/trace.go:171","msg":"trace[96948947] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4678; }","duration":"122.167579ms","start":"2026-04-07T00:14:46.514255Z","end":"2026-04-07T00:14:46.636423Z","steps":["trace[96948947] 'agreement among raft nodes before linearized reading' (duration: 121.921082ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:46.636663Z","caller":"traceutil/trace.go:171","msg":"trace[228306763] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"113.358584ms","start":"2026-04-07T00:14:46.523279Z","end":"2026-04-07T00:14:46.636637Z","steps":["trace[228306763] 'process raft request' (duration: 112.826488ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:46.636898Z","caller":"traceutil/trace.go:171","msg":"trace[167239252] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"113.727294ms","start":"2026-04-07T00:14:46.52316Z","end":"2026-04-07T00:14:46.636887Z","steps":["trace[167239252] 'process raft request' (duration: 112.810607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:46.637153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.313777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T00:14:46.637214Z","caller":"traceutil/trace.go:171","msg":"trace[1835369003] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4678; }","duration":"121.416329ms","start":"2026-04-07T00:14:46.515783Z","end":"2026-04-07T00:14:46.6372Z","steps":["trace[1835369003] 'agreement among raft nodes before linearized reading' (duration: 121.271895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:54.170345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.54549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:14:54.170613Z","caller":"traceutil/trace.go:171","msg":"trace[1911352134] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"231.377557ms","start":"2026-04-07T00:14:53.939218Z","end":"2026-04-07T00:14:54.170596Z","steps":["trace[1911352134] 'process raft request' (duration: 231.260044ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:54.170665Z","caller":"traceutil/trace.go:171","msg":"trace[887281617] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"264.407481ms","start":"2026-04-07T00:14:53.906217Z","end":"2026-04-07T00:14:54.170624Z","steps":["trace[887281617] 'process raft request' (duration: 111.48042ms)","trace[887281617] 'compare' (duration: 152.061397ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:14:54.170714Z","caller":"traceutil/trace.go:171","msg":"trace[966077753] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5142; }","duration":"263.337562ms","start":"2026-04-07T00:14:53.907321Z","end":"2026-04-07T00:14:54.170658Z","steps":["trace[966077753] 'read index received' (duration: 110.39238ms)","trace[966077753] 'applied index is now lower than readState.Index' (duration: 152.939902ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:14:54.171051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.707132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w4l8g\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-07T00:14:54.171195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.26797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-07T00:14:54.171207Z","caller":"traceutil/trace.go:171","msg":"trace[1199001928] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w4l8g; range_end:; response_count:1; response_revision:4954; }","duration":"263.912157ms","start":"2026-04-07T00:14:53.907265Z","end":"2026-04-07T00:14:54.171177Z","steps":["trace[1199001928] 'agreement among raft nodes before linearized reading' (duration: 263.577138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:54.171191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.433493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9fe0a354-7650-45d3-852e-c33ddcbdbe61\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-07T00:14:54.171269Z","caller":"traceutil/trace.go:171","msg":"trace[490256869] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4954; }","duration":"198.453305ms","start":"2026-04-07T00:14:53.972791Z","end":"2026-04-07T00:14:54.171244Z","steps":["trace[490256869] 'agreement among raft nodes before linearized reading' (duration: 198.128696ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:54.171294Z","caller":"traceutil/trace.go:171","msg":"trace[1383003987] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9fe0a354-7650-45d3-852e-c33ddcbdbe61; range_end:; response_count:1; response_revision:4954; }","duration":"164.584918ms","start":"2026-04-07T00:14:54.00668Z","end":"2026-04-07T00:14:54.171265Z","steps":["trace[1383003987] 'agreement among raft nodes before linearized reading' (duration: 164.245728ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:23.822384Z","caller":"traceutil/trace.go:171","msg":"trace[579496387] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"154.753234ms","start":"2026-04-07T00:15:23.667611Z","end":"2026-04-07T00:15:23.822364Z","steps":["trace[579496387] 'process raft request' (duration: 154.519688ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:23.82259Z","caller":"traceutil/trace.go:171","msg":"trace[599866592] linearizableReadLoop","detail":"{readStateIndex:5685; appliedIndex:5684; }","duration":"107.200136ms","start":"2026-04-07T00:15:23.715073Z","end":"2026-04-07T00:15:23.822274Z","steps":["trace[599866592] 'read index received' (duration: 106.982329ms)","trace[599866592] 'applied index is now lower than readState.Index' (duration: 216.497µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:15:23.822648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.572555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T00:15:23.822695Z","caller":"traceutil/trace.go:171","msg":"trace[1374723082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5482; }","duration":"107.677609ms","start":"2026-04-07T00:15:23.715008Z","end":"2026-04-07T00:15:23.822686Z","steps":["trace[1374723082] 'agreement among raft nodes before linearized reading' (duration: 107.524445ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:23.993739Z","caller":"traceutil/trace.go:171","msg":"trace[1726350144] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"165.3643ms","start":"2026-04-07T00:15:23.828338Z","end":"2026-04-07T00:15:23.993702Z","steps":["trace[1726350144] 'process raft request' (duration: 101.484715ms)","trace[1726350144] 'compare' (duration: 63.601087ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:23.993825Z","caller":"traceutil/trace.go:171","msg":"trace[1935017320] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"165.462873ms","start":"2026-04-07T00:15:23.828338Z","end":"2026-04-07T00:15:23.9938Z","steps":["trace[1935017320] 'process raft request' (duration: 165.256577ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:23.994036Z","caller":"traceutil/trace.go:171","msg":"trace[1534201954] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"164.419724ms","start":"2026-04-07T00:15:23.829573Z","end":"2026-04-07T00:15:23.993993Z","steps":["trace[1534201954] 'process raft request' (duration: 164.067954ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:23.994206Z","caller":"traceutil/trace.go:171","msg":"trace[1381860197] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"164.136246ms","start":"2026-04-07T00:15:23.830051Z","end":"2026-04-07T00:15:23.994187Z","steps":["trace[1381860197] 'process raft request' (duration: 163.685884ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:23.994335Z","caller":"traceutil/trace.go:171","msg":"trace[1900537632] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"163.669712ms","start":"2026-04-07T00:15:23.830656Z","end":"2026-04-07T00:15:23.994326Z","steps":["trace[1900537632] 'process raft request' (duration: 163.473177ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:23.994374Z","caller":"traceutil/trace.go:171","msg":"trace[1748037657] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"163.345603ms","start":"2026-04-07T00:15:23.831014Z","end":"2026-04-07T00:15:23.99436Z","steps":["trace[1748037657] 'process raft request' (duration: 163.206079ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:23.994303Z","caller":"traceutil/trace.go:171","msg":"trace[1780004726] linearizableReadLoop","detail":"{readStateIndex:5691; appliedIndex:5685; }","duration":"101.833634ms","start":"2026-04-07T00:15:23.892454Z","end":"2026-04-07T00:15:23.994288Z","steps":["trace[1780004726] 'read index received' (duration: 37.370913ms)","trace[1780004726] 'applied index is now lower than readState.Index' (duration: 64.461881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:15:23.994581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.116852ms","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-04-07T00:15:23.994625Z","caller":"traceutil/trace.go:171","msg":"trace[1349937898] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5488; }","duration":"102.218595ms","start":"2026-04-07T00:15:23.892395Z","end":"2026-04-07T00:15:23.994614Z","steps":["trace[1349937898] 'agreement among raft nodes before linearized reading' (duration: 102.070751ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:25.100474Z","caller":"traceutil/trace.go:171","msg":"trace[1429545937] linearizableReadLoop","detail":"{readStateIndex:5698; appliedIndex:5697; }","duration":"101.097495ms","start":"2026-04-07T00:15:24.999354Z","end":"2026-04-07T00:15:25.100451Z","steps":["trace[1429545937] 'read index received' (duration: 100.93251ms)","trace[1429545937] 'applied index is now lower than readState.Index' (duration: 163.945µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:25.100599Z","caller":"traceutil/trace.go:171","msg":"trace[390307485] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"126.627767ms","start":"2026-04-07T00:15:24.973948Z","end":"2026-04-07T00:15:25.100576Z","steps":["trace[390307485] 'process raft request' (duration: 126.314679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:25.10063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.256859ms","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-04-07T00:15:25.100666Z","caller":"traceutil/trace.go:171","msg":"trace[1648679325] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5495; }","duration":"101.354641ms","start":"2026-04-07T00:15:24.999299Z","end":"2026-04-07T00:15:25.100654Z","steps":["trace[1648679325] 'agreement among raft nodes before linearized reading' (duration: 101.245128ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:37.186506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2982} {"level":"info","ts":"2026-04-07T00:16:37.257162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2982,"took":"69.194825ms","hash":1801724648,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":34086912,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:16:37.257241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1801724648,"revision":2982,"compact-revision":-1} {"level":"info","ts":"2026-04-07T00:21:16.063806Z","caller":"traceutil/trace.go:171","msg":"trace[1057487899] linearizableReadLoop","detail":"{readStateIndex:8440; appliedIndex:8439; }","duration":"232.961004ms","start":"2026-04-07T00:21:15.830817Z","end":"2026-04-07T00:21:16.063778Z","steps":["trace[1057487899] 'read index received' (duration: 199.852007ms)","trace[1057487899] 'applied index is now lower than readState.Index' (duration: 33.107247ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:21:16.063827Z","caller":"traceutil/trace.go:171","msg":"trace[428880410] transaction","detail":"{read_only:false; response_revision:8152; number_of_response:1; }","duration":"248.210731ms","start":"2026-04-07T00:21:15.815574Z","end":"2026-04-07T00:21:16.063785Z","steps":["trace[428880410] 'process raft request' (duration: 215.169316ms)","trace[428880410] 'compare' (duration: 32.778388ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:21:16.064035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.205021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T00:21:16.064078Z","caller":"traceutil/trace.go:171","msg":"trace[104450504] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8152; }","duration":"233.303043ms","start":"2026-04-07T00:21:15.83076Z","end":"2026-04-07T00:21:16.064063Z","steps":["trace[104450504] 'agreement among raft nodes before linearized reading' (duration: 233.135628ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:16.328261Z","caller":"traceutil/trace.go:171","msg":"trace[825236256] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"172.155261ms","start":"2026-04-07T00:21:16.156078Z","end":"2026-04-07T00:21:16.328233Z","steps":["trace[825236256] 'process raft request' (duration: 171.909435ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:17.384959Z","caller":"traceutil/trace.go:171","msg":"trace[757601363] linearizableReadLoop","detail":"{readStateIndex:8446; appliedIndex:8445; }","duration":"113.21976ms","start":"2026-04-07T00:21:17.271719Z","end":"2026-04-07T00:21:17.384939Z","steps":["trace[757601363] 'read index received' (duration: 113.029605ms)","trace[757601363] 'applied index is now lower than readState.Index' (duration: 189.265µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:21:17.385135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.411455ms","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-04-07T00:21:17.385165Z","caller":"traceutil/trace.go:171","msg":"trace[79985669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8158; }","duration":"113.491257ms","start":"2026-04-07T00:21:17.271666Z","end":"2026-04-07T00:21:17.385157Z","steps":["trace[79985669] 'agreement among raft nodes before linearized reading' (duration: 113.347663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:17.385348Z","caller":"traceutil/trace.go:171","msg":"trace[814644930] transaction","detail":"{read_only:false; response_revision:8158; number_of_response:1; }","duration":"132.275766ms","start":"2026-04-07T00:21:17.253037Z","end":"2026-04-07T00:21:17.385313Z","steps":["trace[814644930] 'process raft request' (duration: 131.781671ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:17.621433Z","caller":"traceutil/trace.go:171","msg":"trace[1424800124] transaction","detail":"{read_only:false; response_revision:8159; number_of_response:1; }","duration":"232.625635ms","start":"2026-04-07T00:21:17.38878Z","end":"2026-04-07T00:21:17.621405Z","steps":["trace[1424800124] 'process raft request' (duration: 211.747699ms)","trace[1424800124] 'compare' (duration: 20.558967ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:21:17.621466Z","caller":"traceutil/trace.go:171","msg":"trace[330991816] linearizableReadLoop","detail":"{readStateIndex:8447; appliedIndex:8446; }","duration":"169.747265ms","start":"2026-04-07T00:21:17.45169Z","end":"2026-04-07T00:21:17.621438Z","steps":["trace[330991816] 'read index received' (duration: 148.942591ms)","trace[330991816] 'applied index is now lower than readState.Index' (duration: 20.803123ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:21:17.621594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.881568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:21:17.621638Z","caller":"traceutil/trace.go:171","msg":"trace[1606923195] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8160; }","duration":"169.950241ms","start":"2026-04-07T00:21:17.451673Z","end":"2026-04-07T00:21:17.621623Z","steps":["trace[1606923195] 'agreement among raft nodes before linearized reading' (duration: 169.853328ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:37.193604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6099} {"level":"info","ts":"2026-04-07T00:21:37.285724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6099,"took":"90.138924ms","hash":2467812195,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":23138304,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-07T00:21:37.285794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2467812195,"revision":6099,"compact-revision":2982} {"level":"info","ts":"2026-04-07T00:21:57.643457Z","caller":"traceutil/trace.go:171","msg":"trace[821477959] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"108.756817ms","start":"2026-04-07T00:21:57.534671Z","end":"2026-04-07T00:21:57.643428Z","steps":["trace[821477959] 'process raft request' (duration: 108.52781ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:57.791271Z","caller":"traceutil/trace.go:171","msg":"trace[1422727420] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"145.229977ms","start":"2026-04-07T00:21:57.646029Z","end":"2026-04-07T00:21:57.791259Z","steps":["trace[1422727420] 'process raft request' (duration: 142.755968ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:57.790956Z","caller":"traceutil/trace.go:171","msg":"trace[1007279413] linearizableReadLoop","detail":"{readStateIndex:8780; appliedIndex:8779; }","duration":"141.901165ms","start":"2026-04-07T00:21:57.64903Z","end":"2026-04-07T00:21:57.790931Z","steps":["trace[1007279413] 'read index received' (duration: 139.626391ms)","trace[1007279413] 'applied index is now lower than readState.Index' (duration: 2.268624ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:21:57.792003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.941954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19382"} {"level":"info","ts":"2026-04-07T00:21:57.792071Z","caller":"traceutil/trace.go:171","msg":"trace[1923716000] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8475; }","duration":"143.046016ms","start":"2026-04-07T00:21:57.649002Z","end":"2026-04-07T00:21:57.792048Z","steps":["trace[1923716000] 'agreement among raft nodes before linearized reading' (duration: 142.688806ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:57.943285Z","caller":"traceutil/trace.go:171","msg":"trace[508974818] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"141.913463ms","start":"2026-04-07T00:21:57.801343Z","end":"2026-04-07T00:21:57.943256Z","steps":["trace[508974818] 'process raft request' (duration: 118.458307ms)","trace[508974818] 'compare' (duration: 22.963723ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:21:57.94346Z","caller":"traceutil/trace.go:171","msg":"trace[1024994136] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"135.70461ms","start":"2026-04-07T00:21:57.807729Z","end":"2026-04-07T00:21:57.943434Z","steps":["trace[1024994136] 'process raft request' (duration: 135.424983ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:58.099803Z","caller":"traceutil/trace.go:171","msg":"trace[746309681] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"152.909432ms","start":"2026-04-07T00:21:57.946866Z","end":"2026-04-07T00:21:58.099776Z","steps":["trace[746309681] 'process raft request' (duration: 127.08944ms)","trace[746309681] 'compare' (duration: 25.623057ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:21:58.695519Z","caller":"traceutil/trace.go:171","msg":"trace[341787876] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"106.830442ms","start":"2026-04-07T00:21:58.588672Z","end":"2026-04-07T00:21:58.695502Z","steps":["trace[341787876] 'process raft request' (duration: 106.688648ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:56.4361Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249d.wal"} {"level":"info","ts":"2026-04-07T00:23:13.064637Z","caller":"traceutil/trace.go:171","msg":"trace[1750440621] transaction","detail":"{read_only:false; response_revision:9220; number_of_response:1; }","duration":"287.685415ms","start":"2026-04-07T00:23:12.776928Z","end":"2026-04-07T00:23:13.064613Z","steps":["trace[1750440621] 'process raft request' (duration: 267.589422ms)","trace[1750440621] 'compare' (duration: 19.878137ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:13.234555Z","caller":"traceutil/trace.go:171","msg":"trace[919812207] transaction","detail":"{read_only:false; response_revision:9221; number_of_response:1; }","duration":"232.993953ms","start":"2026-04-07T00:23:13.001536Z","end":"2026-04-07T00:23:13.23453Z","steps":["trace[919812207] 'process raft request' (duration: 232.822139ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:13.253504Z","caller":"traceutil/trace.go:171","msg":"trace[365094446] linearizableReadLoop","detail":"{readStateIndex:9546; appliedIndex:9544; }","duration":"126.546213ms","start":"2026-04-07T00:23:13.126933Z","end":"2026-04-07T00:23:13.253479Z","steps":["trace[365094446] 'read index received' (duration: 107.555591ms)","trace[365094446] 'applied index is now lower than readState.Index' (duration: 18.989172ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:13.253651Z","caller":"traceutil/trace.go:171","msg":"trace[1015832492] transaction","detail":"{read_only:false; response_revision:9222; number_of_response:1; }","duration":"195.295758ms","start":"2026-04-07T00:23:13.058296Z","end":"2026-04-07T00:23:13.253591Z","steps":["trace[1015832492] 'process raft request' (duration: 195.047681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:13.253746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.800631ms","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-04-07T00:23:13.253794Z","caller":"traceutil/trace.go:171","msg":"trace[1126958318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9222; }","duration":"126.897014ms","start":"2026-04-07T00:23:13.126882Z","end":"2026-04-07T00:23:13.253779Z","steps":["trace[1126958318] 'agreement among raft nodes before linearized reading' (duration: 126.717279ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:24:15.373534Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e5729930cf7e1f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:24:15.382539Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T00:24:15.382656Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T00:26:37.199331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8272} {"level":"info","ts":"2026-04-07T00:26:37.27408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8272,"took":"72.364514ms","hash":3035770333,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":26673152,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-07T00:26:37.27415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3035770333,"revision":8272,"compact-revision":6099} {"level":"info","ts":"2026-04-07T00:26:55.272473Z","caller":"traceutil/trace.go:171","msg":"trace[576142856] transaction","detail":"{read_only:false; response_revision:11064; number_of_response:1; }","duration":"271.37594ms","start":"2026-04-07T00:26:55.001072Z","end":"2026-04-07T00:26:55.272448Z","steps":["trace[576142856] 'process raft request' (duration: 271.244497ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:31:37.210982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10961} {"level":"info","ts":"2026-04-07T00:31:37.291615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10961,"took":"78.048482ms","hash":4180285150,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":28405760,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T00:31:37.29167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4180285150,"revision":10961,"compact-revision":8272} {"level":"info","ts":"2026-04-07T00:33:06.076606Z","caller":"traceutil/trace.go:171","msg":"trace[818078398] transaction","detail":"{read_only:false; response_revision:13568; number_of_response:1; }","duration":"113.602195ms","start":"2026-04-07T00:33:05.962971Z","end":"2026-04-07T00:33:06.076573Z","steps":["trace[818078398] 'process raft request' (duration: 113.373029ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:33:22.684465Z","caller":"traceutil/trace.go:171","msg":"trace[230634043] transaction","detail":"{read_only:false; response_revision:13667; number_of_response:1; }","duration":"291.914121ms","start":"2026-04-07T00:33:22.392517Z","end":"2026-04-07T00:33:22.684431Z","steps":["trace[230634043] 'process raft request' (duration: 291.703145ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:33:22.829937Z","caller":"traceutil/trace.go:171","msg":"trace[2130788826] linearizableReadLoop","detail":"{readStateIndex:14139; appliedIndex:14137; }","duration":"326.93935ms","start":"2026-04-07T00:33:22.502973Z","end":"2026-04-07T00:33:22.829913Z","steps":["trace[2130788826] 'read index received' (duration: 181.307709ms)","trace[2130788826] 'applied index is now lower than readState.Index' (duration: 145.630501ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:33:22.830127Z","caller":"traceutil/trace.go:171","msg":"trace[928838816] transaction","detail":"{read_only:false; response_revision:13669; number_of_response:1; }","duration":"237.853249ms","start":"2026-04-07T00:33:22.592222Z","end":"2026-04-07T00:33:22.830076Z","steps":["trace[928838816] 'process raft request' (duration: 237.636243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:33:22.830219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.209277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-07T00:33:22.830264Z","caller":"traceutil/trace.go:171","msg":"trace[1574106290] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:13669; }","duration":"327.33025ms","start":"2026-04-07T00:33:22.502921Z","end":"2026-04-07T00:33:22.830251Z","steps":["trace[1574106290] 'agreement among raft nodes before linearized reading' (duration: 327.121154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:33:22.830304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:33:22.502895Z","time spent":"327.400872ms","remote":"127.0.0.1:41702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-04-07T00:33:22.830264Z","caller":"traceutil/trace.go:171","msg":"trace[1117844811] transaction","detail":"{read_only:false; response_revision:13668; number_of_response:1; }","duration":"431.210534ms","start":"2026-04-07T00:33:22.39898Z","end":"2026-04-07T00:33:22.830191Z","steps":["trace[1117844811] 'process raft request' (duration: 430.702559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:33:22.830568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:33:22.398963Z","time spent":"431.466771ms","remote":"127.0.0.1:41696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:33:22.830833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.072485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:33:22.830869Z","caller":"traceutil/trace.go:171","msg":"trace[746763784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13669; }","duration":"248.142327ms","start":"2026-04-07T00:33:22.582716Z","end":"2026-04-07T00:33:22.830858Z","steps":["trace[746763784] 'agreement among raft nodes before linearized reading' (duration: 248.084225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:33:22.830899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.58802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:33:22.830963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.207582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T00:33:22.831037Z","caller":"traceutil/trace.go:171","msg":"trace[846679586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13669; }","duration":"134.322685ms","start":"2026-04-07T00:33:22.696696Z","end":"2026-04-07T00:33:22.831018Z","steps":["trace[846679586] 'agreement among raft nodes before linearized reading' (duration: 134.100089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:33:22.831037Z","caller":"traceutil/trace.go:171","msg":"trace[1913277710] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:13669; }","duration":"316.787515ms","start":"2026-04-07T00:33:22.514221Z","end":"2026-04-07T00:33:22.831009Z","steps":["trace[1913277710] 'agreement among raft nodes before linearized reading' (duration: 316.567139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:33:22.831309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:33:22.514205Z","time spent":"317.053073ms","remote":"127.0.0.1:41300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:34:59.402681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.341856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-04-07T00:34:59.402806Z","caller":"traceutil/trace.go:171","msg":"trace[353455677] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14730; }","duration":"129.493759ms","start":"2026-04-07T00:34:59.273289Z","end":"2026-04-07T00:34:59.402782Z","steps":["trace[353455677] 'range keys from in-memory index tree' (duration: 129.170841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:59.402784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.753904ms","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-04-07T00:34:59.40288Z","caller":"traceutil/trace.go:171","msg":"trace[2119819992] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14730; }","duration":"131.913577ms","start":"2026-04-07T00:34:59.270937Z","end":"2026-04-07T00:34:59.40285Z","steps":["trace[2119819992] 'range keys from in-memory index tree' (duration: 131.696561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:59.403232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.311098ms","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-04-07T00:34:59.403267Z","caller":"traceutil/trace.go:171","msg":"trace[534821132] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14730; }","duration":"132.38722ms","start":"2026-04-07T00:34:59.27087Z","end":"2026-04-07T00:34:59.403257Z","steps":["trace[534821132] 'range keys from in-memory index tree' (duration: 132.091882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:59.403432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.752144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T00:34:59.403515Z","caller":"traceutil/trace.go:171","msg":"trace[1514516300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14730; }","duration":"111.880647ms","start":"2026-04-07T00:34:59.291616Z","end":"2026-04-07T00:34:59.403496Z","steps":["trace[1514516300] 'range keys from in-memory index tree' (duration: 111.577009ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:59.530219Z","caller":"traceutil/trace.go:171","msg":"trace[740918224] transaction","detail":"{read_only:false; response_revision:14731; number_of_response:1; }","duration":"122.452622ms","start":"2026-04-07T00:34:59.407736Z","end":"2026-04-07T00:34:59.530188Z","steps":["trace[740918224] 'process raft request' (duration: 122.286828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:35:00.812531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.836487ms","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":"info","ts":"2026-04-07T00:35:00.812672Z","caller":"traceutil/trace.go:171","msg":"trace[91260123] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:14737; }","duration":"180.024272ms","start":"2026-04-07T00:35:00.632616Z","end":"2026-04-07T00:35:00.812641Z","steps":["trace[91260123] 'count revisions from in-memory index tree' (duration: 179.748114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:35:00.812859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.41524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:35:00.812944Z","caller":"traceutil/trace.go:171","msg":"trace[799482103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14737; }","duration":"230.550014ms","start":"2026-04-07T00:35:00.582371Z","end":"2026-04-07T00:35:00.812921Z","steps":["trace[799482103] 'range keys from in-memory index tree' (duration: 230.326158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:35:00.813149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.611777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-04-07T00:35:00.813248Z","caller":"traceutil/trace.go:171","msg":"trace[1239602218] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14737; }","duration":"200.761101ms","start":"2026-04-07T00:35:00.612461Z","end":"2026-04-07T00:35:00.813222Z","steps":["trace[1239602218] 'range keys from in-memory index tree' (duration: 200.407271ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:37.217215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13002} {"level":"info","ts":"2026-04-07T00:36:37.293041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13002,"took":"72.938078ms","hash":2598330107,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T00:36:37.293094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2598330107,"revision":13002,"compact-revision":10961} {"level":"info","ts":"2026-04-07T00:40:39.929664Z","caller":"traceutil/trace.go:171","msg":"trace[1786598379] transaction","detail":"{read_only:false; response_revision:17259; number_of_response:1; }","duration":"106.946397ms","start":"2026-04-07T00:40:39.822671Z","end":"2026-04-07T00:40:39.929617Z","steps":["trace[1786598379] 'process raft request' (duration: 65.15431ms)","trace[1786598379] 'compare' (duration: 41.369566ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:40:45.817299Z","caller":"traceutil/trace.go:171","msg":"trace[671562492] transaction","detail":"{read_only:false; response_revision:17306; number_of_response:1; }","duration":"655.264683ms","start":"2026-04-07T00:40:45.162016Z","end":"2026-04-07T00:40:45.817281Z","steps":["trace[671562492] 'process raft request' (duration: 655.14161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:40:45.817421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:40:45.162006Z","time spent":"655.353846ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:40:45.856363Z","caller":"traceutil/trace.go:171","msg":"trace[2113990906] transaction","detail":"{read_only:false; response_revision:17307; number_of_response:1; }","duration":"682.439792ms","start":"2026-04-07T00:40:45.173896Z","end":"2026-04-07T00:40:45.856336Z","steps":["trace[2113990906] 'process raft request' (duration: 663.567465ms)","trace[2113990906] 'compare' (duration: 18.60536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:40:45.856522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:40:45.17386Z","time spent":"682.578876ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:40:45.912557Z","caller":"traceutil/trace.go:171","msg":"trace[1348232991] linearizableReadLoop","detail":"{readStateIndex:17896; appliedIndex:17893; }","duration":"328.227648ms","start":"2026-04-07T00:40:45.584306Z","end":"2026-04-07T00:40:45.912534Z","steps":["trace[1348232991] 'read index received' (duration: 232.849124ms)","trace[1348232991] 'applied index is now lower than readState.Index' (duration: 95.377414ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:40:45.913078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.723771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:40:45.913228Z","caller":"traceutil/trace.go:171","msg":"trace[1697311041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17307; }","duration":"328.923707ms","start":"2026-04-07T00:40:45.584272Z","end":"2026-04-07T00:40:45.913195Z","steps":["trace[1697311041] 'agreement among raft nodes before linearized reading' (duration: 328.617199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:40:45.913294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:40:45.584259Z","time spent":"329.02291ms","remote":"127.0.0.1:41206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:40:45.913302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.065621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-cjt9v\" ","response":"range_response_count:1 size:20869"} {"level":"info","ts":"2026-04-07T00:40:45.913355Z","caller":"traceutil/trace.go:171","msg":"trace[1607914415] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-cjt9v; range_end:; response_count:1; response_revision:17308; }","duration":"189.162433ms","start":"2026-04-07T00:40:45.724179Z","end":"2026-04-07T00:40:45.913341Z","steps":["trace[1607914415] 'agreement among raft nodes before linearized reading' (duration: 188.856405ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:41:37.223399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15364} {"level":"info","ts":"2026-04-07T00:41:37.32215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15364,"took":"94.704464ms","hash":2872323263,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":33484800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:41:37.322226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2872323263,"revision":15364,"compact-revision":13002} {"level":"info","ts":"2026-04-07T00:44:51.001268Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e5729930cf7e1f","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:44:51.007142Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-07T00:44:51.00726Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-07T00:46:13.640347Z","caller":"traceutil/trace.go:171","msg":"trace[725936844] transaction","detail":"{read_only:false; response_revision:19897; number_of_response:1; }","duration":"208.35798ms","start":"2026-04-07T00:46:13.431953Z","end":"2026-04-07T00:46:13.640311Z","steps":["trace[725936844] 'process raft request' (duration: 208.082533ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:46:13.881789Z","caller":"traceutil/trace.go:171","msg":"trace[1397502290] linearizableReadLoop","detail":"{readStateIndex:20572; appliedIndex:20571; }","duration":"180.829001ms","start":"2026-04-07T00:46:13.700926Z","end":"2026-04-07T00:46:13.881755Z","steps":["trace[1397502290] 'read index received' (duration: 83.914544ms)","trace[1397502290] 'applied index is now lower than readState.Index' (duration: 96.913297ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:46:13.88196Z","caller":"traceutil/trace.go:171","msg":"trace[768672194] transaction","detail":"{read_only:false; response_revision:19898; number_of_response:1; }","duration":"228.685788ms","start":"2026-04-07T00:46:13.653259Z","end":"2026-04-07T00:46:13.881945Z","steps":["trace[768672194] 'process raft request' (duration: 131.622497ms)","trace[768672194] 'compare' (duration: 96.745423ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:46:13.882187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.696929ms","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-04-07T00:46:13.882308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.370426ms","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-04-07T00:46:13.882365Z","caller":"traceutil/trace.go:171","msg":"trace[1947795652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19898; }","duration":"179.938275ms","start":"2026-04-07T00:46:13.702405Z","end":"2026-04-07T00:46:13.882344Z","steps":["trace[1947795652] 'agreement among raft nodes before linearized reading' (duration: 179.591676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:46:13.882432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.890034ms","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-04-07T00:46:13.88255Z","caller":"traceutil/trace.go:171","msg":"trace[1501746619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19898; }","duration":"166.049778ms","start":"2026-04-07T00:46:13.716469Z","end":"2026-04-07T00:46:13.882519Z","steps":["trace[1501746619] 'agreement among raft nodes before linearized reading' (duration: 165.720449ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:46:13.88254Z","caller":"traceutil/trace.go:171","msg":"trace[2026518762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19898; }","duration":"181.667854ms","start":"2026-04-07T00:46:13.700847Z","end":"2026-04-07T00:46:13.882514Z","steps":["trace[2026518762] 'agreement among raft nodes before linearized reading' (duration: 181.234982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:46:14.208172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.933541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:46:14.208618Z","caller":"traceutil/trace.go:171","msg":"trace[1248255873] transaction","detail":"{read_only:false; response_revision:19899; number_of_response:1; }","duration":"321.603783ms","start":"2026-04-07T00:46:13.88697Z","end":"2026-04-07T00:46:14.208574Z","steps":["trace[1248255873] 'process raft request' (duration: 158.158867ms)","trace[1248255873] 'compare' (duration: 162.703075ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:46:14.208674Z","caller":"traceutil/trace.go:171","msg":"trace[283079167] transaction","detail":"{read_only:false; response_revision:19900; number_of_response:1; }","duration":"320.527523ms","start":"2026-04-07T00:46:13.888121Z","end":"2026-04-07T00:46:14.208648Z","steps":["trace[283079167] 'process raft request' (duration: 320.374758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:46:14.208771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:46:13.888096Z","time spent":"320.630866ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:46:14.208847Z","caller":"traceutil/trace.go:171","msg":"trace[2041764404] linearizableReadLoop","detail":"{readStateIndex:20575; appliedIndex:20572; }","duration":"169.578767ms","start":"2026-04-07T00:46:14.03924Z","end":"2026-04-07T00:46:14.208818Z","steps":["trace[2041764404] 'read index received' (duration: 5.900084ms)","trace[2041764404] 'applied index is now lower than readState.Index' (duration: 163.671603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:46:14.208878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:46:13.886945Z","time spent":"321.764668ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:46:14.208917Z","caller":"traceutil/trace.go:171","msg":"trace[26571901] transaction","detail":"{read_only:false; response_revision:19901; number_of_response:1; }","duration":"320.674428ms","start":"2026-04-07T00:46:13.888212Z","end":"2026-04-07T00:46:14.208887Z","steps":["trace[26571901] 'process raft request' (duration: 320.38721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:46:14.208982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.71562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:46:14.209085Z","caller":"traceutil/trace.go:171","msg":"trace[1159727082] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:19901; }","duration":"169.887756ms","start":"2026-04-07T00:46:14.039182Z","end":"2026-04-07T00:46:14.209069Z","steps":["trace[1159727082] 'agreement among raft nodes before linearized reading' (duration: 169.709391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:46:14.209128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:46:13.888205Z","time spent":"320.788221ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:46:14.550244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.032615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:46:14.550326Z","caller":"traceutil/trace.go:171","msg":"trace[1952279920] transaction","detail":"{read_only:false; response_revision:19902; number_of_response:1; }","duration":"244.15655ms","start":"2026-04-07T00:46:14.306153Z","end":"2026-04-07T00:46:14.55031Z","steps":["trace[1952279920] 'process raft request' (duration: 81.977441ms)","trace[1952279920] 'compare' (duration: 161.768697ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:46:14.589237Z","caller":"traceutil/trace.go:171","msg":"trace[797866611] transaction","detail":"{read_only:false; response_revision:19903; number_of_response:1; }","duration":"281.42696ms","start":"2026-04-07T00:46:14.307776Z","end":"2026-04-07T00:46:14.589203Z","steps":["trace[797866611] 'process raft request' (duration: 281.145222ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:46:15.28902Z","caller":"traceutil/trace.go:171","msg":"trace[2089406978] transaction","detail":"{read_only:false; response_revision:19904; number_of_response:1; }","duration":"127.267884ms","start":"2026-04-07T00:46:15.161727Z","end":"2026-04-07T00:46:15.288983Z","steps":["trace[2089406978] 'process raft request' (duration: 126.999877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:46:15.291039Z","caller":"traceutil/trace.go:171","msg":"trace[591958555] transaction","detail":"{read_only:false; response_revision:19905; number_of_response:1; }","duration":"126.481713ms","start":"2026-04-07T00:46:15.164533Z","end":"2026-04-07T00:46:15.291014Z","steps":["trace[591958555] 'process raft request' (duration: 126.329798ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:46:37.231605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17943} {"level":"info","ts":"2026-04-07T00:46:37.344984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17943,"took":"109.176929ms","hash":1838194812,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":29626368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T00:46:37.345064Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1838194812,"revision":17943,"compact-revision":15364} {"level":"info","ts":"2026-04-07T00:49:57.585192Z","caller":"traceutil/trace.go:171","msg":"trace[1514882809] transaction","detail":"{read_only:false; response_revision:22221; number_of_response:1; }","duration":"234.867421ms","start":"2026-04-07T00:49:57.350292Z","end":"2026-04-07T00:49:57.58516Z","steps":["trace[1514882809] 'process raft request' (duration: 234.677016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:49:57.838119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.020213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:49:57.838243Z","caller":"traceutil/trace.go:171","msg":"trace[621551031] linearizableReadLoop","detail":"{readStateIndex:22971; appliedIndex:22970; }","duration":"485.658801ms","start":"2026-04-07T00:49:57.352564Z","end":"2026-04-07T00:49:57.838223Z","steps":["trace[621551031] 'read index received' (duration: 232.585959ms)","trace[621551031] 'applied index is now lower than readState.Index' (duration: 253.071182ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:49:57.83857Z","caller":"traceutil/trace.go:171","msg":"trace[1355750832] transaction","detail":"{read_only:false; response_revision:22222; number_of_response:1; }","duration":"487.852441ms","start":"2026-04-07T00:49:57.350487Z","end":"2026-04-07T00:49:57.838339Z","steps":["trace[1355750832] 'process raft request' (duration: 332.50188ms)","trace[1355750832] 'compare' (duration: 154.860358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:49:57.838766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:49:57.350477Z","time spent":"488.1877ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:49:57.838808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.245467ms","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-04-07T00:49:57.838937Z","caller":"traceutil/trace.go:171","msg":"trace[140073171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22222; }","duration":"486.344699ms","start":"2026-04-07T00:49:57.352545Z","end":"2026-04-07T00:49:57.83889Z","steps":["trace[140073171] 'agreement among raft nodes before linearized reading' (duration: 486.102243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:49:57.839031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:49:57.35248Z","time spent":"486.533505ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T00:51:18.643316Z","caller":"traceutil/trace.go:171","msg":"trace[1293993427] linearizableReadLoop","detail":"{readStateIndex:23896; appliedIndex:23894; }","duration":"125.115806ms","start":"2026-04-07T00:51:18.518168Z","end":"2026-04-07T00:51:18.643284Z","steps":["trace[1293993427] 'read index received' (duration: 4.236488ms)","trace[1293993427] 'applied index is now lower than readState.Index' (duration: 120.878138ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:51:18.643368Z","caller":"traceutil/trace.go:171","msg":"trace[548742090] transaction","detail":"{read_only:false; response_revision:23126; number_of_response:1; }","duration":"136.301318ms","start":"2026-04-07T00:51:18.507031Z","end":"2026-04-07T00:51:18.643333Z","steps":["trace[548742090] 'process raft request' (duration: 111.004871ms)","trace[548742090] 'compare' (duration: 25.104762ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:51:18.643485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.29274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:51:18.643532Z","caller":"traceutil/trace.go:171","msg":"trace[1555502244] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:23126; }","duration":"125.363912ms","start":"2026-04-07T00:51:18.518154Z","end":"2026-04-07T00:51:18.643518Z","steps":["trace[1555502244] 'agreement among raft nodes before linearized reading' (duration: 125.261119ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:51:37.239151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20024} {"level":"info","ts":"2026-04-07T00:51:37.34934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20024,"took":"107.179476ms","hash":3340543664,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":42094592,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-07T00:51:37.349416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3340543664,"revision":20024,"compact-revision":17943} {"level":"warn","ts":"2026-04-07T00:53:12.913829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.154672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:53:12.913981Z","caller":"traceutil/trace.go:171","msg":"trace[1614450395] transaction","detail":"{read_only:false; response_revision:24247; number_of_response:1; }","duration":"290.886648ms","start":"2026-04-07T00:53:12.623076Z","end":"2026-04-07T00:53:12.913962Z","steps":["trace[1614450395] 'process raft request' (duration: 124.506839ms)","trace[1614450395] 'compare' (duration: 165.968127ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:13.377853Z","caller":"traceutil/trace.go:171","msg":"trace[1606687275] transaction","detail":"{read_only:false; response_revision:24250; number_of_response:1; }","duration":"400.178362ms","start":"2026-04-07T00:53:12.977647Z","end":"2026-04-07T00:53:13.377826Z","steps":["trace[1606687275] 'process raft request' (duration: 399.999527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:13.378043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:12.977619Z","time spent":"400.338356ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:13.5317Z","caller":"traceutil/trace.go:171","msg":"trace[978064646] linearizableReadLoop","detail":"{readStateIndex:25053; appliedIndex:25051; }","duration":"514.471216ms","start":"2026-04-07T00:53:13.017199Z","end":"2026-04-07T00:53:13.53167Z","steps":["trace[978064646] 'read index received' (duration: 360.551165ms)","trace[978064646] 'applied index is now lower than readState.Index' (duration: 153.918631ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:13.531783Z","caller":"traceutil/trace.go:171","msg":"trace[2027157573] transaction","detail":"{read_only:false; response_revision:24251; number_of_response:1; }","duration":"517.743377ms","start":"2026-04-07T00:53:13.01401Z","end":"2026-04-07T00:53:13.531753Z","steps":["trace[2027157573] 'process raft request' (duration: 471.595737ms)","trace[2027157573] 'compare' (duration: 45.81592ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:13.531833Z","caller":"traceutil/trace.go:171","msg":"trace[644914821] transaction","detail":"{read_only:false; response_revision:24252; number_of_response:1; }","duration":"428.128883ms","start":"2026-04-07T00:53:13.103682Z","end":"2026-04-07T00:53:13.531811Z","steps":["trace[644914821] 'process raft request' (duration: 427.909867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:13.531898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:13.013977Z","time spent":"517.8625ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:13.531934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:13.103665Z","time spent":"428.217125ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:13.531938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.724253ms","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-04-07T00:53:13.531993Z","caller":"traceutil/trace.go:171","msg":"trace[1990808461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24252; }","duration":"514.818506ms","start":"2026-04-07T00:53:13.01716Z","end":"2026-04-07T00:53:13.531979Z","steps":["trace[1990808461] 'agreement among raft nodes before linearized reading' (duration: 514.652851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:13.532021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:13.017146Z","time spent":"514.869066ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:53:13.532012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.392456ms","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-04-07T00:53:13.532073Z","caller":"traceutil/trace.go:171","msg":"trace[68484948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24252; }","duration":"513.512329ms","start":"2026-04-07T00:53:13.018546Z","end":"2026-04-07T00:53:13.532058Z","steps":["trace[68484948] 'agreement among raft nodes before linearized reading' (duration: 513.303203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:13.53212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:13.018524Z","time spent":"513.584221ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T00:53:13.532114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.882552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:53:13.532192Z","caller":"traceutil/trace.go:171","msg":"trace[87113994] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24252; }","duration":"430.010706ms","start":"2026-04-07T00:53:13.102161Z","end":"2026-04-07T00:53:13.532172Z","steps":["trace[87113994] 'agreement among raft nodes before linearized reading' (duration: 429.81768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:13.532233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:13.102137Z","time spent":"430.087878ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T00:53:17.728783Z","caller":"traceutil/trace.go:171","msg":"trace[1235851451] linearizableReadLoop","detail":"{readStateIndex:25084; appliedIndex:25082; }","duration":"110.105547ms","start":"2026-04-07T00:53:17.618653Z","end":"2026-04-07T00:53:17.728759Z","steps":["trace[1235851451] 'read index received' (duration: 87.263229ms)","trace[1235851451] 'applied index is now lower than readState.Index' (duration: 22.841358ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:17.728811Z","caller":"traceutil/trace.go:171","msg":"trace[2077582200] transaction","detail":"{read_only:false; response_revision:24280; number_of_response:1; }","duration":"115.351083ms","start":"2026-04-07T00:53:17.61341Z","end":"2026-04-07T00:53:17.728761Z","steps":["trace[2077582200] 'process raft request' (duration: 92.373811ms)","trace[2077582200] 'compare' (duration: 22.433187ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:17.728796Z","caller":"traceutil/trace.go:171","msg":"trace[1363747230] transaction","detail":"{read_only:false; response_revision:24281; number_of_response:1; }","duration":"113.81358ms","start":"2026-04-07T00:53:17.614955Z","end":"2026-04-07T00:53:17.728769Z","steps":["trace[1363747230] 'process raft request' (duration: 113.701977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:17.729011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.447144ms","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-04-07T00:53:17.729009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.094167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T00:53:17.729091Z","caller":"traceutil/trace.go:171","msg":"trace[1909948107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24281; }","duration":"174.588748ms","start":"2026-04-07T00:53:17.554486Z","end":"2026-04-07T00:53:17.729075Z","steps":["trace[1909948107] 'agreement among raft nodes before linearized reading' (duration: 174.379482ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:17.729116Z","caller":"traceutil/trace.go:171","msg":"trace[1975504042] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24281; }","duration":"173.239831ms","start":"2026-04-07T00:53:17.555856Z","end":"2026-04-07T00:53:17.729096Z","steps":["trace[1975504042] 'agreement among raft nodes before linearized reading' (duration: 172.945823ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:17.856282Z","caller":"traceutil/trace.go:171","msg":"trace[897597354] transaction","detail":"{read_only:false; response_revision:24282; number_of_response:1; }","duration":"233.928476ms","start":"2026-04-07T00:53:17.622321Z","end":"2026-04-07T00:53:17.856249Z","steps":["trace[897597354] 'process raft request' (duration: 229.724679ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:17.882017Z","caller":"traceutil/trace.go:171","msg":"trace[552426176] transaction","detail":"{read_only:false; response_revision:24283; number_of_response:1; }","duration":"148.505459ms","start":"2026-04-07T00:53:17.73347Z","end":"2026-04-07T00:53:17.881976Z","steps":["trace[552426176] 'process raft request' (duration: 148.198001ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:17.882033Z","caller":"traceutil/trace.go:171","msg":"trace[624615596] transaction","detail":"{read_only:false; response_revision:24284; number_of_response:1; }","duration":"148.017765ms","start":"2026-04-07T00:53:17.73398Z","end":"2026-04-07T00:53:17.881997Z","steps":["trace[624615596] 'process raft request' (duration: 147.872611ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:17.882317Z","caller":"traceutil/trace.go:171","msg":"trace[148738488] transaction","detail":"{read_only:false; response_revision:24285; number_of_response:1; }","duration":"142.80528ms","start":"2026-04-07T00:53:17.739498Z","end":"2026-04-07T00:53:17.882303Z","steps":["trace[148738488] 'process raft request' (duration: 142.420579ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:18.154944Z","caller":"traceutil/trace.go:171","msg":"trace[68936850] transaction","detail":"{read_only:false; response_revision:24286; number_of_response:1; }","duration":"102.506315ms","start":"2026-04-07T00:53:18.052408Z","end":"2026-04-07T00:53:18.154914Z","steps":["trace[68936850] 'process raft request' (duration: 102.34962ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:18.711433Z","caller":"traceutil/trace.go:171","msg":"trace[1104557904] linearizableReadLoop","detail":"{readStateIndex:25091; appliedIndex:25089; }","duration":"278.261816ms","start":"2026-04-07T00:53:18.433142Z","end":"2026-04-07T00:53:18.711404Z","steps":["trace[1104557904] 'read index received' (duration: 8.699853ms)","trace[1104557904] 'applied index is now lower than readState.Index' (duration: 269.560993ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:18.711527Z","caller":"traceutil/trace.go:171","msg":"trace[2033944394] transaction","detail":"{read_only:false; response_revision:24288; number_of_response:1; }","duration":"327.775809ms","start":"2026-04-07T00:53:18.383705Z","end":"2026-04-07T00:53:18.71148Z","steps":["trace[2033944394] 'process raft request' (duration: 327.535493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:18.711639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.471462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:18.711894Z","caller":"traceutil/trace.go:171","msg":"trace[118662061] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24288; }","duration":"278.77796ms","start":"2026-04-07T00:53:18.433096Z","end":"2026-04-07T00:53:18.711874Z","steps":["trace[118662061] 'agreement among raft nodes before linearized reading' (duration: 278.463471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:18.712334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:18.383675Z","time spent":"328.103818ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:18.738954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.958839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:18.739026Z","caller":"traceutil/trace.go:171","msg":"trace[1366455619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24289; }","duration":"155.083312ms","start":"2026-04-07T00:53:18.583927Z","end":"2026-04-07T00:53:18.739011Z","steps":["trace[1366455619] 'agreement among raft nodes before linearized reading' (duration: 154.940038ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:18.739181Z","caller":"traceutil/trace.go:171","msg":"trace[991263275] transaction","detail":"{read_only:false; response_revision:24289; number_of_response:1; }","duration":"170.619676ms","start":"2026-04-07T00:53:18.568534Z","end":"2026-04-07T00:53:18.739154Z","steps":["trace[991263275] 'process raft request' (duration: 170.160614ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.01334Z","caller":"traceutil/trace.go:171","msg":"trace[1819518510] transaction","detail":"{read_only:false; response_revision:24293; number_of_response:1; }","duration":"422.858696ms","start":"2026-04-07T00:53:19.590457Z","end":"2026-04-07T00:53:20.013315Z","steps":["trace[1819518510] 'process raft request' (duration: 422.684181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.013526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:19.590437Z","time spent":"422.971948ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:20.01804Z","caller":"traceutil/trace.go:171","msg":"trace[398985816] transaction","detail":"{read_only:false; response_revision:24296; number_of_response:1; }","duration":"283.422879ms","start":"2026-04-07T00:53:19.734592Z","end":"2026-04-07T00:53:20.018015Z","steps":["trace[398985816] 'process raft request' (duration: 283.342867ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.018236Z","caller":"traceutil/trace.go:171","msg":"trace[1970783725] linearizableReadLoop","detail":"{readStateIndex:25097; appliedIndex:25095; }","duration":"385.915454ms","start":"2026-04-07T00:53:19.632295Z","end":"2026-04-07T00:53:20.018211Z","steps":["trace[1970783725] 'read index received' (duration: 380.803701ms)","trace[1970783725] 'applied index is now lower than readState.Index' (duration: 5.110473ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:20.018303Z","caller":"traceutil/trace.go:171","msg":"trace[412958933] transaction","detail":"{read_only:false; response_revision:24294; number_of_response:1; }","duration":"394.208765ms","start":"2026-04-07T00:53:19.624084Z","end":"2026-04-07T00:53:20.018293Z","steps":["trace[412958933] 'process raft request' (duration: 393.68305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.018389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:19.624066Z","time spent":"394.260097ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:20.018472Z","caller":"traceutil/trace.go:171","msg":"trace[1343184604] transaction","detail":"{read_only:false; response_revision:24295; number_of_response:1; }","duration":"284.668404ms","start":"2026-04-07T00:53:19.733795Z","end":"2026-04-07T00:53:20.018464Z","steps":["trace[1343184604] 'process raft request' (duration: 284.102228ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.018535Z","caller":"traceutil/trace.go:171","msg":"trace[2135151463] transaction","detail":"{read_only:false; response_revision:24297; number_of_response:1; }","duration":"115.371543ms","start":"2026-04-07T00:53:19.903151Z","end":"2026-04-07T00:53:20.018522Z","steps":["trace[2135151463] 'process raft request' (duration: 114.826508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.018719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.418857ms","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-04-07T00:53:20.018751Z","caller":"traceutil/trace.go:171","msg":"trace[1555510960] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24297; }","duration":"386.475948ms","start":"2026-04-07T00:53:19.632266Z","end":"2026-04-07T00:53:20.018742Z","steps":["trace[1555510960] 'agreement among raft nodes before linearized reading' (duration: 386.422527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.018778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:19.632252Z","time spent":"386.52064ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-07T00:53:20.019756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.980439ms","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-04-07T00:53:20.019912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.925133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:20.019984Z","caller":"traceutil/trace.go:171","msg":"trace[1245555264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24297; }","duration":"113.986625ms","start":"2026-04-07T00:53:19.905976Z","end":"2026-04-07T00:53:20.019962Z","steps":["trace[1245555264] 'agreement among raft nodes before linearized reading' (duration: 113.894692ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.020061Z","caller":"traceutil/trace.go:171","msg":"trace[1024128362] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:24297; }","duration":"331.190875ms","start":"2026-04-07T00:53:19.6887Z","end":"2026-04-07T00:53:20.019891Z","steps":["trace[1024128362] 'agreement among raft nodes before linearized reading' (duration: 330.870186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.020174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:19.688679Z","time spent":"331.477272ms","remote":"127.0.0.1:45846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":74,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:53:20.019767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.118067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-07T00:53:20.020357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.825709ms","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-04-07T00:53:20.020381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.778388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T00:53:20.020396Z","caller":"traceutil/trace.go:171","msg":"trace[415216407] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:24297; }","duration":"129.788107ms","start":"2026-04-07T00:53:19.890589Z","end":"2026-04-07T00:53:20.020377Z","steps":["trace[415216407] 'agreement among raft nodes before linearized reading' (duration: 129.016696ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.020426Z","caller":"traceutil/trace.go:171","msg":"trace[4099762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24297; }","duration":"133.928932ms","start":"2026-04-07T00:53:19.886477Z","end":"2026-04-07T00:53:20.020406Z","steps":["trace[4099762] 'agreement among raft nodes before linearized reading' (duration: 133.709956ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.020444Z","caller":"traceutil/trace.go:171","msg":"trace[1488727132] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24297; }","duration":"133.891802ms","start":"2026-04-07T00:53:19.886534Z","end":"2026-04-07T00:53:20.020426Z","steps":["trace[1488727132] 'agreement among raft nodes before linearized reading' (duration: 133.699586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.020506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.857487ms","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-04-07T00:53:20.020568Z","caller":"traceutil/trace.go:171","msg":"trace[1585568939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24297; }","duration":"159.954409ms","start":"2026-04-07T00:53:19.860599Z","end":"2026-04-07T00:53:20.020553Z","steps":["trace[1585568939] 'agreement among raft nodes before linearized reading' (duration: 159.809905ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.184361Z","caller":"traceutil/trace.go:171","msg":"trace[241428222] linearizableReadLoop","detail":"{readStateIndex:25101; appliedIndex:25100; }","duration":"159.078944ms","start":"2026-04-07T00:53:20.025258Z","end":"2026-04-07T00:53:20.184337Z","steps":["trace[241428222] 'read index received' (duration: 71.60799ms)","trace[241428222] 'applied index is now lower than readState.Index' (duration: 87.469494ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:20.184529Z","caller":"traceutil/trace.go:171","msg":"trace[1072048398] transaction","detail":"{read_only:false; response_revision:24298; number_of_response:1; }","duration":"159.882018ms","start":"2026-04-07T00:53:20.024615Z","end":"2026-04-07T00:53:20.184497Z","steps":["trace[1072048398] 'process raft request' (duration: 72.151947ms)","trace[1072048398] 'compare' (duration: 87.452463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:20.184581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.30234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-04-07T00:53:20.184616Z","caller":"traceutil/trace.go:171","msg":"trace[508920993] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:24298; }","duration":"159.388273ms","start":"2026-04-07T00:53:20.025216Z","end":"2026-04-07T00:53:20.184604Z","steps":["trace[508920993] 'agreement among raft nodes before linearized reading' (duration: 159.242259ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.473739Z","caller":"traceutil/trace.go:171","msg":"trace[860120131] transaction","detail":"{read_only:false; response_revision:24299; number_of_response:1; }","duration":"448.341818ms","start":"2026-04-07T00:53:20.025375Z","end":"2026-04-07T00:53:20.473716Z","steps":["trace[860120131] 'process raft request' (duration: 440.516889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.473882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:20.025356Z","time spent":"448.4492ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:20.473987Z","caller":"traceutil/trace.go:171","msg":"trace[569421705] linearizableReadLoop","detail":"{readStateIndex:25104; appliedIndex:25101; }","duration":"289.541181ms","start":"2026-04-07T00:53:20.184428Z","end":"2026-04-07T00:53:20.473969Z","steps":["trace[569421705] 'read index received' (duration: 281.477075ms)","trace[569421705] 'applied index is now lower than readState.Index' (duration: 8.063136ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:20.474103Z","caller":"traceutil/trace.go:171","msg":"trace[554351692] transaction","detail":"{read_only:false; response_revision:24301; number_of_response:1; }","duration":"447.619388ms","start":"2026-04-07T00:53:20.02646Z","end":"2026-04-07T00:53:20.474079Z","steps":["trace[554351692] 'process raft request' (duration: 447.449803ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.47414Z","caller":"traceutil/trace.go:171","msg":"trace[1109208111] transaction","detail":"{read_only:false; response_revision:24300; number_of_response:1; }","duration":"447.737701ms","start":"2026-04-07T00:53:20.026379Z","end":"2026-04-07T00:53:20.474117Z","steps":["trace[1109208111] 'process raft request' (duration: 447.301739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.4742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.858334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-07T00:53:20.474225Z","caller":"traceutil/trace.go:171","msg":"trace[1586084824] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24301; }","duration":"447.913665ms","start":"2026-04-07T00:53:20.026304Z","end":"2026-04-07T00:53:20.474217Z","steps":["trace[1586084824] 'agreement among raft nodes before linearized reading' (duration: 447.800992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.474246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:20.026297Z","time spent":"447.943926ms","remote":"127.0.0.1:41696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T00:53:20.474239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.788914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-07T00:53:20.474259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:20.026374Z","time spent":"447.814953ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:20.474239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:20.026444Z","time spent":"447.71679ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:20.474303Z","caller":"traceutil/trace.go:171","msg":"trace[567790782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24301; }","duration":"315.889447ms","start":"2026-04-07T00:53:20.158394Z","end":"2026-04-07T00:53:20.474283Z","steps":["trace[567790782] 'agreement among raft nodes before linearized reading' (duration: 315.690022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.474338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:20.158372Z","time spent":"315.957729ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:53:20.496489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.52364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9fe0a354-7650-45d3-852e-c33ddcbdbe61\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-04-07T00:53:20.496539Z","caller":"traceutil/trace.go:171","msg":"trace[356694946] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9fe0a354-7650-45d3-852e-c33ddcbdbe61; range_end:; response_count:1; response_revision:24302; }","duration":"308.604823ms","start":"2026-04-07T00:53:20.187921Z","end":"2026-04-07T00:53:20.496526Z","steps":["trace[356694946] 'agreement among raft nodes before linearized reading' (duration: 308.468219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.496538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.364506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-04-07T00:53:20.496612Z","caller":"traceutil/trace.go:171","msg":"trace[1705836131] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:24302; }","duration":"109.51245ms","start":"2026-04-07T00:53:20.387085Z","end":"2026-04-07T00:53:20.496598Z","steps":["trace[1705836131] 'agreement among raft nodes before linearized reading' (duration: 109.287124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:20.496567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:20.187903Z","time spent":"308.658224ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1299,"request content":"key:\"/registry/persistentvolumes/pvc-9fe0a354-7650-45d3-852e-c33ddcbdbe61\" "} {"level":"info","ts":"2026-04-07T00:53:20.649629Z","caller":"traceutil/trace.go:171","msg":"trace[1849579324] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"171.605244ms","start":"2026-04-07T00:53:20.477984Z","end":"2026-04-07T00:53:20.64959Z","steps":["trace[1849579324] 'process raft request' (duration: 158.19336ms)","trace[1849579324] 'compare' (duration: 13.173797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:20.650039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.204728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18366"} {"level":"info","ts":"2026-04-07T00:53:20.650117Z","caller":"traceutil/trace.go:171","msg":"trace[490134829] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24303; }","duration":"174.322351ms","start":"2026-04-07T00:53:20.475779Z","end":"2026-04-07T00:53:20.650101Z","steps":["trace[490134829] 'agreement among raft nodes before linearized reading' (duration: 174.056074ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.649779Z","caller":"traceutil/trace.go:171","msg":"trace[1247111866] linearizableReadLoop","detail":"{readStateIndex:25106; appliedIndex:25105; }","duration":"153.366615ms","start":"2026-04-07T00:53:20.496294Z","end":"2026-04-07T00:53:20.649661Z","steps":["trace[1247111866] 'read index received' (duration: 140.007062ms)","trace[1247111866] 'applied index is now lower than readState.Index' (duration: 13.354913ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:20.81058Z","caller":"traceutil/trace.go:171","msg":"trace[777211499] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"159.887077ms","start":"2026-04-07T00:53:20.65067Z","end":"2026-04-07T00:53:20.810557Z","steps":["trace[777211499] 'process raft request' (duration: 159.703962ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:20.81058Z","caller":"traceutil/trace.go:171","msg":"trace[1233694958] linearizableReadLoop","detail":"{readStateIndex:25108; appliedIndex:25108; }","duration":"132.43455ms","start":"2026-04-07T00:53:20.678113Z","end":"2026-04-07T00:53:20.810547Z","steps":["trace[1233694958] 'read index received' (duration: 132.42121ms)","trace[1233694958] 'applied index is now lower than readState.Index' (duration: 10.83µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:20.810813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.683277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-04-07T00:53:20.810881Z","caller":"traceutil/trace.go:171","msg":"trace[913533668] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24304; }","duration":"132.805311ms","start":"2026-04-07T00:53:20.678055Z","end":"2026-04-07T00:53:20.81086Z","steps":["trace[913533668] 'agreement among raft nodes before linearized reading' (duration: 132.549524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:21.124217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.08142ms","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":"warn","ts":"2026-04-07T00:53:21.124217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.38513ms","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-04-07T00:53:21.124281Z","caller":"traceutil/trace.go:171","msg":"trace[1820451331] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24305; }","duration":"210.188993ms","start":"2026-04-07T00:53:20.914076Z","end":"2026-04-07T00:53:21.124265Z","steps":["trace[1820451331] 'count revisions from in-memory index tree' (duration: 209.992507ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:21.124285Z","caller":"traceutil/trace.go:171","msg":"trace[269130764] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:24305; }","duration":"190.503363ms","start":"2026-04-07T00:53:20.933766Z","end":"2026-04-07T00:53:21.12427Z","steps":["trace[269130764] 'count revisions from in-memory index tree' (duration: 190.245165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:21.429928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.998775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:53:21.43003Z","caller":"traceutil/trace.go:171","msg":"trace[2035001355] linearizableReadLoop","detail":"{readStateIndex:25112; appliedIndex:25110; }","duration":"292.199714ms","start":"2026-04-07T00:53:21.137815Z","end":"2026-04-07T00:53:21.430014Z","steps":["trace[2035001355] 'read index received' (duration: 18.411894ms)","trace[2035001355] 'applied index is now lower than readState.Index' (duration: 273.78659ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:21.430267Z","caller":"traceutil/trace.go:171","msg":"trace[1416187320] transaction","detail":"{read_only:false; response_revision:24308; number_of_response:1; }","duration":"292.757569ms","start":"2026-04-07T00:53:21.137496Z","end":"2026-04-07T00:53:21.430254Z","steps":["trace[1416187320] 'process raft request' (duration: 149.355103ms)","trace[1416187320] 'compare' (duration: 142.701397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:21.430453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.623116ms","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":"warn","ts":"2026-04-07T00:53:21.430484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.785848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-04-07T00:53:21.430527Z","caller":"traceutil/trace.go:171","msg":"trace[232848945] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24308; }","duration":"292.745159ms","start":"2026-04-07T00:53:21.137763Z","end":"2026-04-07T00:53:21.430509Z","steps":["trace[232848945] 'agreement among raft nodes before linearized reading' (duration: 292.613306ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:21.430536Z","caller":"traceutil/trace.go:171","msg":"trace[1806627797] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24308; }","duration":"157.864161ms","start":"2026-04-07T00:53:21.272657Z","end":"2026-04-07T00:53:21.430521Z","steps":["trace[1806627797] 'agreement among raft nodes before linearized reading' (duration: 157.706426ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:22.379716Z","caller":"traceutil/trace.go:171","msg":"trace[1827562822] transaction","detail":"{read_only:false; response_revision:24310; number_of_response:1; }","duration":"361.334906ms","start":"2026-04-07T00:53:22.018356Z","end":"2026-04-07T00:53:22.379691Z","steps":["trace[1827562822] 'process raft request' (duration: 361.140141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:22.379868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.018325Z","time spent":"361.46684ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:22.392414Z","caller":"traceutil/trace.go:171","msg":"trace[889139875] transaction","detail":"{read_only:false; response_revision:24311; number_of_response:1; }","duration":"369.918066ms","start":"2026-04-07T00:53:22.022463Z","end":"2026-04-07T00:53:22.392381Z","steps":["trace[889139875] 'process raft request' (duration: 369.72949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:22.392575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.022441Z","time spent":"370.058311ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:22.39273Z","caller":"traceutil/trace.go:171","msg":"trace[596302174] linearizableReadLoop","detail":"{readStateIndex:25117; appliedIndex:25113; }","duration":"345.084033ms","start":"2026-04-07T00:53:22.047623Z","end":"2026-04-07T00:53:22.392707Z","steps":["trace[596302174] 'read index received' (duration: 331.867414ms)","trace[596302174] 'applied index is now lower than readState.Index' (duration: 13.215509ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:22.392809Z","caller":"traceutil/trace.go:171","msg":"trace[94982455] transaction","detail":"{read_only:false; response_revision:24312; number_of_response:1; }","duration":"368.902578ms","start":"2026-04-07T00:53:22.023884Z","end":"2026-04-07T00:53:22.392787Z","steps":["trace[94982455] 'process raft request' (duration: 368.428215ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:22.392884Z","caller":"traceutil/trace.go:171","msg":"trace[710032109] transaction","detail":"{read_only:false; response_revision:24313; number_of_response:1; }","duration":"368.374284ms","start":"2026-04-07T00:53:22.024491Z","end":"2026-04-07T00:53:22.392865Z","steps":["trace[710032109] 'process raft request' (duration: 368.127857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:22.392912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.023868Z","time spent":"368.993771ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:22.393001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.024483Z","time spent":"368.439575ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:22.39317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.546416ms","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-04-07T00:53:22.393222Z","caller":"traceutil/trace.go:171","msg":"trace[1604153398] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24313; }","duration":"345.600557ms","start":"2026-04-07T00:53:22.047607Z","end":"2026-04-07T00:53:22.393208Z","steps":["trace[1604153398] 'agreement among raft nodes before linearized reading' (duration: 345.522255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:22.39326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.04755Z","time spent":"345.7015ms","remote":"127.0.0.1:42584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:53:22.393329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.683098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:22.394181Z","caller":"traceutil/trace.go:171","msg":"trace[1250723627] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24313; }","duration":"317.46994ms","start":"2026-04-07T00:53:22.076603Z","end":"2026-04-07T00:53:22.394073Z","steps":["trace[1250723627] 'agreement among raft nodes before linearized reading' (duration: 316.491713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:22.394242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.076523Z","time spent":"317.700267ms","remote":"127.0.0.1:46026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:53:22.584548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.191938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T00:53:22.584609Z","caller":"traceutil/trace.go:171","msg":"trace[1411363230] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24313; }","duration":"105.294191ms","start":"2026-04-07T00:53:22.479299Z","end":"2026-04-07T00:53:22.584594Z","steps":["trace[1411363230] 'range keys from in-memory index tree' (duration: 105.041024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:22.584904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.741133ms","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-04-07T00:53:22.584922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.771263ms","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-04-07T00:53:22.585008Z","caller":"traceutil/trace.go:171","msg":"trace[1555370066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24313; }","duration":"106.862846ms","start":"2026-04-07T00:53:22.478119Z","end":"2026-04-07T00:53:22.584982Z","steps":["trace[1555370066] 'range keys from in-memory index tree' (duration: 106.394492ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:22.585022Z","caller":"traceutil/trace.go:171","msg":"trace[940463313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24313; }","duration":"106.925557ms","start":"2026-04-07T00:53:22.478073Z","end":"2026-04-07T00:53:22.584999Z","steps":["trace[940463313] 'range keys from in-memory index tree' (duration: 106.404103ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:22.812634Z","caller":"traceutil/trace.go:171","msg":"trace[151064811] transaction","detail":"{read_only:false; response_revision:24315; number_of_response:1; }","duration":"223.155466ms","start":"2026-04-07T00:53:22.589454Z","end":"2026-04-07T00:53:22.812609Z","steps":["trace[151064811] 'process raft request' (duration: 141.498393ms)","trace[151064811] 'compare' (duration: 81.379804ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:22.812662Z","caller":"traceutil/trace.go:171","msg":"trace[2069690154] transaction","detail":"{read_only:false; response_revision:24316; number_of_response:1; }","duration":"222.992061ms","start":"2026-04-07T00:53:22.58965Z","end":"2026-04-07T00:53:22.812642Z","steps":["trace[2069690154] 'process raft request' (duration: 222.880058ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:22.812736Z","caller":"traceutil/trace.go:171","msg":"trace[378033908] linearizableReadLoop","detail":"{readStateIndex:25123; appliedIndex:25118; }","duration":"157.700787ms","start":"2026-04-07T00:53:22.655022Z","end":"2026-04-07T00:53:22.812722Z","steps":["trace[378033908] 'read index received' (duration: 75.982053ms)","trace[378033908] 'applied index is now lower than readState.Index' (duration: 81.717824ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:22.812824Z","caller":"traceutil/trace.go:171","msg":"trace[2005932654] transaction","detail":"{read_only:false; response_revision:24317; number_of_response:1; }","duration":"222.94347ms","start":"2026-04-07T00:53:22.589869Z","end":"2026-04-07T00:53:22.812812Z","steps":["trace[2005932654] 'process raft request' (duration: 222.731904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:22.812998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.967094ms","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-04-07T00:53:22.813019Z","caller":"traceutil/trace.go:171","msg":"trace[1557245994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24319; }","duration":"158.038366ms","start":"2026-04-07T00:53:22.654975Z","end":"2026-04-07T00:53:22.813013Z","steps":["trace[1557245994] 'agreement among raft nodes before linearized reading' (duration: 157.947014ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:22.813101Z","caller":"traceutil/trace.go:171","msg":"trace[2059504806] transaction","detail":"{read_only:false; response_revision:24318; number_of_response:1; }","duration":"220.232494ms","start":"2026-04-07T00:53:22.592863Z","end":"2026-04-07T00:53:22.813096Z","steps":["trace[2059504806] 'process raft request' (duration: 219.774042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:22.813096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.943355ms","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:6"} {"level":"info","ts":"2026-04-07T00:53:22.813136Z","caller":"traceutil/trace.go:171","msg":"trace[1220023062] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24319; }","duration":"157.016327ms","start":"2026-04-07T00:53:22.656109Z","end":"2026-04-07T00:53:22.813125Z","steps":["trace[1220023062] 'agreement among raft nodes before linearized reading' (duration: 156.938805ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:22.81314Z","caller":"traceutil/trace.go:171","msg":"trace[1879132510] transaction","detail":"{read_only:false; response_revision:24319; number_of_response:1; }","duration":"219.527084ms","start":"2026-04-07T00:53:22.593603Z","end":"2026-04-07T00:53:22.81313Z","steps":["trace[1879132510] 'process raft request' (duration: 219.082922ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:22.977555Z","caller":"traceutil/trace.go:171","msg":"trace[1737173125] transaction","detail":"{read_only:false; response_revision:24320; number_of_response:1; }","duration":"160.767622ms","start":"2026-04-07T00:53:22.816755Z","end":"2026-04-07T00:53:22.977522Z","steps":["trace[1737173125] 'process raft request' (duration: 160.609678ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:23.000538Z","caller":"traceutil/trace.go:171","msg":"trace[2131363304] transaction","detail":"{read_only:false; response_revision:24321; number_of_response:1; }","duration":"181.737528ms","start":"2026-04-07T00:53:22.81877Z","end":"2026-04-07T00:53:23.000507Z","steps":["trace[2131363304] 'process raft request' (duration: 181.45371ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:23.000783Z","caller":"traceutil/trace.go:171","msg":"trace[1403452789] linearizableReadLoop","detail":"{readStateIndex:25126; appliedIndex:25123; }","duration":"160.742732ms","start":"2026-04-07T00:53:22.840002Z","end":"2026-04-07T00:53:23.000744Z","steps":["trace[1403452789] 'read index received' (duration: 137.384888ms)","trace[1403452789] 'applied index is now lower than readState.Index' (duration: 23.354574ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:23.000816Z","caller":"traceutil/trace.go:171","msg":"trace[118495050] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"170.661669ms","start":"2026-04-07T00:53:22.830123Z","end":"2026-04-07T00:53:23.000785Z","steps":["trace[118495050] 'process raft request' (duration: 170.301459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:23.001061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.034411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:53:23.001108Z","caller":"traceutil/trace.go:171","msg":"trace[227914112] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:24322; }","duration":"161.138233ms","start":"2026-04-07T00:53:22.839958Z","end":"2026-04-07T00:53:23.001096Z","steps":["trace[227914112] 'agreement among raft nodes before linearized reading' (duration: 160.887746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:23.316056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.809902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:53:23.316276Z","caller":"traceutil/trace.go:171","msg":"trace[1870641780] transaction","detail":"{read_only:false; response_revision:24324; number_of_response:1; }","duration":"176.699537ms","start":"2026-04-07T00:53:23.139557Z","end":"2026-04-07T00:53:23.316257Z","steps":["trace[1870641780] 'process raft request' (duration: 176.599324ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:23.316331Z","caller":"traceutil/trace.go:171","msg":"trace[1667942980] linearizableReadLoop","detail":"{readStateIndex:25127; appliedIndex:25126; }","duration":"274.106859ms","start":"2026-04-07T00:53:23.042171Z","end":"2026-04-07T00:53:23.316278Z","steps":["trace[1667942980] 'read index received' (duration: 92.907666ms)","trace[1667942980] 'applied index is now lower than readState.Index' (duration: 181.197133ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:23.316448Z","caller":"traceutil/trace.go:171","msg":"trace[3439283] transaction","detail":"{read_only:false; response_revision:24323; number_of_response:1; }","duration":"309.781675ms","start":"2026-04-07T00:53:23.006631Z","end":"2026-04-07T00:53:23.316412Z","steps":["trace[3439283] 'process raft request' (duration: 128.528961ms)","trace[3439283] 'compare' (duration: 180.439062ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:23.316575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.380447ms","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":"warn","ts":"2026-04-07T00:53:23.316585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:23.006617Z","time spent":"309.898788ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:23.316633Z","caller":"traceutil/trace.go:171","msg":"trace[1796703543] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24324; }","duration":"274.50075ms","start":"2026-04-07T00:53:23.042114Z","end":"2026-04-07T00:53:23.316615Z","steps":["trace[1796703543] 'agreement among raft nodes before linearized reading' (duration: 274.356026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:23.31686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.902884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T00:53:23.316918Z","caller":"traceutil/trace.go:171","msg":"trace[1188023179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24324; }","duration":"156.964976ms","start":"2026-04-07T00:53:23.159937Z","end":"2026-04-07T00:53:23.316902Z","steps":["trace[1188023179] 'agreement among raft nodes before linearized reading' (duration: 156.782521ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:23.5562Z","caller":"traceutil/trace.go:171","msg":"trace[817120929] linearizableReadLoop","detail":"{readStateIndex:25129; appliedIndex:25128; }","duration":"121.751901ms","start":"2026-04-07T00:53:23.434425Z","end":"2026-04-07T00:53:23.556177Z","steps":["trace[817120929] 'read index received' (duration: 121.628988ms)","trace[817120929] 'applied index is now lower than readState.Index' (duration: 121.893µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:23.556351Z","caller":"traceutil/trace.go:171","msg":"trace[1795107477] transaction","detail":"{read_only:false; response_revision:24325; number_of_response:1; }","duration":"234.661787ms","start":"2026-04-07T00:53:23.32167Z","end":"2026-04-07T00:53:23.556331Z","steps":["trace[1795107477] 'process raft request' (duration: 234.378079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:23.556421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.989318ms","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-04-07T00:53:23.556454Z","caller":"traceutil/trace.go:171","msg":"trace[2114236910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24325; }","duration":"122.07873ms","start":"2026-04-07T00:53:23.434365Z","end":"2026-04-07T00:53:23.556444Z","steps":["trace[2114236910] 'agreement among raft nodes before linearized reading' (duration: 121.916726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:24.49642Z","caller":"traceutil/trace.go:171","msg":"trace[836475997] transaction","detail":"{read_only:false; response_revision:24328; number_of_response:1; }","duration":"111.139446ms","start":"2026-04-07T00:53:24.385257Z","end":"2026-04-07T00:53:24.496396Z","steps":["trace[836475997] 'process raft request' (duration: 111.001662ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:24.762543Z","caller":"traceutil/trace.go:171","msg":"trace[494735327] transaction","detail":"{read_only:false; response_revision:24329; number_of_response:1; }","duration":"364.051753ms","start":"2026-04-07T00:53:24.398468Z","end":"2026-04-07T00:53:24.762519Z","steps":["trace[494735327] 'process raft request' (duration: 363.776135ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:24.762644Z","caller":"traceutil/trace.go:171","msg":"trace[1344112999] linearizableReadLoop","detail":"{readStateIndex:25135; appliedIndex:25132; }","duration":"178.802387ms","start":"2026-04-07T00:53:24.583824Z","end":"2026-04-07T00:53:24.762627Z","steps":["trace[1344112999] 'read index received' (duration: 178.491358ms)","trace[1344112999] 'applied index is now lower than readState.Index' (duration: 309.999µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:24.762659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:24.398441Z","time spent":"364.158636ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:24.762745Z","caller":"traceutil/trace.go:171","msg":"trace[302987206] transaction","detail":"{read_only:false; response_revision:24331; number_of_response:1; }","duration":"362.204611ms","start":"2026-04-07T00:53:24.40051Z","end":"2026-04-07T00:53:24.762715Z","steps":["trace[302987206] 'process raft request' (duration: 362.034016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:24.762827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.006202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:24.762868Z","caller":"traceutil/trace.go:171","msg":"trace[168096348] transaction","detail":"{read_only:false; response_revision:24330; number_of_response:1; }","duration":"363.484047ms","start":"2026-04-07T00:53:24.399376Z","end":"2026-04-07T00:53:24.76286Z","steps":["trace[168096348] 'process raft request' (duration: 363.052775ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:24.762869Z","caller":"traceutil/trace.go:171","msg":"trace[34804001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24331; }","duration":"179.086394ms","start":"2026-04-07T00:53:24.583773Z","end":"2026-04-07T00:53:24.762859Z","steps":["trace[34804001] 'agreement among raft nodes before linearized reading' (duration: 179.016212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:24.762889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:24.400487Z","time spent":"362.327015ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:24.762915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:24.399362Z","time spent":"363.531538ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:24.851583Z","caller":"traceutil/trace.go:171","msg":"trace[1807729357] transaction","detail":"{read_only:false; response_revision:24332; number_of_response:1; }","duration":"261.127206ms","start":"2026-04-07T00:53:24.59043Z","end":"2026-04-07T00:53:24.851557Z","steps":["trace[1807729357] 'process raft request' (duration: 258.863213ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:24.962042Z","caller":"traceutil/trace.go:171","msg":"trace[1313243090] transaction","detail":"{read_only:false; response_revision:24335; number_of_response:1; }","duration":"105.552859ms","start":"2026-04-07T00:53:24.856455Z","end":"2026-04-07T00:53:24.962008Z","steps":["trace[1313243090] 'process raft request' (duration: 105.498128ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:24.962037Z","caller":"traceutil/trace.go:171","msg":"trace[1606293854] transaction","detail":"{read_only:false; response_revision:24334; number_of_response:1; }","duration":"105.675243ms","start":"2026-04-07T00:53:24.856337Z","end":"2026-04-07T00:53:24.962012Z","steps":["trace[1606293854] 'process raft request' (duration: 105.267012ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:28.734833Z","caller":"traceutil/trace.go:171","msg":"trace[918965299] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"227.652212ms","start":"2026-04-07T00:53:28.507163Z","end":"2026-04-07T00:53:28.734815Z","steps":["trace[918965299] 'process raft request' (duration: 227.489517ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:28.734866Z","caller":"traceutil/trace.go:171","msg":"trace[1374661023] linearizableReadLoop","detail":"{readStateIndex:25161; appliedIndex:25161; }","duration":"152.480681ms","start":"2026-04-07T00:53:28.582342Z","end":"2026-04-07T00:53:28.734823Z","steps":["trace[1374661023] 'read index received' (duration: 152.465901ms)","trace[1374661023] 'applied index is now lower than readState.Index' (duration: 12.71µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:28.735018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.661596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:28.735057Z","caller":"traceutil/trace.go:171","msg":"trace[1726417936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24356; }","duration":"152.761359ms","start":"2026-04-07T00:53:28.582287Z","end":"2026-04-07T00:53:28.735048Z","steps":["trace[1726417936] 'agreement among raft nodes before linearized reading' (duration: 152.658816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:29.019592Z","caller":"traceutil/trace.go:171","msg":"trace[1762055478] transaction","detail":"{read_only:false; response_revision:24357; number_of_response:1; }","duration":"245.819899ms","start":"2026-04-07T00:53:28.773743Z","end":"2026-04-07T00:53:29.019563Z","steps":["trace[1762055478] 'process raft request' (duration: 242.460185ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:29.019718Z","caller":"traceutil/trace.go:171","msg":"trace[1353874246] transaction","detail":"{read_only:false; response_revision:24358; number_of_response:1; }","duration":"244.335577ms","start":"2026-04-07T00:53:28.77535Z","end":"2026-04-07T00:53:29.019686Z","steps":["trace[1353874246] 'process raft request' (duration: 244.117991ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:29.020502Z","caller":"traceutil/trace.go:171","msg":"trace[204357595] transaction","detail":"{read_only:false; response_revision:24360; number_of_response:1; }","duration":"198.740153ms","start":"2026-04-07T00:53:28.821725Z","end":"2026-04-07T00:53:29.020465Z","steps":["trace[204357595] 'process raft request' (duration: 197.91648ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:29.020075Z","caller":"traceutil/trace.go:171","msg":"trace[1533788120] transaction","detail":"{read_only:false; response_revision:24359; number_of_response:1; }","duration":"242.625609ms","start":"2026-04-07T00:53:28.777418Z","end":"2026-04-07T00:53:29.020044Z","steps":["trace[1533788120] 'process raft request' (duration: 242.127855ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:29.020949Z","caller":"traceutil/trace.go:171","msg":"trace[2073218268] linearizableReadLoop","detail":"{readStateIndex:25166; appliedIndex:25161; }","duration":"122.355089ms","start":"2026-04-07T00:53:28.898577Z","end":"2026-04-07T00:53:29.020932Z","steps":["trace[2073218268] 'read index received' (duration: 117.894574ms)","trace[2073218268] 'applied index is now lower than readState.Index' (duration: 4.459545ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:29.021014Z","caller":"traceutil/trace.go:171","msg":"trace[464016511] transaction","detail":"{read_only:false; response_revision:24361; number_of_response:1; }","duration":"158.569371ms","start":"2026-04-07T00:53:28.862383Z","end":"2026-04-07T00:53:29.020952Z","steps":["trace[464016511] 'process raft request' (duration: 158.416427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:29.021145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.560975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T00:53:29.021184Z","caller":"traceutil/trace.go:171","msg":"trace[1911129104] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24361; }","duration":"122.652307ms","start":"2026-04-07T00:53:28.898521Z","end":"2026-04-07T00:53:29.021173Z","steps":["trace[1911129104] 'agreement among raft nodes before linearized reading' (duration: 122.495343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:38.365172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.014531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2bc4317-9b54-4b19-a91f-a262a0e62938\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-04-07T00:53:38.365242Z","caller":"traceutil/trace.go:171","msg":"trace[19301090] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e2bc4317-9b54-4b19-a91f-a262a0e62938; range_end:; response_count:1; response_revision:24486; }","duration":"218.167806ms","start":"2026-04-07T00:53:38.147057Z","end":"2026-04-07T00:53:38.365225Z","steps":["trace[19301090] 'range keys from in-memory index tree' (duration: 217.887758ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:38.661593Z","caller":"traceutil/trace.go:171","msg":"trace[1407095479] transaction","detail":"{read_only:false; response_revision:24487; number_of_response:1; }","duration":"239.903453ms","start":"2026-04-07T00:53:38.42166Z","end":"2026-04-07T00:53:38.661564Z","steps":["trace[1407095479] 'process raft request' (duration: 239.671007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:38.830906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.227331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-07T00:53:38.830975Z","caller":"traceutil/trace.go:171","msg":"trace[237160094] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24487; }","duration":"144.347644ms","start":"2026-04-07T00:53:38.686614Z","end":"2026-04-07T00:53:38.830962Z","steps":["trace[237160094] 'range keys from in-memory index tree' (duration: 144.028495ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:38.94232Z","caller":"traceutil/trace.go:171","msg":"trace[841259233] transaction","detail":"{read_only:false; response_revision:24488; number_of_response:1; }","duration":"163.961121ms","start":"2026-04-07T00:53:38.778335Z","end":"2026-04-07T00:53:38.942296Z","steps":["trace[841259233] 'process raft request' (duration: 163.810617ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.215384Z","caller":"traceutil/trace.go:171","msg":"trace[1542524243] transaction","detail":"{read_only:false; response_revision:24489; number_of_response:1; }","duration":"184.159636ms","start":"2026-04-07T00:53:39.031203Z","end":"2026-04-07T00:53:39.215363Z","steps":["trace[1542524243] 'process raft request' (duration: 184.009412ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.26948Z","caller":"traceutil/trace.go:171","msg":"trace[695439417] linearizableReadLoop","detail":"{readStateIndex:25301; appliedIndex:25295; }","duration":"182.824508ms","start":"2026-04-07T00:53:39.086627Z","end":"2026-04-07T00:53:39.269452Z","steps":["trace[695439417] 'read index received' (duration: 128.572432ms)","trace[695439417] 'applied index is now lower than readState.Index' (duration: 54.250735ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:39.269961Z","caller":"traceutil/trace.go:171","msg":"trace[1204084765] transaction","detail":"{read_only:false; response_revision:24490; number_of_response:1; }","duration":"217.126036ms","start":"2026-04-07T00:53:39.052787Z","end":"2026-04-07T00:53:39.269913Z","steps":["trace[1204084765] 'process raft request' (duration: 216.322644ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.270043Z","caller":"traceutil/trace.go:171","msg":"trace[543744386] transaction","detail":"{read_only:false; response_revision:24491; number_of_response:1; }","duration":"213.815094ms","start":"2026-04-07T00:53:39.05622Z","end":"2026-04-07T00:53:39.270036Z","steps":["trace[543744386] 'process raft request' (duration: 213.079093ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.270231Z","caller":"traceutil/trace.go:171","msg":"trace[235886664] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"213.416303ms","start":"2026-04-07T00:53:39.056807Z","end":"2026-04-07T00:53:39.270223Z","steps":["trace[235886664] 'process raft request' (duration: 212.533498ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.270307Z","caller":"traceutil/trace.go:171","msg":"trace[2088404316] transaction","detail":"{read_only:false; response_revision:24493; number_of_response:1; }","duration":"201.575622ms","start":"2026-04-07T00:53:39.068725Z","end":"2026-04-07T00:53:39.270301Z","steps":["trace[2088404316] 'process raft request' (duration: 200.655157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.270507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.860677ms","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-04-07T00:53:39.270544Z","caller":"traceutil/trace.go:171","msg":"trace[1577562010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24494; }","duration":"183.947179ms","start":"2026-04-07T00:53:39.086585Z","end":"2026-04-07T00:53:39.270532Z","steps":["trace[1577562010] 'agreement among raft nodes before linearized reading' (duration: 183.781935ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.269991Z","caller":"traceutil/trace.go:171","msg":"trace[1502897969] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"196.247783ms","start":"2026-04-07T00:53:39.073722Z","end":"2026-04-07T00:53:39.26997Z","steps":["trace[1502897969] 'process raft request' (duration: 195.689667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.270688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.860391ms","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-04-07T00:53:39.270712Z","caller":"traceutil/trace.go:171","msg":"trace[942185037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24494; }","duration":"181.899202ms","start":"2026-04-07T00:53:39.088808Z","end":"2026-04-07T00:53:39.270707Z","steps":["trace[942185037] 'agreement among raft nodes before linearized reading' (duration: 181.83703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.270815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.64694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-04-07T00:53:39.270837Z","caller":"traceutil/trace.go:171","msg":"trace[1575836173] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24494; }","duration":"145.692751ms","start":"2026-04-07T00:53:39.125139Z","end":"2026-04-07T00:53:39.270832Z","steps":["trace[1575836173] 'agreement among raft nodes before linearized reading' (duration: 145.626599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.270992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.951784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:53:39.271012Z","caller":"traceutil/trace.go:171","msg":"trace[1435914398] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24494; }","duration":"181.987795ms","start":"2026-04-07T00:53:39.089019Z","end":"2026-04-07T00:53:39.271007Z","steps":["trace[1435914398] 'agreement among raft nodes before linearized reading' (duration: 181.928834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.271046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.527311ms","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-04-07T00:53:39.271203Z","caller":"traceutil/trace.go:171","msg":"trace[1636755315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24494; }","duration":"167.727137ms","start":"2026-04-07T00:53:39.103448Z","end":"2026-04-07T00:53:39.271175Z","steps":["trace[1636755315] 'agreement among raft nodes before linearized reading' (duration: 167.379117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.543363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.337358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:53:39.543501Z","caller":"traceutil/trace.go:171","msg":"trace[636046494] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"266.572208ms","start":"2026-04-07T00:53:39.276888Z","end":"2026-04-07T00:53:39.54346Z","steps":["trace[636046494] 'process raft request' (duration: 138.778397ms)","trace[636046494] 'compare' (duration: 127.02538ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:39.543545Z","caller":"traceutil/trace.go:171","msg":"trace[961598284] linearizableReadLoop","detail":"{readStateIndex:25302; appliedIndex:25301; }","duration":"266.540077ms","start":"2026-04-07T00:53:39.276993Z","end":"2026-04-07T00:53:39.543534Z","steps":["trace[961598284] 'read index received' (duration: 138.662324ms)","trace[961598284] 'applied index is now lower than readState.Index' (duration: 127.877033ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:39.543681Z","caller":"traceutil/trace.go:171","msg":"trace[833242412] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"266.023624ms","start":"2026-04-07T00:53:39.277632Z","end":"2026-04-07T00:53:39.543656Z","steps":["trace[833242412] 'process raft request' (duration: 265.811708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.543845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.851577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-04-07T00:53:39.543867Z","caller":"traceutil/trace.go:171","msg":"trace[1897008455] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24496; }","duration":"266.875207ms","start":"2026-04-07T00:53:39.276986Z","end":"2026-04-07T00:53:39.543861Z","steps":["trace[1897008455] 'agreement among raft nodes before linearized reading' (duration: 266.61463ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.544116Z","caller":"traceutil/trace.go:171","msg":"trace[534895173] transaction","detail":"{read_only:false; response_revision:24497; number_of_response:1; }","duration":"266.119556ms","start":"2026-04-07T00:53:39.27799Z","end":"2026-04-07T00:53:39.544109Z","steps":["trace[534895173] 'process raft request' (duration: 266.038314ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.544331Z","caller":"traceutil/trace.go:171","msg":"trace[66251630] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"165.023782ms","start":"2026-04-07T00:53:39.379286Z","end":"2026-04-07T00:53:39.54431Z","steps":["trace[66251630] 'process raft request' (duration: 164.876427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.544367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.986998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:39.544399Z","caller":"traceutil/trace.go:171","msg":"trace[140820705] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24499; }","duration":"132.073041ms","start":"2026-04-07T00:53:39.412317Z","end":"2026-04-07T00:53:39.54439Z","steps":["trace[140820705] 'agreement among raft nodes before linearized reading' (duration: 132.012569ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.54441Z","caller":"traceutil/trace.go:171","msg":"trace[1633344748] transaction","detail":"{read_only:false; response_revision:24498; number_of_response:1; }","duration":"264.685815ms","start":"2026-04-07T00:53:39.279692Z","end":"2026-04-07T00:53:39.544377Z","steps":["trace[1633344748] 'process raft request' (duration: 264.372667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.544541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.851508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2bc4317-9b54-4b19-a91f-a262a0e62938\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-04-07T00:53:39.54456Z","caller":"traceutil/trace.go:171","msg":"trace[957032202] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e2bc4317-9b54-4b19-a91f-a262a0e62938; range_end:; response_count:1; response_revision:24499; }","duration":"173.92569ms","start":"2026-04-07T00:53:39.370628Z","end":"2026-04-07T00:53:39.544554Z","steps":["trace[957032202] 'agreement among raft nodes before linearized reading' (duration: 173.657783ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:39.764754Z","caller":"traceutil/trace.go:171","msg":"trace[1476265724] linearizableReadLoop","detail":"{readStateIndex:25307; appliedIndex:25306; }","duration":"210.012748ms","start":"2026-04-07T00:53:39.55472Z","end":"2026-04-07T00:53:39.764733Z","steps":["trace[1476265724] 'read index received' (duration: 167.896031ms)","trace[1476265724] 'applied index is now lower than readState.Index' (duration: 42.115847ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:39.765038Z","caller":"traceutil/trace.go:171","msg":"trace[2136745608] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"212.139318ms","start":"2026-04-07T00:53:39.552884Z","end":"2026-04-07T00:53:39.765023Z","steps":["trace[2136745608] 'process raft request' (duration: 169.799955ms)","trace[2136745608] 'compare' (duration: 41.574042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:39.765237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.247675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:39.765323Z","caller":"traceutil/trace.go:171","msg":"trace[731500832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24500; }","duration":"181.359847ms","start":"2026-04-07T00:53:39.583944Z","end":"2026-04-07T00:53:39.765303Z","steps":["trace[731500832] 'agreement among raft nodes before linearized reading' (duration: 181.208763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.765643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.88107ms","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-04-07T00:53:39.765683Z","caller":"traceutil/trace.go:171","msg":"trace[1516331607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24500; }","duration":"143.958622ms","start":"2026-04-07T00:53:39.621714Z","end":"2026-04-07T00:53:39.765673Z","steps":["trace[1516331607] 'agreement among raft nodes before linearized reading' (duration: 143.823338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:39.765726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.997926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-04-07T00:53:39.765771Z","caller":"traceutil/trace.go:171","msg":"trace[1693948919] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24500; }","duration":"211.066657ms","start":"2026-04-07T00:53:39.554693Z","end":"2026-04-07T00:53:39.76576Z","steps":["trace[1693948919] 'agreement among raft nodes before linearized reading' (duration: 210.954034ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:40.041426Z","caller":"traceutil/trace.go:171","msg":"trace[187328935] linearizableReadLoop","detail":"{readStateIndex:25309; appliedIndex:25307; }","duration":"270.054136ms","start":"2026-04-07T00:53:39.771342Z","end":"2026-04-07T00:53:40.041396Z","steps":["trace[187328935] 'read index received' (duration: 76.261681ms)","trace[187328935] 'applied index is now lower than readState.Index' (duration: 193.791145ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:40.041544Z","caller":"traceutil/trace.go:171","msg":"trace[1268395067] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"270.948201ms","start":"2026-04-07T00:53:39.770566Z","end":"2026-04-07T00:53:40.041515Z","steps":["trace[1268395067] 'process raft request' (duration: 212.819816ms)","trace[1268395067] 'compare' (duration: 57.787555ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:40.041573Z","caller":"traceutil/trace.go:171","msg":"trace[2091981078] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"269.532832ms","start":"2026-04-07T00:53:39.771989Z","end":"2026-04-07T00:53:40.041522Z","steps":["trace[2091981078] 'process raft request' (duration: 269.329696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:40.041718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.360205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-04-07T00:53:40.04178Z","caller":"traceutil/trace.go:171","msg":"trace[975351135] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24503; }","duration":"270.461748ms","start":"2026-04-07T00:53:39.771301Z","end":"2026-04-07T00:53:40.041763Z","steps":["trace[975351135] 'agreement among raft nodes before linearized reading' (duration: 270.265522ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:41.665881Z","caller":"traceutil/trace.go:171","msg":"trace[675887985] transaction","detail":"{read_only:false; response_revision:24515; number_of_response:1; }","duration":"115.019275ms","start":"2026-04-07T00:53:41.550836Z","end":"2026-04-07T00:53:41.665855Z","steps":["trace[675887985] 'process raft request' (duration: 114.865439ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:41.794524Z","caller":"traceutil/trace.go:171","msg":"trace[1126598989] linearizableReadLoop","detail":"{readStateIndex:25325; appliedIndex:25323; }","duration":"241.772195ms","start":"2026-04-07T00:53:41.552734Z","end":"2026-04-07T00:53:41.794506Z","steps":["trace[1126598989] 'read index received' (duration: 113.176373ms)","trace[1126598989] 'applied index is now lower than readState.Index' (duration: 128.594902ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:41.794637Z","caller":"traceutil/trace.go:171","msg":"trace[1628163202] transaction","detail":"{read_only:false; response_revision:24517; number_of_response:1; }","duration":"241.93803ms","start":"2026-04-07T00:53:41.552662Z","end":"2026-04-07T00:53:41.7946Z","steps":["trace[1628163202] 'process raft request' (duration: 241.763595ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:41.794646Z","caller":"traceutil/trace.go:171","msg":"trace[573525479] transaction","detail":"{read_only:false; response_revision:24519; number_of_response:1; }","duration":"240.546821ms","start":"2026-04-07T00:53:41.554073Z","end":"2026-04-07T00:53:41.79462Z","steps":["trace[573525479] 'process raft request' (duration: 240.408597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:41.794685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.995917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:41.794702Z","caller":"traceutil/trace.go:171","msg":"trace[1157557959] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"242.066945ms","start":"2026-04-07T00:53:41.552622Z","end":"2026-04-07T00:53:41.794689Z","steps":["trace[1157557959] 'process raft request' (duration: 241.675873ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:41.794717Z","caller":"traceutil/trace.go:171","msg":"trace[243682288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24519; }","duration":"211.071879ms","start":"2026-04-07T00:53:41.583636Z","end":"2026-04-07T00:53:41.794708Z","steps":["trace[243682288] 'agreement among raft nodes before linearized reading' (duration: 211.003066ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:41.794609Z","caller":"traceutil/trace.go:171","msg":"trace[95687945] transaction","detail":"{read_only:false; response_revision:24518; number_of_response:1; }","duration":"241.406865ms","start":"2026-04-07T00:53:41.553155Z","end":"2026-04-07T00:53:41.794562Z","steps":["trace[95687945] 'process raft request' (duration: 241.296452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:41.795146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.409244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2bc4317-9b54-4b19-a91f-a262a0e62938\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-04-07T00:53:41.795175Z","caller":"traceutil/trace.go:171","msg":"trace[1738994755] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e2bc4317-9b54-4b19-a91f-a262a0e62938; range_end:; response_count:1; response_revision:24519; }","duration":"242.457916ms","start":"2026-04-07T00:53:41.552709Z","end":"2026-04-07T00:53:41.795167Z","steps":["trace[1738994755] 'agreement among raft nodes before linearized reading' (duration: 242.346393ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:44.419861Z","caller":"traceutil/trace.go:171","msg":"trace[138129702] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"197.995263ms","start":"2026-04-07T00:53:44.22184Z","end":"2026-04-07T00:53:44.419836Z","steps":["trace[138129702] 'process raft request' (duration: 197.836889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.703379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.35768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-07T00:53:44.703434Z","caller":"traceutil/trace.go:171","msg":"trace[1692591672] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24553; }","duration":"112.446151ms","start":"2026-04-07T00:53:44.590974Z","end":"2026-04-07T00:53:44.70342Z","steps":["trace[1692591672] 'range keys from in-memory index tree' (duration: 112.250747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.703656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.934355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-04-07T00:53:44.703672Z","caller":"traceutil/trace.go:171","msg":"trace[53133063] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24553; }","duration":"185.981796ms","start":"2026-04-07T00:53:44.517685Z","end":"2026-04-07T00:53:44.703667Z","steps":["trace[53133063] 'range keys from in-memory index tree' (duration: 185.843782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.703805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.51683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:44.703898Z","caller":"traceutil/trace.go:171","msg":"trace[744368792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24553; }","duration":"119.653783ms","start":"2026-04-07T00:53:44.584231Z","end":"2026-04-07T00:53:44.703885Z","steps":["trace[744368792] 'range keys from in-memory index tree' (duration: 119.385866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:05.940172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.051118ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T00:54:05.940386Z","caller":"traceutil/trace.go:171","msg":"trace[1058745524] linearizableReadLoop","detail":"{readStateIndex:25545; appliedIndex:25544; }","duration":"252.892097ms","start":"2026-04-07T00:54:05.687478Z","end":"2026-04-07T00:54:05.94037Z","steps":["trace[1058745524] 'read index received' (duration: 54.821µs)","trace[1058745524] 'applied index is now lower than readState.Index' (duration: 252.836005ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:05.940653Z","caller":"traceutil/trace.go:171","msg":"trace[1098945569] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"194.230068ms","start":"2026-04-07T00:54:05.746407Z","end":"2026-04-07T00:54:05.940637Z","steps":["trace[1098945569] 'process raft request' (duration: 193.864118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:06.088483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.776574ms","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-04-07T00:54:06.088567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.054657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:06.088618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.71594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:06.088665Z","caller":"traceutil/trace.go:171","msg":"trace[730497023] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24732; }","duration":"401.211702ms","start":"2026-04-07T00:54:05.687429Z","end":"2026-04-07T00:54:06.088641Z","steps":["trace[730497023] 'agreement among raft nodes before linearized reading' (duration: 252.99957ms)","trace[730497023] 'count revisions from in-memory index tree' (duration: 148.059218ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:06.088649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.203291ms","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-04-07T00:54:06.088706Z","caller":"traceutil/trace.go:171","msg":"trace[1058076187] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24732; }","duration":"389.857815ms","start":"2026-04-07T00:54:05.698822Z","end":"2026-04-07T00:54:06.08868Z","steps":["trace[1058076187] 'agreement among raft nodes before linearized reading' (duration: 241.784217ms)","trace[1058076187] 'count revisions from in-memory index tree' (duration: 147.936764ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:06.088763Z","caller":"traceutil/trace.go:171","msg":"trace[1289279208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24732; }","duration":"153.352125ms","start":"2026-04-07T00:54:05.935383Z","end":"2026-04-07T00:54:06.088735Z","steps":["trace[1289279208] 'range keys from in-memory index tree' (duration: 147.935324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:06.088792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:05.698802Z","time spent":"389.975507ms","remote":"127.0.0.1:46224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:54:06.088743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:05.687401Z","time spent":"401.327954ms","remote":"127.0.0.1:46242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:54:06.088492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.366918ms","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-04-07T00:54:06.088844Z","caller":"traceutil/trace.go:171","msg":"trace[1340741518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24732; }","duration":"169.99571ms","start":"2026-04-07T00:54:05.918628Z","end":"2026-04-07T00:54:06.088624Z","steps":["trace[1340741518] 'agreement among raft nodes before linearized reading' (duration: 21.998555ms)","trace[1340741518] 'range keys from in-memory index tree' (duration: 147.686287ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:06.088942Z","caller":"traceutil/trace.go:171","msg":"trace[1332725914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24732; }","duration":"186.832281ms","start":"2026-04-07T00:54:05.902066Z","end":"2026-04-07T00:54:06.088898Z","steps":["trace[1332725914] 'agreement among raft nodes before linearized reading' (duration: 38.579008ms)","trace[1332725914] 'range keys from in-memory index tree' (duration: 147.714408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:06.089072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.42684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T00:54:06.08918Z","caller":"traceutil/trace.go:171","msg":"trace[1722950935] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24732; }","duration":"152.545573ms","start":"2026-04-07T00:54:05.936595Z","end":"2026-04-07T00:54:06.089141Z","steps":["trace[1722950935] 'range keys from in-memory index tree' (duration: 148.255312ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:06.202547Z","caller":"traceutil/trace.go:171","msg":"trace[599355909] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"109.642433ms","start":"2026-04-07T00:54:06.092884Z","end":"2026-04-07T00:54:06.202526Z","steps":["trace[599355909] 'process raft request' (duration: 109.469868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:06.300169Z","caller":"traceutil/trace.go:171","msg":"trace[429161404] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"206.670505ms","start":"2026-04-07T00:54:06.093468Z","end":"2026-04-07T00:54:06.300139Z","steps":["trace[429161404] 'process raft request' (duration: 206.277214ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:06.300375Z","caller":"traceutil/trace.go:171","msg":"trace[1804479643] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"206.796579ms","start":"2026-04-07T00:54:06.093567Z","end":"2026-04-07T00:54:06.300363Z","steps":["trace[1804479643] 'process raft request' (duration: 206.545012ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:06.300536Z","caller":"traceutil/trace.go:171","msg":"trace[1066397730] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"206.960783ms","start":"2026-04-07T00:54:06.09354Z","end":"2026-04-07T00:54:06.300501Z","steps":["trace[1066397730] 'process raft request' (duration: 206.530711ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:06.300225Z","caller":"traceutil/trace.go:171","msg":"trace[1150728432] linearizableReadLoop","detail":"{readStateIndex:25551; appliedIndex:25547; }","duration":"146.251787ms","start":"2026-04-07T00:54:06.153952Z","end":"2026-04-07T00:54:06.300203Z","steps":["trace[1150728432] 'read index received' (duration: 48.583817ms)","trace[1150728432] 'applied index is now lower than readState.Index' (duration: 97.66711ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:06.300578Z","caller":"traceutil/trace.go:171","msg":"trace[1559288733] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"206.964343ms","start":"2026-04-07T00:54:06.093591Z","end":"2026-04-07T00:54:06.300555Z","steps":["trace[1559288733] 'process raft request' (duration: 206.570742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:06.300653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.700588ms","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-04-07T00:54:06.30068Z","caller":"traceutil/trace.go:171","msg":"trace[1092562350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24737; }","duration":"146.780891ms","start":"2026-04-07T00:54:06.153891Z","end":"2026-04-07T00:54:06.300672Z","steps":["trace[1092562350] 'agreement among raft nodes before linearized reading' (duration: 146.682229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:06.654423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.809601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-04-07T00:54:06.654498Z","caller":"traceutil/trace.go:171","msg":"trace[795955957] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24738; }","duration":"166.923715ms","start":"2026-04-07T00:54:06.487557Z","end":"2026-04-07T00:54:06.654481Z","steps":["trace[795955957] 'range keys from in-memory index tree' (duration: 166.670178ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:06.782312Z","caller":"traceutil/trace.go:171","msg":"trace[1473487587] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"124.175099ms","start":"2026-04-07T00:54:06.658104Z","end":"2026-04-07T00:54:06.782279Z","steps":["trace[1473487587] 'process raft request' (duration: 123.877871ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:07.140653Z","caller":"traceutil/trace.go:171","msg":"trace[1109192126] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"103.055909ms","start":"2026-04-07T00:54:07.037577Z","end":"2026-04-07T00:54:07.140633Z","steps":["trace[1109192126] 'process raft request' (duration: 102.937006ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:07.549874Z","caller":"traceutil/trace.go:171","msg":"trace[315632256] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"175.779492ms","start":"2026-04-07T00:54:07.374074Z","end":"2026-04-07T00:54:07.549853Z","steps":["trace[315632256] 'process raft request' (duration: 175.660528ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:07.550754Z","caller":"traceutil/trace.go:171","msg":"trace[1507971487] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"174.148807ms","start":"2026-04-07T00:54:07.376588Z","end":"2026-04-07T00:54:07.550737Z","steps":["trace[1507971487] 'process raft request' (duration: 174.067385ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:07.550871Z","caller":"traceutil/trace.go:171","msg":"trace[1125944261] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"174.236959ms","start":"2026-04-07T00:54:07.376623Z","end":"2026-04-07T00:54:07.55086Z","steps":["trace[1125944261] 'process raft request' (duration: 174.079015ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:07.550949Z","caller":"traceutil/trace.go:171","msg":"trace[984661247] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"174.484377ms","start":"2026-04-07T00:54:07.376454Z","end":"2026-04-07T00:54:07.550938Z","steps":["trace[984661247] 'process raft request' (duration: 174.121246ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:07.551044Z","caller":"traceutil/trace.go:171","msg":"trace[880085535] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"174.089074ms","start":"2026-04-07T00:54:07.376947Z","end":"2026-04-07T00:54:07.551036Z","steps":["trace[880085535] 'process raft request' (duration: 173.875858ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:08.074Z","caller":"traceutil/trace.go:171","msg":"trace[1039967314] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"128.723406ms","start":"2026-04-07T00:54:07.945254Z","end":"2026-04-07T00:54:08.073977Z","steps":["trace[1039967314] 'process raft request' (duration: 128.565392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:08.351298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.879118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:08.3514Z","caller":"traceutil/trace.go:171","msg":"trace[274913205] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:24746; }","duration":"150.006171ms","start":"2026-04-07T00:54:08.201369Z","end":"2026-04-07T00:54:08.351375Z","steps":["trace[274913205] 'count revisions from in-memory index tree' (duration: 149.764384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:08.351603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.737382ms","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-04-07T00:54:08.351685Z","caller":"traceutil/trace.go:171","msg":"trace[835250700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24746; }","duration":"145.843855ms","start":"2026-04-07T00:54:08.205823Z","end":"2026-04-07T00:54:08.351667Z","steps":["trace[835250700] 'range keys from in-memory index tree' (duration: 145.383442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:08.351675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.365058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:08.35193Z","caller":"traceutil/trace.go:171","msg":"trace[907879416] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"144.006474ms","start":"2026-04-07T00:54:08.207902Z","end":"2026-04-07T00:54:08.351909Z","steps":["trace[907879416] 'process raft request' (duration: 11.341646ms)","trace[907879416] 'compare' (duration: 132.162293ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:08.530457Z","caller":"traceutil/trace.go:171","msg":"trace[645679624] linearizableReadLoop","detail":"{readStateIndex:25566; appliedIndex:25561; }","duration":"171.952674ms","start":"2026-04-07T00:54:08.358481Z","end":"2026-04-07T00:54:08.530433Z","steps":["trace[645679624] 'read index received' (duration: 85.779288ms)","trace[645679624] 'applied index is now lower than readState.Index' (duration: 86.172276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:08.530738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.239522ms","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-04-07T00:54:08.530785Z","caller":"traceutil/trace.go:171","msg":"trace[1918332591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24752; }","duration":"172.330085ms","start":"2026-04-07T00:54:08.358442Z","end":"2026-04-07T00:54:08.530772Z","steps":["trace[1918332591] 'agreement among raft nodes before linearized reading' (duration: 172.1447ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:08.531244Z","caller":"traceutil/trace.go:171","msg":"trace[218866232] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"174.794254ms","start":"2026-04-07T00:54:08.356438Z","end":"2026-04-07T00:54:08.531232Z","steps":["trace[218866232] 'process raft request' (duration: 173.689253ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:08.531395Z","caller":"traceutil/trace.go:171","msg":"trace[612899389] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"174.881807ms","start":"2026-04-07T00:54:08.356504Z","end":"2026-04-07T00:54:08.531385Z","steps":["trace[612899389] 'process raft request' (duration: 173.778675ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:08.531528Z","caller":"traceutil/trace.go:171","msg":"trace[993920890] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"174.876766ms","start":"2026-04-07T00:54:08.356642Z","end":"2026-04-07T00:54:08.531519Z","steps":["trace[993920890] 'process raft request' (duration: 173.683032ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:08.53166Z","caller":"traceutil/trace.go:171","msg":"trace[597309109] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"174.381693ms","start":"2026-04-07T00:54:08.35727Z","end":"2026-04-07T00:54:08.531652Z","steps":["trace[597309109] 'process raft request' (duration: 173.102597ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:08.693652Z","caller":"traceutil/trace.go:171","msg":"trace[323916117] linearizableReadLoop","detail":"{readStateIndex:25567; appliedIndex:25566; }","duration":"109.976594ms","start":"2026-04-07T00:54:08.583643Z","end":"2026-04-07T00:54:08.693619Z","steps":["trace[323916117] 'read index received' (duration: 87.146705ms)","trace[323916117] 'applied index is now lower than readState.Index' (duration: 22.828909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:08.693844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.182929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:08.693884Z","caller":"traceutil/trace.go:171","msg":"trace[1151635759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24753; }","duration":"110.283932ms","start":"2026-04-07T00:54:08.58359Z","end":"2026-04-07T00:54:08.693874Z","steps":["trace[1151635759] 'agreement among raft nodes before linearized reading' (duration: 110.145208ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:08.693995Z","caller":"traceutil/trace.go:171","msg":"trace[1481879567] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"154.9544ms","start":"2026-04-07T00:54:08.538996Z","end":"2026-04-07T00:54:08.693951Z","steps":["trace[1481879567] 'process raft request' (duration: 131.752501ms)","trace[1481879567] 'compare' (duration: 22.738935ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:09.272615Z","caller":"traceutil/trace.go:171","msg":"trace[1460539106] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"122.503763ms","start":"2026-04-07T00:54:09.150081Z","end":"2026-04-07T00:54:09.272585Z","steps":["trace[1460539106] 'process raft request' (duration: 122.322868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:09.48004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.053747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-07T00:54:09.480139Z","caller":"traceutil/trace.go:171","msg":"trace[973212483] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24754; }","duration":"148.196321ms","start":"2026-04-07T00:54:09.331919Z","end":"2026-04-07T00:54:09.480115Z","steps":["trace[973212483] 'range keys from in-memory index tree' (duration: 147.80836ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:09.789513Z","caller":"traceutil/trace.go:171","msg":"trace[1612582772] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"233.294089ms","start":"2026-04-07T00:54:09.556191Z","end":"2026-04-07T00:54:09.789485Z","steps":["trace[1612582772] 'process raft request' (duration: 233.115254ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:09.921846Z","caller":"traceutil/trace.go:171","msg":"trace[499771678] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"365.319678ms","start":"2026-04-07T00:54:09.556502Z","end":"2026-04-07T00:54:09.921822Z","steps":["trace[499771678] 'process raft request' (duration: 304.503498ms)","trace[499771678] 'compare' (duration: 60.524631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:09.921997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:09.556492Z","time spent":"365.42268ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:09.922144Z","caller":"traceutil/trace.go:171","msg":"trace[625533806] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"365.39037ms","start":"2026-04-07T00:54:09.556724Z","end":"2026-04-07T00:54:09.922114Z","steps":["trace[625533806] 'process raft request' (duration: 365.000649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:09.922338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:09.556715Z","time spent":"365.506773ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:09.922693Z","caller":"traceutil/trace.go:171","msg":"trace[1457491492] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"365.78196ms","start":"2026-04-07T00:54:09.556875Z","end":"2026-04-07T00:54:09.922657Z","steps":["trace[1457491492] 'process raft request' (duration: 365.148422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:09.92287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:09.55686Z","time spent":"365.917864ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:09.922909Z","caller":"traceutil/trace.go:171","msg":"trace[1011992057] linearizableReadLoop","detail":"{readStateIndex:25574; appliedIndex:25570; }","duration":"338.608532ms","start":"2026-04-07T00:54:09.58416Z","end":"2026-04-07T00:54:09.922768Z","steps":["trace[1011992057] 'read index received' (duration: 205.371419ms)","trace[1011992057] 'applied index is now lower than readState.Index' (duration: 133.236143ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:09.92303Z","caller":"traceutil/trace.go:171","msg":"trace[512422449] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"365.988505ms","start":"2026-04-07T00:54:09.557026Z","end":"2026-04-07T00:54:09.923014Z","steps":["trace[512422449] 'process raft request' (duration: 365.487262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:09.923092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:09.557021Z","time spent":"366.036197ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:09.923345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.986272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:09.923231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.061774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:09.923469Z","caller":"traceutil/trace.go:171","msg":"trace[917963486] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24760; }","duration":"334.179258ms","start":"2026-04-07T00:54:09.589262Z","end":"2026-04-07T00:54:09.923441Z","steps":["trace[917963486] 'agreement among raft nodes before linearized reading' (duration: 333.947361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:09.923554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:09.589247Z","time spent":"334.283492ms","remote":"127.0.0.1:46482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:54:09.923528Z","caller":"traceutil/trace.go:171","msg":"trace[1964730100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24760; }","duration":"339.401314ms","start":"2026-04-07T00:54:09.58411Z","end":"2026-04-07T00:54:09.923512Z","steps":["trace[1964730100] 'agreement among raft nodes before linearized reading' (duration: 339.041363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:09.923763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:09.584089Z","time spent":"339.659722ms","remote":"127.0.0.1:41206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:54:10.967364Z","caller":"traceutil/trace.go:171","msg":"trace[1189594443] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"116.819435ms","start":"2026-04-07T00:54:10.850523Z","end":"2026-04-07T00:54:10.967343Z","steps":["trace[1189594443] 'process raft request' (duration: 55.847921ms)","trace[1189594443] 'compare' (duration: 60.873321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:11.257059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.531885ms","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":"info","ts":"2026-04-07T00:54:11.257148Z","caller":"traceutil/trace.go:171","msg":"trace[670838813] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:24770; }","duration":"141.670889ms","start":"2026-04-07T00:54:11.115451Z","end":"2026-04-07T00:54:11.257122Z","steps":["trace[670838813] 'count revisions from in-memory index tree' (duration: 141.34979ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:14.703113Z","caller":"traceutil/trace.go:171","msg":"trace[1850745057] transaction","detail":"{read_only:false; response_revision:24793; number_of_response:1; }","duration":"128.500481ms","start":"2026-04-07T00:54:14.574563Z","end":"2026-04-07T00:54:14.703063Z","steps":["trace[1850745057] 'process raft request' (duration: 128.170692ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:14.70326Z","caller":"traceutil/trace.go:171","msg":"trace[344932819] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"127.775421ms","start":"2026-04-07T00:54:14.575451Z","end":"2026-04-07T00:54:14.703226Z","steps":["trace[344932819] 'process raft request' (duration: 127.540864ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:14.703369Z","caller":"traceutil/trace.go:171","msg":"trace[1331592368] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"127.422161ms","start":"2026-04-07T00:54:14.575915Z","end":"2026-04-07T00:54:14.703337Z","steps":["trace[1331592368] 'process raft request' (duration: 127.223575ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:14.703562Z","caller":"traceutil/trace.go:171","msg":"trace[1486480325] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"127.358559ms","start":"2026-04-07T00:54:14.57619Z","end":"2026-04-07T00:54:14.703548Z","steps":["trace[1486480325] 'process raft request' (duration: 127.02732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:15.76396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.084871ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T00:54:36.814934Z","caller":"traceutil/trace.go:171","msg":"trace[1295493098] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"123.48198ms","start":"2026-04-07T00:54:36.691429Z","end":"2026-04-07T00:54:36.814911Z","steps":["trace[1295493098] 'process raft request' (duration: 122.928405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.02503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.042883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:37.025139Z","caller":"traceutil/trace.go:171","msg":"trace[1821742796] linearizableReadLoop","detail":"{readStateIndex:25784; appliedIndex:25783; }","duration":"203.188528ms","start":"2026-04-07T00:54:36.821935Z","end":"2026-04-07T00:54:37.025123Z","steps":["trace[1821742796] 'read index received' (duration: 57.842796ms)","trace[1821742796] 'applied index is now lower than readState.Index' (duration: 145.337772ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:37.025304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.365432ms","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-04-07T00:54:37.025264Z","caller":"traceutil/trace.go:171","msg":"trace[286078225] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"205.4517ms","start":"2026-04-07T00:54:36.81977Z","end":"2026-04-07T00:54:37.025221Z","steps":["trace[286078225] 'process raft request' (duration: 60.100539ms)","trace[286078225] 'compare' (duration: 144.690403ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:37.02534Z","caller":"traceutil/trace.go:171","msg":"trace[158845066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24964; }","duration":"203.432234ms","start":"2026-04-07T00:54:36.821898Z","end":"2026-04-07T00:54:37.025331Z","steps":["trace[158845066] 'agreement among raft nodes before linearized reading' (duration: 203.311701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.025632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.646718ms","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-04-07T00:54:37.02569Z","caller":"traceutil/trace.go:171","msg":"trace[1779248166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24964; }","duration":"199.743361ms","start":"2026-04-07T00:54:36.82593Z","end":"2026-04-07T00:54:37.025673Z","steps":["trace[1779248166] 'agreement among raft nodes before linearized reading' (duration: 199.553716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.025956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.581288ms","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-04-07T00:54:37.026009Z","caller":"traceutil/trace.go:171","msg":"trace[1564266850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24964; }","duration":"198.617979ms","start":"2026-04-07T00:54:36.827365Z","end":"2026-04-07T00:54:37.025983Z","steps":["trace[1564266850] 'agreement among raft nodes before linearized reading' (duration: 198.507196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.026156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.841026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T00:54:37.026189Z","caller":"traceutil/trace.go:171","msg":"trace[1390002232] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24964; }","duration":"198.874397ms","start":"2026-04-07T00:54:36.827306Z","end":"2026-04-07T00:54:37.026181Z","steps":["trace[1390002232] 'agreement among raft nodes before linearized reading' (duration: 198.782254ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:37.230318Z","caller":"traceutil/trace.go:171","msg":"trace[1791473240] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"200.851762ms","start":"2026-04-07T00:54:37.029438Z","end":"2026-04-07T00:54:37.23029Z","steps":["trace[1791473240] 'process raft request' (duration: 200.681248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.435622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.284789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:37.435996Z","caller":"traceutil/trace.go:171","msg":"trace[343326471] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"406.387996ms","start":"2026-04-07T00:54:37.029573Z","end":"2026-04-07T00:54:37.435961Z","steps":["trace[343326471] 'process raft request' (duration: 304.685224ms)","trace[343326471] 'compare' (duration: 101.170316ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:37.436216Z","caller":"traceutil/trace.go:171","msg":"trace[1662124294] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"406.21276ms","start":"2026-04-07T00:54:37.029976Z","end":"2026-04-07T00:54:37.436189Z","steps":["trace[1662124294] 'process raft request' (duration: 405.971484ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:37.436299Z","caller":"traceutil/trace.go:171","msg":"trace[2095492729] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"405.737257ms","start":"2026-04-07T00:54:37.030539Z","end":"2026-04-07T00:54:37.436276Z","steps":["trace[2095492729] 'process raft request' (duration: 405.589923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.436326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:37.029959Z","time spent":"406.308842ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:37.436326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:37.02955Z","time spent":"406.515919ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:37.436338Z","caller":"traceutil/trace.go:171","msg":"trace[1180668737] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"404.697179ms","start":"2026-04-07T00:54:37.03161Z","end":"2026-04-07T00:54:37.436307Z","steps":["trace[1180668737] 'process raft request' (duration: 404.587135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.436388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:37.030531Z","time spent":"405.815409ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:37.436416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.340522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:37.436451Z","caller":"traceutil/trace.go:171","msg":"trace[687628209] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:24969; }","duration":"329.422944ms","start":"2026-04-07T00:54:37.107016Z","end":"2026-04-07T00:54:37.436439Z","steps":["trace[687628209] 'agreement among raft nodes before linearized reading' (duration: 329.357892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.436476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:37.106994Z","time spent":"329.475546ms","remote":"127.0.0.1:58730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":31,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:54:37.43649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:37.0316Z","time spent":"404.798951ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:37.436669Z","caller":"traceutil/trace.go:171","msg":"trace[1430179220] linearizableReadLoop","detail":"{readStateIndex:25789; appliedIndex:25785; }","duration":"329.169878ms","start":"2026-04-07T00:54:37.107067Z","end":"2026-04-07T00:54:37.436237Z","steps":["trace[1430179220] 'read index received' (duration: 123.222783ms)","trace[1430179220] 'applied index is now lower than readState.Index' (duration: 205.946155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:37.690399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.018987ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:37.690536Z","caller":"traceutil/trace.go:171","msg":"trace[142191176] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24970; }","duration":"109.176151ms","start":"2026-04-07T00:54:37.581341Z","end":"2026-04-07T00:54:37.690517Z","steps":["trace[142191176] 'range keys from in-memory index tree' (duration: 108.996686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.690615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.200114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:37.690713Z","caller":"traceutil/trace.go:171","msg":"trace[941162335] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:24970; }","duration":"117.337368ms","start":"2026-04-07T00:54:37.573353Z","end":"2026-04-07T00:54:37.69069Z","steps":["trace[941162335] 'count revisions from in-memory index tree' (duration: 117.131682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.690789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.290836ms","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-04-07T00:54:37.690878Z","caller":"traceutil/trace.go:171","msg":"trace[1986119139] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24970; }","duration":"142.42229ms","start":"2026-04-07T00:54:37.548431Z","end":"2026-04-07T00:54:37.690853Z","steps":["trace[1986119139] 'range keys from in-memory index tree' (duration: 142.211613ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:25.772045Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000692b.wal"} {"level":"info","ts":"2026-04-07T00:56:37.247268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23317} {"level":"info","ts":"2026-04-07T00:56:37.382708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23317,"took":"132.028069ms","hash":2814160728,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":36655104,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-07T00:56:37.382762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2814160728,"revision":23317,"compact-revision":20024} {"level":"info","ts":"2026-04-07T00:58:20.767396Z","caller":"traceutil/trace.go:171","msg":"trace[726998270] transaction","detail":"{read_only:false; response_revision:27252; number_of_response:1; }","duration":"128.827835ms","start":"2026-04-07T00:58:20.638541Z","end":"2026-04-07T00:58:20.767368Z","steps":["trace[726998270] 'process raft request' (duration: 92.229571ms)","trace[726998270] 'compare' (duration: 36.032588ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:58:20.767585Z","caller":"traceutil/trace.go:171","msg":"trace[2138939511] transaction","detail":"{read_only:false; response_revision:27253; number_of_response:1; }","duration":"125.240335ms","start":"2026-04-07T00:58:20.642335Z","end":"2026-04-07T00:58:20.767575Z","steps":["trace[2138939511] 'process raft request' (duration: 124.587187ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:58:20.767706Z","caller":"traceutil/trace.go:171","msg":"trace[1029197282] transaction","detail":"{read_only:false; response_revision:27254; number_of_response:1; }","duration":"121.093199ms","start":"2026-04-07T00:58:20.646605Z","end":"2026-04-07T00:58:20.767698Z","steps":["trace[1029197282] 'process raft request' (duration: 120.358909ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:01:37.253239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26183} {"level":"info","ts":"2026-04-07T01:01:37.396089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26183,"took":"139.302868ms","hash":3828073155,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":33107968,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T01:01:37.396165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3828073155,"revision":26183,"compact-revision":23317} {"level":"info","ts":"2026-04-07T01:02:13.730635Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e5729930cf7e1f","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:02:13.735513Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-07T01:02:13.73562Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-07T01:06:37.262564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28810} {"level":"info","ts":"2026-04-07T01:06:37.405893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28810,"took":"137.552978ms","hash":129011610,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":30126080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T01:06:37.405979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":129011610,"revision":28810,"compact-revision":26183} {"level":"info","ts":"2026-04-07T01:11:37.267333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31067} {"level":"info","ts":"2026-04-07T01:11:37.427904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31067,"took":"156.105368ms","hash":77374120,"current-db-size-bytes":57905152,"current-db-size":"58 MB","current-db-size-in-use-bytes":31571968,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:11:37.427989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":77374120,"revision":31067,"compact-revision":28810} {"level":"info","ts":"2026-04-07T01:16:37.274608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33386} {"level":"info","ts":"2026-04-07T01:16:37.424854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33386,"took":"145.531427ms","hash":1127131574,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":32509952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:16:37.424916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1127131574,"revision":33386,"compact-revision":31067} {"level":"info","ts":"2026-04-07T01:21:37.281918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35627} {"level":"info","ts":"2026-04-07T01:21:37.42498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35627,"took":"137.912814ms","hash":715071434,"current-db-size-bytes":59371520,"current-db-size":"59 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T01:21:37.425064Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":715071434,"revision":35627,"compact-revision":33386} {"level":"info","ts":"2026-04-07T01:23:38.643043Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e5729930cf7e1f","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:23:38.64716Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-07T01:23:38.647238Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}