{"level":"warn","ts":"2026-05-07T16:40:28.578179Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T16:40:28.578281Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T16:40:28.578371Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T16:40:28.578388Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-07T16:40:28.578412Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T16:40:28.579167Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-05-07T16:40:28.579252Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T16:40:28.583308Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.786848ms"} {"level":"info","ts":"2026-05-07T16:40:28.592315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-05-07T16:40:28.592427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T16:40:28.592462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-05-07T16:40:28.592483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T16:40:28.592506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-05-07T16:40:28.592555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-05-07T16:40:28.601595Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T16:40:28.603577Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T16:40:28.604835Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T16:40:28.606949Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T16:40:28.607184Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T16:40:28.607393Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T16:40:28.607507Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T16:40:28.607542Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T16:40:28.608293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-05-07T16:40:28.609025Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-07T16:40:28.613183Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T16:40:28.613406Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-07T16:40:28.613493Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-07T16:40:28.613645Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T16:40:28.613707Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T16:40:29.394598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T16:40:29.394682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T16:40:29.394711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-05-07T16:40:29.394765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-05-07T16:40:29.394781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-07T16:40:29.394809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-05-07T16:40:29.394828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-07T16:40:29.396164Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T16:40:29.396229Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T16:40:29.39638Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T16:40:29.396403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T16:40:29.396725Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T16:40:29.396755Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T16:40:29.397555Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T16:40:29.397696Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T16:40:29.397748Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T16:40:29.400441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-05-07T16:40:29.400856Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T16:41:06.190989Z","caller":"traceutil/trace.go:171","msg":"trace[1148634246] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"304.859519ms","start":"2026-05-07T16:41:05.886079Z","end":"2026-05-07T16:41:06.190939Z","steps":["trace[1148634246] 'process raft request' (duration: 302.779118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:41:06.191893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T16:41:05.886054Z","time spent":"305.039103ms","remote":"127.0.0.1:38444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T16:41:06.604306Z","caller":"traceutil/trace.go:171","msg":"trace[1232545216] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"306.151466ms","start":"2026-05-07T16:41:06.298123Z","end":"2026-05-07T16:41:06.604275Z","steps":["trace[1232545216] 'read index received' (duration: 305.89685ms)","trace[1232545216] 'applied index is now lower than readState.Index' (duration: 253.416µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:41:06.604416Z","caller":"traceutil/trace.go:171","msg":"trace[11046226] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"352.35355ms","start":"2026-05-07T16:41:06.252023Z","end":"2026-05-07T16:41:06.604377Z","steps":["trace[11046226] 'process raft request' (duration: 352.066554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:41:06.604601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T16:41:06.251991Z","time spent":"352.524063ms","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T16:41:06.604606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.462022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T16:41:06.604672Z","caller":"traceutil/trace.go:171","msg":"trace[837653031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"306.580434ms","start":"2026-05-07T16:41:06.298077Z","end":"2026-05-07T16:41:06.604658Z","steps":["trace[837653031] 'agreement among raft nodes before linearized reading' (duration: 306.331369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:41:06.604694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.647864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9bw6q\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-07T16:41:06.60473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T16:41:06.298058Z","time spent":"306.663196ms","remote":"127.0.0.1:38376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T16:41:06.604757Z","caller":"traceutil/trace.go:171","msg":"trace[129872947] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9bw6q; range_end:; response_count:1; response_revision:619; }","duration":"249.741927ms","start":"2026-05-07T16:41:06.354995Z","end":"2026-05-07T16:41:06.604737Z","steps":["trace[129872947] 'agreement among raft nodes before linearized reading' (duration: 249.463241ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:42:10.866972Z","caller":"traceutil/trace.go:171","msg":"trace[369712051] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"108.670134ms","start":"2026-05-07T16:42:10.75827Z","end":"2026-05-07T16:42:10.86694Z","steps":["trace[369712051] 'process raft request' (duration: 108.435379ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:42:28.337898Z","caller":"traceutil/trace.go:171","msg":"trace[828812780] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"104.776867ms","start":"2026-05-07T16:42:28.2331Z","end":"2026-05-07T16:42:28.337877Z","steps":["trace[828812780] 'process raft request' (duration: 104.494021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:42:35.100173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.026137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-07T16:42:35.100318Z","caller":"traceutil/trace.go:171","msg":"trace[843174518] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1841; }","duration":"165.411324ms","start":"2026-05-07T16:42:34.934873Z","end":"2026-05-07T16:42:35.100285Z","steps":["trace[843174518] 'range keys from in-memory index tree' (duration: 164.851853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:42:35.100173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.73092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8bfb7081-042e-43ce-97e5-23fefb756ef5\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-07T16:42:35.100346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.932604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T16:42:35.1005Z","caller":"traceutil/trace.go:171","msg":"trace[1723835764] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8bfb7081-042e-43ce-97e5-23fefb756ef5; range_end:; response_count:1; response_revision:1841; }","duration":"294.299391ms","start":"2026-05-07T16:42:34.806145Z","end":"2026-05-07T16:42:35.100444Z","steps":["trace[1723835764] 'range keys from in-memory index tree' (duration: 293.427594ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:42:35.100578Z","caller":"traceutil/trace.go:171","msg":"trace[558186849] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1841; }","duration":"178.26366ms","start":"2026-05-07T16:42:34.92229Z","end":"2026-05-07T16:42:35.100553Z","steps":["trace[558186849] 'count revisions from in-memory index tree' (duration: 177.7665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:42:35.100612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.682531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8bfb7081-042e-43ce-97e5-23fefb756ef5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-07T16:42:35.100692Z","caller":"traceutil/trace.go:171","msg":"trace[555492605] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8bfb7081-042e-43ce-97e5-23fefb756ef5; range_end:; response_count:1; response_revision:1841; }","duration":"264.809123ms","start":"2026-05-07T16:42:34.835862Z","end":"2026-05-07T16:42:35.100671Z","steps":["trace[555492605] 'range keys from in-memory index tree' (duration: 264.13552ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:42:35.133235Z","caller":"traceutil/trace.go:171","msg":"trace[685603944] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"185.578176ms","start":"2026-05-07T16:42:34.947602Z","end":"2026-05-07T16:42:35.13318Z","steps":["trace[685603944] 'process raft request' (duration: 185.412653ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:42:35.257689Z","caller":"traceutil/trace.go:171","msg":"trace[1221804677] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"151.370065ms","start":"2026-05-07T16:42:35.106283Z","end":"2026-05-07T16:42:35.257653Z","steps":["trace[1221804677] 'process raft request' (duration: 120.306806ms)","trace[1221804677] 'compare' (duration: 30.844424ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:42:35.566627Z","caller":"traceutil/trace.go:171","msg":"trace[1741751282] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"292.74745ms","start":"2026-05-07T16:42:35.273836Z","end":"2026-05-07T16:42:35.566583Z","steps":["trace[1741751282] 'process raft request' (duration: 291.914113ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:42:42.629871Z","caller":"traceutil/trace.go:171","msg":"trace[104669019] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"137.897387ms","start":"2026-05-07T16:42:42.491947Z","end":"2026-05-07T16:42:42.629845Z","steps":["trace[104669019] 'process raft request' (duration: 137.628152ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:42:45.993869Z","caller":"traceutil/trace.go:171","msg":"trace[494794510] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"140.232393ms","start":"2026-05-07T16:42:45.853618Z","end":"2026-05-07T16:42:45.99385Z","steps":["trace[494794510] 'process raft request' (duration: 140.11601ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:42:46.267271Z","caller":"traceutil/trace.go:171","msg":"trace[2097946154] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"100.96755ms","start":"2026-05-07T16:42:46.166276Z","end":"2026-05-07T16:42:46.267244Z","steps":["trace[2097946154] 'process raft request' (duration: 100.729946ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:43:25.507456Z","caller":"traceutil/trace.go:171","msg":"trace[2130739995] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"135.538769ms","start":"2026-05-07T16:43:25.371893Z","end":"2026-05-07T16:43:25.507432Z","steps":["trace[2130739995] 'process raft request' (duration: 135.386076ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:47:17.289738Z","caller":"traceutil/trace.go:171","msg":"trace[830804659] transaction","detail":"{read_only:false; response_revision:3800; number_of_response:1; }","duration":"257.161718ms","start":"2026-05-07T16:47:17.032513Z","end":"2026-05-07T16:47:17.289675Z","steps":["trace[830804659] 'process raft request' (duration: 181.942069ms)","trace[830804659] 'compare' (duration: 74.939303ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:48:42.879986Z","caller":"traceutil/trace.go:171","msg":"trace[1026077310] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"123.891655ms","start":"2026-05-07T16:48:42.756069Z","end":"2026-05-07T16:48:42.879961Z","steps":["trace[1026077310] 'process raft request' (duration: 123.734762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:48:46.542509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.364421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T16:48:46.542659Z","caller":"traceutil/trace.go:171","msg":"trace[1378901027] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5201; }","duration":"246.686148ms","start":"2026-05-07T16:48:46.295933Z","end":"2026-05-07T16:48:46.542619Z","steps":["trace[1378901027] 'read index received' (duration: 113.403749ms)","trace[1378901027] 'applied index is now lower than readState.Index' (duration: 133.281089ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:48:46.542748Z","caller":"traceutil/trace.go:171","msg":"trace[1110572595] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"275.821531ms","start":"2026-05-07T16:48:46.266918Z","end":"2026-05-07T16:48:46.54274Z","steps":["trace[1110572595] 'process raft request' (duration: 142.479281ms)","trace[1110572595] 'compare' (duration: 132.004904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T16:48:46.542926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.987584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T16:48:46.542945Z","caller":"traceutil/trace.go:171","msg":"trace[248083961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5011; }","duration":"247.038956ms","start":"2026-05-07T16:48:46.2959Z","end":"2026-05-07T16:48:46.542939Z","steps":["trace[248083961] 'agreement among raft nodes before linearized reading' (duration: 246.972304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:48:46.543155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.997578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7mkc9\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-07T16:48:46.54325Z","caller":"traceutil/trace.go:171","msg":"trace[1754362554] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7mkc9; range_end:; response_count:1; response_revision:5011; }","duration":"236.123011ms","start":"2026-05-07T16:48:46.307105Z","end":"2026-05-07T16:48:46.543228Z","steps":["trace[1754362554] 'agreement among raft nodes before linearized reading' (duration: 235.865706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:48:46.543314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.623645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0008c2bf-7477-48bc-bb10-96c18cf8fe7f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-07T16:48:46.543401Z","caller":"traceutil/trace.go:171","msg":"trace[324283870] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0008c2bf-7477-48bc-bb10-96c18cf8fe7f; range_end:; response_count:1; response_revision:5011; }","duration":"105.763458ms","start":"2026-05-07T16:48:46.437618Z","end":"2026-05-07T16:48:46.543382Z","steps":["trace[324283870] 'agreement among raft nodes before linearized reading' (duration: 105.369271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:48:46.959249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.947181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T16:48:46.959585Z","caller":"traceutil/trace.go:171","msg":"trace[1887070148] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"213.859683ms","start":"2026-05-07T16:48:46.74569Z","end":"2026-05-07T16:48:46.95955Z","steps":["trace[1887070148] 'process raft request' (duration: 45.513924ms)","trace[1887070148] 'compare' (duration: 167.629095ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:48:46.959652Z","caller":"traceutil/trace.go:171","msg":"trace[874154028] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"127.132468ms","start":"2026-05-07T16:48:46.832499Z","end":"2026-05-07T16:48:46.959631Z","steps":["trace[874154028] 'read index received' (duration: 47.681µs)","trace[874154028] 'applied index is now lower than readState.Index' (duration: 127.083337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T16:48:46.960048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.548497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:778177"} {"level":"info","ts":"2026-05-07T16:48:46.960075Z","caller":"traceutil/trace.go:171","msg":"trace[866249038] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5024; }","duration":"127.628208ms","start":"2026-05-07T16:48:46.832439Z","end":"2026-05-07T16:48:46.960067Z","steps":["trace[866249038] 'agreement among raft nodes before linearized reading' (duration: 127.264971ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:48:57.62366Z","caller":"traceutil/trace.go:171","msg":"trace[796840985] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"201.135453ms","start":"2026-05-07T16:48:57.422499Z","end":"2026-05-07T16:48:57.623634Z","steps":["trace[796840985] 'process raft request' (duration: 200.990471ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:49:05.120359Z","caller":"traceutil/trace.go:171","msg":"trace[676797912] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"136.383181ms","start":"2026-05-07T16:49:04.98395Z","end":"2026-05-07T16:49:05.120333Z","steps":["trace[676797912] 'process raft request' (duration: 136.229348ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:49:05.153371Z","caller":"traceutil/trace.go:171","msg":"trace[1357205785] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"137.740449ms","start":"2026-05-07T16:49:05.015598Z","end":"2026-05-07T16:49:05.153338Z","steps":["trace[1357205785] 'process raft request' (duration: 137.400752ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:49:05.324232Z","caller":"traceutil/trace.go:171","msg":"trace[1055821214] linearizableReadLoop","detail":"{readStateIndex:5614; appliedIndex:5608; }","duration":"170.939739ms","start":"2026-05-07T16:49:05.153277Z","end":"2026-05-07T16:49:05.324217Z","steps":["trace[1055821214] 'read index received' (duration: 83.347717ms)","trace[1055821214] 'applied index is now lower than readState.Index' (duration: 87.591162ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:49:05.324301Z","caller":"traceutil/trace.go:171","msg":"trace[536304848] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"197.369691ms","start":"2026-05-07T16:49:05.126908Z","end":"2026-05-07T16:49:05.324277Z","steps":["trace[536304848] 'process raft request' (duration: 197.169707ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:49:05.324316Z","caller":"traceutil/trace.go:171","msg":"trace[1995623161] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"196.960233ms","start":"2026-05-07T16:49:05.127314Z","end":"2026-05-07T16:49:05.324274Z","steps":["trace[1995623161] 'process raft request' (duration: 196.792169ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:49:05.324346Z","caller":"traceutil/trace.go:171","msg":"trace[700420485] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"196.910021ms","start":"2026-05-07T16:49:05.127428Z","end":"2026-05-07T16:49:05.324338Z","steps":["trace[700420485] 'process raft request' (duration: 196.714827ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:49:05.324212Z","caller":"traceutil/trace.go:171","msg":"trace[1662218334] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"197.531734ms","start":"2026-05-07T16:49:05.126639Z","end":"2026-05-07T16:49:05.324171Z","steps":["trace[1662218334] 'process raft request' (duration: 110.053134ms)","trace[1662218334] 'compare' (duration: 87.313486ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:49:05.324471Z","caller":"traceutil/trace.go:171","msg":"trace[1244337072] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"196.992003ms","start":"2026-05-07T16:49:05.127466Z","end":"2026-05-07T16:49:05.324458Z","steps":["trace[1244337072] 'process raft request' (duration: 196.703438ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:49:05.324508Z","caller":"traceutil/trace.go:171","msg":"trace[1374809225] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"196.216937ms","start":"2026-05-07T16:49:05.128272Z","end":"2026-05-07T16:49:05.324489Z","steps":["trace[1374809225] 'process raft request' (duration: 195.919141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:49:05.324616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.021112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb0d4f10-53a5-46d3-bba4-69a83044627b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-07T16:49:05.324681Z","caller":"traceutil/trace.go:171","msg":"trace[1899193128] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bb0d4f10-53a5-46d3-bba4-69a83044627b; range_end:; response_count:1; response_revision:5413; }","duration":"200.147855ms","start":"2026-05-07T16:49:05.124514Z","end":"2026-05-07T16:49:05.324662Z","steps":["trace[1899193128] 'agreement among raft nodes before linearized reading' (duration: 199.90625ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:50:29.426361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3040} {"level":"info","ts":"2026-05-07T16:50:29.514571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3040,"took":"86.921611ms","hash":377693228,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":31920128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-07T16:50:29.514643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":377693228,"revision":3040,"compact-revision":-1} {"level":"warn","ts":"2026-05-07T16:55:17.869637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.835279ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-05-07T16:55:17.86981Z","caller":"traceutil/trace.go:171","msg":"trace[1062779886] linearizableReadLoop","detail":"{readStateIndex:8566; appliedIndex:8565; }","duration":"153.418321ms","start":"2026-05-07T16:55:17.71637Z","end":"2026-05-07T16:55:17.869789Z","steps":["trace[1062779886] 'read index received' (duration: 38.336897ms)","trace[1062779886] 'applied index is now lower than readState.Index' (duration: 115.078854ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T16:55:17.869984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.591175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T16:55:17.870078Z","caller":"traceutil/trace.go:171","msg":"trace[1884090121] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8274; }","duration":"153.757768ms","start":"2026-05-07T16:55:17.716295Z","end":"2026-05-07T16:55:17.870053Z","steps":["trace[1884090121] 'agreement among raft nodes before linearized reading' (duration: 153.565144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:17.870189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.973663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T16:55:17.870312Z","caller":"traceutil/trace.go:171","msg":"trace[1173467025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8274; }","duration":"136.146176ms","start":"2026-05-07T16:55:17.734139Z","end":"2026-05-07T16:55:17.870285Z","steps":["trace[1173467025] 'agreement among raft nodes before linearized reading' (duration: 135.793179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:17.870195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.772538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T16:55:17.870418Z","caller":"traceutil/trace.go:171","msg":"trace[192265588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8274; }","duration":"132.043224ms","start":"2026-05-07T16:55:17.738348Z","end":"2026-05-07T16:55:17.870391Z","steps":["trace[192265588] 'agreement among raft nodes before linearized reading' (duration: 131.582155ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:55:19.361617Z","caller":"traceutil/trace.go:171","msg":"trace[82006325] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"148.697996ms","start":"2026-05-07T16:55:19.21289Z","end":"2026-05-07T16:55:19.361588Z","steps":["trace[82006325] 'process raft request' (duration: 64.147114ms)","trace[82006325] 'compare' (duration: 84.142274ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:55:19.770045Z","caller":"traceutil/trace.go:171","msg":"trace[753554517] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"186.916942ms","start":"2026-05-07T16:55:19.583102Z","end":"2026-05-07T16:55:19.770018Z","steps":["trace[753554517] 'process raft request' (duration: 158.818599ms)","trace[753554517] 'compare' (duration: 27.727995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T16:55:19.77001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.34499ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T16:55:19.770045Z","caller":"traceutil/trace.go:171","msg":"trace[1235854047] linearizableReadLoop","detail":"{readStateIndex:8579; appliedIndex:8578; }","duration":"183.162597ms","start":"2026-05-07T16:55:19.586857Z","end":"2026-05-07T16:55:19.77002Z","steps":["trace[1235854047] 'read index received' (duration: 155.074804ms)","trace[1235854047] 'applied index is now lower than readState.Index' (duration: 28.085913ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:55:19.770142Z","caller":"traceutil/trace.go:171","msg":"trace[1728333959] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8286; }","duration":"137.490263ms","start":"2026-05-07T16:55:19.632634Z","end":"2026-05-07T16:55:19.770124Z","steps":["trace[1728333959] 'range keys from in-memory index tree' (duration: 137.317959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:19.770403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.535984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-qcmwz\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-05-07T16:55:19.770567Z","caller":"traceutil/trace.go:171","msg":"trace[1399483015] range","detail":"{range_begin:/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-qcmwz; range_end:; response_count:1; response_revision:8287; }","duration":"183.749198ms","start":"2026-05-07T16:55:19.586798Z","end":"2026-05-07T16:55:19.770547Z","steps":["trace[1399483015] 'agreement among raft nodes before linearized reading' (duration: 183.295169ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:55:29.43306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6170} {"level":"info","ts":"2026-05-07T16:55:29.534947Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6170,"took":"99.805908ms","hash":1296434836,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":23269376,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-07T16:55:29.535018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1296434836,"revision":6170,"compact-revision":3040} {"level":"info","ts":"2026-05-07T16:55:46.232388Z","caller":"traceutil/trace.go:171","msg":"trace[2043794711] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8781; }","duration":"167.014923ms","start":"2026-05-07T16:55:46.065336Z","end":"2026-05-07T16:55:46.232351Z","steps":["trace[2043794711] 'read index received' (duration: 166.725207ms)","trace[2043794711] 'applied index is now lower than readState.Index' (duration: 288.766µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:55:46.232554Z","caller":"traceutil/trace.go:171","msg":"trace[1425431908] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"197.389481ms","start":"2026-05-07T16:55:46.03513Z","end":"2026-05-07T16:55:46.232519Z","steps":["trace[1425431908] 'process raft request' (duration: 197.025504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:46.23276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.38632ms","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-05-07T16:55:46.232815Z","caller":"traceutil/trace.go:171","msg":"trace[430233222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8482; }","duration":"167.521504ms","start":"2026-05-07T16:55:46.065279Z","end":"2026-05-07T16:55:46.2328Z","steps":["trace[430233222] 'agreement among raft nodes before linearized reading' (duration: 167.281628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:46.233268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.84158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T16:55:46.233683Z","caller":"traceutil/trace.go:171","msg":"trace[738768780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8482; }","duration":"167.262629ms","start":"2026-05-07T16:55:46.066369Z","end":"2026-05-07T16:55:46.233631Z","steps":["trace[738768780] 'agreement among raft nodes before linearized reading' (duration: 166.583856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:46.233976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.075144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T16:55:46.234046Z","caller":"traceutil/trace.go:171","msg":"trace[482636530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8482; }","duration":"162.203527ms","start":"2026-05-07T16:55:46.071828Z","end":"2026-05-07T16:55:46.234031Z","steps":["trace[482636530] 'agreement among raft nodes before linearized reading' (duration: 162.044354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:46.425733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.504443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T16:55:46.425896Z","caller":"traceutil/trace.go:171","msg":"trace[738226940] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"185.130695ms","start":"2026-05-07T16:55:46.240739Z","end":"2026-05-07T16:55:46.42587Z","steps":["trace[738226940] 'process raft request' (duration: 25.350607ms)","trace[738226940] 'compare' (duration: 158.826799ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:55:46.433574Z","caller":"traceutil/trace.go:171","msg":"trace[1606510007] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"192.283808ms","start":"2026-05-07T16:55:46.241269Z","end":"2026-05-07T16:55:46.433553Z","steps":["trace[1606510007] 'process raft request' (duration: 192.226717ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:55:46.433794Z","caller":"traceutil/trace.go:171","msg":"trace[655497841] linearizableReadLoop","detail":"{readStateIndex:8786; appliedIndex:8783; }","duration":"136.704516ms","start":"2026-05-07T16:55:46.297043Z","end":"2026-05-07T16:55:46.433747Z","steps":["trace[655497841] 'read index received' (duration: 136.361689ms)","trace[655497841] 'applied index is now lower than readState.Index' (duration: 339.957µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:55:46.433803Z","caller":"traceutil/trace.go:171","msg":"trace[1846166173] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"168.404061ms","start":"2026-05-07T16:55:46.265381Z","end":"2026-05-07T16:55:46.433785Z","steps":["trace[1846166173] 'process raft request' (duration: 168.138856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:46.433891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.846439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T16:55:46.433927Z","caller":"traceutil/trace.go:171","msg":"trace[879235455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8486; }","duration":"136.93073ms","start":"2026-05-07T16:55:46.296986Z","end":"2026-05-07T16:55:46.433917Z","steps":["trace[879235455] 'agreement among raft nodes before linearized reading' (duration: 136.862749ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:55:46.433626Z","caller":"traceutil/trace.go:171","msg":"trace[1457555668] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"192.773909ms","start":"2026-05-07T16:55:46.240775Z","end":"2026-05-07T16:55:46.433549Z","steps":["trace[1457555668] 'process raft request' (duration: 192.604956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:50.368318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.263727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T16:55:50.368418Z","caller":"traceutil/trace.go:171","msg":"trace[383566263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8501; }","duration":"117.421641ms","start":"2026-05-07T16:55:50.250974Z","end":"2026-05-07T16:55:50.368396Z","steps":["trace[383566263] 'range keys from in-memory index tree' (duration: 117.079303ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:55:50.479071Z","caller":"traceutil/trace.go:171","msg":"trace[1192073905] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"105.616273ms","start":"2026-05-07T16:55:50.373437Z","end":"2026-05-07T16:55:50.479053Z","steps":["trace[1192073905] 'process raft request' (duration: 105.424129ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:55:59.433337Z","caller":"traceutil/trace.go:171","msg":"trace[1404732980] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"112.791418ms","start":"2026-05-07T16:55:59.320517Z","end":"2026-05-07T16:55:59.433308Z","steps":["trace[1404732980] 'process raft request' (duration: 112.613605ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:55:59.464197Z","caller":"traceutil/trace.go:171","msg":"trace[1203363925] linearizableReadLoop","detail":"{readStateIndex:8904; appliedIndex:8903; }","duration":"109.537082ms","start":"2026-05-07T16:55:59.354634Z","end":"2026-05-07T16:55:59.464171Z","steps":["trace[1203363925] 'read index received' (duration: 78.659914ms)","trace[1203363925] 'applied index is now lower than readState.Index' (duration: 30.875848ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T16:55:59.464221Z","caller":"traceutil/trace.go:171","msg":"trace[754117944] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"109.678584ms","start":"2026-05-07T16:55:59.354512Z","end":"2026-05-07T16:55:59.46419Z","steps":["trace[754117944] 'process raft request' (duration: 109.42881ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:55:59.464402Z","caller":"traceutil/trace.go:171","msg":"trace[2003661790] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"102.509322ms","start":"2026-05-07T16:55:59.361865Z","end":"2026-05-07T16:55:59.464375Z","steps":["trace[2003661790] 'process raft request' (duration: 102.242377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:55:59.46449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.834368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tghpn\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-05-07T16:55:59.464529Z","caller":"traceutil/trace.go:171","msg":"trace[1421554232] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tghpn; range_end:; response_count:1; response_revision:8598; }","duration":"109.92359ms","start":"2026-05-07T16:55:59.354595Z","end":"2026-05-07T16:55:59.464518Z","steps":["trace[1421554232] 'agreement among raft nodes before linearized reading' (duration: 109.677625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:56:00.0794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.654317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-07T16:56:00.079498Z","caller":"traceutil/trace.go:171","msg":"trace[272866229] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8601; }","duration":"229.772109ms","start":"2026-05-07T16:55:59.849709Z","end":"2026-05-07T16:56:00.079481Z","steps":["trace[272866229] 'range keys from in-memory index tree' (duration: 229.560635ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:56:00.443208Z","caller":"traceutil/trace.go:171","msg":"trace[1751308746] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8908; }","duration":"145.173446ms","start":"2026-05-07T16:56:00.297981Z","end":"2026-05-07T16:56:00.443155Z","steps":["trace[1751308746] 'read index received' (duration: 144.958931ms)","trace[1751308746] 'applied index is now lower than readState.Index' (duration: 212.695µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T16:56:00.443527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.528153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T16:56:00.44351Z","caller":"traceutil/trace.go:171","msg":"trace[1179420712] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"245.85837ms","start":"2026-05-07T16:56:00.197622Z","end":"2026-05-07T16:56:00.44348Z","steps":["trace[1179420712] 'process raft request' (duration: 245.254928ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:56:00.443585Z","caller":"traceutil/trace.go:171","msg":"trace[1390081910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8602; }","duration":"145.653986ms","start":"2026-05-07T16:56:00.297912Z","end":"2026-05-07T16:56:00.443566Z","steps":["trace[1390081910] 'agreement among raft nodes before linearized reading' (duration: 145.537043ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:56:00.50654Z","caller":"traceutil/trace.go:171","msg":"trace[312153590] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"113.87085ms","start":"2026-05-07T16:56:00.392648Z","end":"2026-05-07T16:56:00.506519Z","steps":["trace[312153590] 'process raft request' (duration: 113.686016ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:56:46.578603Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002471.wal"} {"level":"warn","ts":"2026-05-07T16:57:17.076565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.989423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T16:57:17.076705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.7944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T16:57:17.076771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.239538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T16:57:17.076799Z","caller":"traceutil/trace.go:171","msg":"trace[300450194] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:9348; }","duration":"155.954733ms","start":"2026-05-07T16:57:16.920824Z","end":"2026-05-07T16:57:17.076779Z","steps":["trace[300450194] 'count revisions from in-memory index tree' (duration: 155.656086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:57:17.076739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.26524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T16:57:17.076826Z","caller":"traceutil/trace.go:171","msg":"trace[1191186543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9348; }","duration":"155.309249ms","start":"2026-05-07T16:57:16.9215Z","end":"2026-05-07T16:57:17.07681Z","steps":["trace[1191186543] 'range keys from in-memory index tree' (duration: 155.132836ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:57:17.076894Z","caller":"traceutil/trace.go:171","msg":"trace[1635911084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9348; }","duration":"158.510195ms","start":"2026-05-07T16:57:16.918354Z","end":"2026-05-07T16:57:17.076865Z","steps":["trace[1635911084] 'range keys from in-memory index tree' (duration: 158.001434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T16:57:17.077037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.136215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T16:57:17.077198Z","caller":"traceutil/trace.go:171","msg":"trace[1170201665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9348; }","duration":"154.33788ms","start":"2026-05-07T16:57:16.922802Z","end":"2026-05-07T16:57:17.07714Z","steps":["trace[1170201665] 'range keys from in-memory index tree' (duration: 153.821829ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:57:17.077159Z","caller":"traceutil/trace.go:171","msg":"trace[1588974106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9348; }","duration":"154.157966ms","start":"2026-05-07T16:57:16.92249Z","end":"2026-05-07T16:57:17.076648Z","steps":["trace[1588974106] 'range keys from in-memory index tree' (duration: 153.778779ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:57:17.226727Z","caller":"traceutil/trace.go:171","msg":"trace[310507808] transaction","detail":"{read_only:false; response_revision:9352; number_of_response:1; }","duration":"145.145084ms","start":"2026-05-07T16:57:17.08155Z","end":"2026-05-07T16:57:17.226695Z","steps":["trace[310507808] 'process raft request' (duration: 145.063462ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:57:17.226839Z","caller":"traceutil/trace.go:171","msg":"trace[667371405] transaction","detail":"{read_only:false; response_revision:9351; number_of_response:1; }","duration":"145.275156ms","start":"2026-05-07T16:57:17.081519Z","end":"2026-05-07T16:57:17.226794Z","steps":["trace[667371405] 'process raft request' (duration: 137.306386ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:57:17.226936Z","caller":"traceutil/trace.go:171","msg":"trace[955177749] transaction","detail":"{read_only:false; response_revision:9353; number_of_response:1; }","duration":"144.658985ms","start":"2026-05-07T16:57:17.082242Z","end":"2026-05-07T16:57:17.2269Z","steps":["trace[955177749] 'process raft request' (duration: 144.416219ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T16:57:59.446056Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-07T16:57:59.45287Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-07T16:57:59.453004Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-07T17:00:29.439763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8343} {"level":"info","ts":"2026-05-07T17:00:29.513841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8343,"took":"71.472395ms","hash":1430550628,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":26468352,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-07T17:00:29.513937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1430550628,"revision":8343,"compact-revision":6170} {"level":"info","ts":"2026-05-07T17:05:29.446138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10992} {"level":"info","ts":"2026-05-07T17:05:29.525343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10992,"took":"76.443513ms","hash":953391667,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":28180480,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-07T17:05:29.525395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":953391667,"revision":10992,"compact-revision":8343} {"level":"info","ts":"2026-05-07T17:06:52.451871Z","caller":"traceutil/trace.go:171","msg":"trace[316512148] transaction","detail":"{read_only:false; response_revision:13631; number_of_response:1; }","duration":"124.926509ms","start":"2026-05-07T17:06:52.326924Z","end":"2026-05-07T17:06:52.45185Z","steps":["trace[316512148] 'process raft request' (duration: 124.618272ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:07:26.827599Z","caller":"traceutil/trace.go:171","msg":"trace[1205340945] linearizableReadLoop","detail":"{readStateIndex:14304; appliedIndex:14303; }","duration":"183.664152ms","start":"2026-05-07T17:07:26.643869Z","end":"2026-05-07T17:07:26.827533Z","steps":["trace[1205340945] 'read index received' (duration: 183.56888ms)","trace[1205340945] 'applied index is now lower than readState.Index' (duration: 93.722µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:07:26.827807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.903187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T17:07:26.827866Z","caller":"traceutil/trace.go:171","msg":"trace[1629866983] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:13830; }","duration":"183.991509ms","start":"2026-05-07T17:07:26.643856Z","end":"2026-05-07T17:07:26.827848Z","steps":["trace[1629866983] 'agreement among raft nodes before linearized reading' (duration: 183.836976ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:07:26.827994Z","caller":"traceutil/trace.go:171","msg":"trace[1562073838] transaction","detail":"{read_only:false; response_revision:13830; number_of_response:1; }","duration":"200.527597ms","start":"2026-05-07T17:07:26.627195Z","end":"2026-05-07T17:07:26.827722Z","steps":["trace[1562073838] 'process raft request' (duration: 200.121679ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:07:26.849613Z","caller":"traceutil/trace.go:171","msg":"trace[1823839467] transaction","detail":"{read_only:false; response_revision:13831; number_of_response:1; }","duration":"201.510818ms","start":"2026-05-07T17:07:26.648076Z","end":"2026-05-07T17:07:26.849587Z","steps":["trace[1823839467] 'process raft request' (duration: 201.332474ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:07:28.335056Z","caller":"traceutil/trace.go:171","msg":"trace[1772826086] transaction","detail":"{read_only:false; response_revision:13844; number_of_response:1; }","duration":"103.583812ms","start":"2026-05-07T17:07:28.231441Z","end":"2026-05-07T17:07:28.335025Z","steps":["trace[1772826086] 'process raft request' (duration: 103.362068ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:07:36.11853Z","caller":"traceutil/trace.go:171","msg":"trace[1705986552] transaction","detail":"{read_only:false; response_revision:13996; number_of_response:1; }","duration":"201.968926ms","start":"2026-05-07T17:07:35.916529Z","end":"2026-05-07T17:07:36.118498Z","steps":["trace[1705986552] 'process raft request' (duration: 116.566127ms)","trace[1705986552] 'compare' (duration: 85.237255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:07:36.393019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.388478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T17:07:36.39308Z","caller":"traceutil/trace.go:171","msg":"trace[1849973616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13997; }","duration":"144.4934ms","start":"2026-05-07T17:07:36.248572Z","end":"2026-05-07T17:07:36.393066Z","steps":["trace[1849973616] 'range keys from in-memory index tree' (duration: 144.228235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:09:09.1321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.541334ms","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-05-07T17:09:09.132189Z","caller":"traceutil/trace.go:171","msg":"trace[107215604] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15049; }","duration":"110.658407ms","start":"2026-05-07T17:09:09.021514Z","end":"2026-05-07T17:09:09.132172Z","steps":["trace[107215604] 'count revisions from in-memory index tree' (duration: 110.393372ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:09:14.756292Z","caller":"traceutil/trace.go:171","msg":"trace[1937061632] transaction","detail":"{read_only:false; response_revision:15077; number_of_response:1; }","duration":"123.863057ms","start":"2026-05-07T17:09:14.632405Z","end":"2026-05-07T17:09:14.756268Z","steps":["trace[1937061632] 'process raft request' (duration: 90.605786ms)","trace[1937061632] 'compare' (duration: 32.760771ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:09:15.483014Z","caller":"traceutil/trace.go:171","msg":"trace[834269067] transaction","detail":"{read_only:false; response_revision:15081; number_of_response:1; }","duration":"103.800406ms","start":"2026-05-07T17:09:15.379188Z","end":"2026-05-07T17:09:15.482988Z","steps":["trace[834269067] 'process raft request' (duration: 103.649913ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:10:29.457033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13072} {"level":"info","ts":"2026-05-07T17:10:29.529541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13072,"took":"70.244453ms","hash":2484691070,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":31399936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-07T17:10:29.529593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2484691070,"revision":13072,"compact-revision":10992} {"level":"info","ts":"2026-05-07T17:15:00.961867Z","caller":"traceutil/trace.go:171","msg":"trace[1104666863] transaction","detail":"{read_only:false; response_revision:17742; number_of_response:1; }","duration":"138.315223ms","start":"2026-05-07T17:15:00.823526Z","end":"2026-05-07T17:15:00.961841Z","steps":["trace[1104666863] 'process raft request' (duration: 137.757442ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:15:01.343473Z","caller":"traceutil/trace.go:171","msg":"trace[465123290] linearizableReadLoop","detail":"{readStateIndex:18345; appliedIndex:18344; }","duration":"123.005107ms","start":"2026-05-07T17:15:01.220441Z","end":"2026-05-07T17:15:01.343446Z","steps":["trace[465123290] 'read index received' (duration: 122.787713ms)","trace[465123290] 'applied index is now lower than readState.Index' (duration: 216.574µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:15:01.343887Z","caller":"traceutil/trace.go:171","msg":"trace[1103986167] transaction","detail":"{read_only:false; response_revision:17745; number_of_response:1; }","duration":"156.179269ms","start":"2026-05-07T17:15:01.187691Z","end":"2026-05-07T17:15:01.34387Z","steps":["trace[1103986167] 'process raft request' (duration: 155.567447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:15:01.34425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.802343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-xkjrx\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-05-07T17:15:01.344296Z","caller":"traceutil/trace.go:171","msg":"trace[838282896] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-xkjrx; range_end:; response_count:1; response_revision:17745; }","duration":"123.909966ms","start":"2026-05-07T17:15:01.220375Z","end":"2026-05-07T17:15:01.344285Z","steps":["trace[838282896] 'agreement among raft nodes before linearized reading' (duration: 123.709352ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:15:01.82452Z","caller":"traceutil/trace.go:171","msg":"trace[1331072821] transaction","detail":"{read_only:false; response_revision:17747; number_of_response:1; }","duration":"162.480733ms","start":"2026-05-07T17:15:01.661998Z","end":"2026-05-07T17:15:01.824479Z","steps":["trace[1331072821] 'process raft request' (duration: 105.828176ms)","trace[1331072821] 'compare' (duration: 56.423043ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:15:11.105262Z","caller":"traceutil/trace.go:171","msg":"trace[1871821680] transaction","detail":"{read_only:false; response_revision:18013; number_of_response:1; }","duration":"100.00243ms","start":"2026-05-07T17:15:11.005186Z","end":"2026-05-07T17:15:11.105188Z","steps":["trace[1871821680] 'process raft request' (duration: 99.444518ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:15:29.46339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15568} {"level":"info","ts":"2026-05-07T17:15:29.566901Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15568,"took":"100.324649ms","hash":2702742119,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":33714176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-07T17:15:29.566974Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2702742119,"revision":15568,"compact-revision":13072} {"level":"info","ts":"2026-05-07T17:18:23.788451Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-07T17:18:23.79326Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-07T17:18:23.793374Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-07T17:20:03.97247Z","caller":"traceutil/trace.go:171","msg":"trace[2131081933] transaction","detail":"{read_only:false; response_revision:20074; number_of_response:1; }","duration":"230.285521ms","start":"2026-05-07T17:20:03.742151Z","end":"2026-05-07T17:20:03.972437Z","steps":["trace[2131081933] 'process raft request' (duration: 230.125938ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:20:03.975281Z","caller":"traceutil/trace.go:171","msg":"trace[505266131] transaction","detail":"{read_only:false; response_revision:20075; number_of_response:1; }","duration":"231.72294ms","start":"2026-05-07T17:20:03.743531Z","end":"2026-05-07T17:20:03.975254Z","steps":["trace[505266131] 'process raft request' (duration: 231.539876ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:20:04.197366Z","caller":"traceutil/trace.go:171","msg":"trace[879673347] transaction","detail":"{read_only:false; response_revision:20076; number_of_response:1; }","duration":"127.745284ms","start":"2026-05-07T17:20:04.069594Z","end":"2026-05-07T17:20:04.197339Z","steps":["trace[879673347] 'process raft request' (duration: 127.55308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:20:04.583248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.529161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:20:04.583647Z","caller":"traceutil/trace.go:171","msg":"trace[601587796] transaction","detail":"{read_only:false; response_revision:20078; number_of_response:1; }","duration":"335.755796ms","start":"2026-05-07T17:20:04.247842Z","end":"2026-05-07T17:20:04.583598Z","steps":["trace[601587796] 'process raft request' (duration: 168.787687ms)","trace[601587796] 'compare' (duration: 166.245195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:20:04.583788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:20:04.247812Z","time spent":"335.909759ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:20:04.583846Z","caller":"traceutil/trace.go:171","msg":"trace[891095358] transaction","detail":"{read_only:false; response_revision:20079; number_of_response:1; }","duration":"335.634303ms","start":"2026-05-07T17:20:04.248147Z","end":"2026-05-07T17:20:04.583782Z","steps":["trace[891095358] 'process raft request' (duration: 335.275216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:20:04.583984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:20:04.248137Z","time spent":"335.794956ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:20:04.584396Z","caller":"traceutil/trace.go:171","msg":"trace[2129516782] transaction","detail":"{read_only:false; response_revision:20080; number_of_response:1; }","duration":"130.877377ms","start":"2026-05-07T17:20:04.453476Z","end":"2026-05-07T17:20:04.584354Z","steps":["trace[2129516782] 'process raft request' (duration: 130.020669ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:20:29.473663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18163} {"level":"info","ts":"2026-05-07T17:20:29.598628Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18163,"took":"121.221446ms","hash":1457124621,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-07T17:20:29.598705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1457124621,"revision":18163,"compact-revision":15568} {"level":"info","ts":"2026-05-07T17:23:57.86481Z","caller":"traceutil/trace.go:171","msg":"trace[1373941397] transaction","detail":"{read_only:false; response_revision:22477; number_of_response:1; }","duration":"316.825232ms","start":"2026-05-07T17:23:57.547958Z","end":"2026-05-07T17:23:57.864783Z","steps":["trace[1373941397] 'process raft request' (duration: 316.639858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:23:57.864984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:23:57.547937Z","time spent":"316.962964ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:23:57.963276Z","caller":"traceutil/trace.go:171","msg":"trace[1806183298] transaction","detail":"{read_only:false; response_revision:22478; number_of_response:1; }","duration":"303.304485ms","start":"2026-05-07T17:23:57.659932Z","end":"2026-05-07T17:23:57.963236Z","steps":["trace[1806183298] 'process raft request' (duration: 256.749155ms)","trace[1806183298] 'compare' (duration: 46.143681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:23:57.963453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:23:57.659905Z","time spent":"303.455068ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:23:57.963431Z","caller":"traceutil/trace.go:171","msg":"trace[729285183] transaction","detail":"{read_only:false; response_revision:22479; number_of_response:1; }","duration":"194.500435ms","start":"2026-05-07T17:23:57.768904Z","end":"2026-05-07T17:23:57.963404Z","steps":["trace[729285183] 'process raft request' (duration: 194.198369ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:23:57.963499Z","caller":"traceutil/trace.go:171","msg":"trace[1798585921] transaction","detail":"{read_only:false; response_revision:22480; number_of_response:1; }","duration":"192.816661ms","start":"2026-05-07T17:23:57.770645Z","end":"2026-05-07T17:23:57.963462Z","steps":["trace[1798585921] 'process raft request' (duration: 192.563796ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:23:57.963628Z","caller":"traceutil/trace.go:171","msg":"trace[1254120309] linearizableReadLoop","detail":"{readStateIndex:23233; appliedIndex:23229; }","duration":"169.588522ms","start":"2026-05-07T17:23:57.794027Z","end":"2026-05-07T17:23:57.963616Z","steps":["trace[1254120309] 'read index received' (duration: 70.614495ms)","trace[1254120309] 'applied index is now lower than readState.Index' (duration: 98.972847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:23:58.017436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.834784ms","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-05-07T17:23:58.017523Z","caller":"traceutil/trace.go:171","msg":"trace[808859536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22480; }","duration":"221.993868ms","start":"2026-05-07T17:23:57.795509Z","end":"2026-05-07T17:23:58.017503Z","steps":["trace[808859536] 'agreement among raft nodes before linearized reading' (duration: 221.739393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:23:58.017543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.500618ms","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-05-07T17:23:58.017625Z","caller":"traceutil/trace.go:171","msg":"trace[1708744739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22480; }","duration":"223.618209ms","start":"2026-05-07T17:23:57.793982Z","end":"2026-05-07T17:23:58.0176Z","steps":["trace[1708744739] 'agreement among raft nodes before linearized reading' (duration: 169.699124ms)","trace[1708744739] 'range keys from in-memory index tree' (duration: 53.536048ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:23:58.017673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.004711ms","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-05-07T17:23:58.017748Z","caller":"traceutil/trace.go:171","msg":"trace[623558189] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:22480; }","duration":"205.080673ms","start":"2026-05-07T17:23:57.812646Z","end":"2026-05-07T17:23:58.017727Z","steps":["trace[623558189] 'agreement among raft nodes before linearized reading' (duration: 204.651475ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:25:29.487313Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20224} {"level":"info","ts":"2026-05-07T17:25:29.59474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20224,"took":"103.344173ms","hash":3474054761,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":42229760,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-07T17:25:29.59482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3474054761,"revision":20224,"compact-revision":18163} {"level":"info","ts":"2026-05-07T17:27:35.905647Z","caller":"traceutil/trace.go:171","msg":"trace[732292108] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"101.545157ms","start":"2026-05-07T17:27:35.804079Z","end":"2026-05-07T17:27:35.905624Z","steps":["trace[732292108] 'process raft request' (duration: 101.418975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:36.620153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.777342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:27:36.620272Z","caller":"traceutil/trace.go:171","msg":"trace[1299437113] linearizableReadLoop","detail":"{readStateIndex:25480; appliedIndex:25479; }","duration":"177.739252ms","start":"2026-05-07T17:27:36.442515Z","end":"2026-05-07T17:27:36.620255Z","steps":["trace[1299437113] 'read index received' (duration: 36.710365ms)","trace[1299437113] 'applied index is now lower than readState.Index' (duration: 141.027487ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:36.620333Z","caller":"traceutil/trace.go:171","msg":"trace[1663902330] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"323.601895ms","start":"2026-05-07T17:27:36.296689Z","end":"2026-05-07T17:27:36.620291Z","steps":["trace[1663902330] 'process raft request' (duration: 182.590158ms)","trace[1663902330] 'compare' (duration: 140.517637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:36.62045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.915586ms","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-05-07T17:27:36.620487Z","caller":"traceutil/trace.go:171","msg":"trace[1766409579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24670; }","duration":"177.997818ms","start":"2026-05-07T17:27:36.442481Z","end":"2026-05-07T17:27:36.620478Z","steps":["trace[1766409579] 'agreement among raft nodes before linearized reading' (duration: 177.820274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:36.620481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:36.29667Z","time spent":"323.735137ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:36.836252Z","caller":"traceutil/trace.go:171","msg":"trace[1646000155] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"211.764215ms","start":"2026-05-07T17:27:36.624464Z","end":"2026-05-07T17:27:36.836228Z","steps":["trace[1646000155] 'process raft request' (duration: 211.616752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:37.077734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.566229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:27:37.07788Z","caller":"traceutil/trace.go:171","msg":"trace[759186511] linearizableReadLoop","detail":"{readStateIndex:25484; appliedIndex:25482; }","duration":"189.237661ms","start":"2026-05-07T17:27:36.888628Z","end":"2026-05-07T17:27:37.077866Z","steps":["trace[759186511] 'read index received' (duration: 53.397755ms)","trace[759186511] 'applied index is now lower than readState.Index' (duration: 135.839186ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:37.077964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.330552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-07T17:27:37.077989Z","caller":"traceutil/trace.go:171","msg":"trace[430087292] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24674; }","duration":"189.386223ms","start":"2026-05-07T17:27:36.888595Z","end":"2026-05-07T17:27:37.077981Z","steps":["trace[430087292] 'agreement among raft nodes before linearized reading' (duration: 189.340422ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:37.077967Z","caller":"traceutil/trace.go:171","msg":"trace[1317078469] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"352.528337ms","start":"2026-05-07T17:27:36.725408Z","end":"2026-05-07T17:27:37.077936Z","steps":["trace[1317078469] 'process raft request' (duration: 352.405785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:37.078084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:36.72538Z","time spent":"352.644859ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:37.078478Z","caller":"traceutil/trace.go:171","msg":"trace[1960062863] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"353.054277ms","start":"2026-05-07T17:27:36.725395Z","end":"2026-05-07T17:27:37.07845Z","steps":["trace[1960062863] 'process raft request' (duration: 216.692252ms)","trace[1960062863] 'compare' (duration: 135.279404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:37.078789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:36.725361Z","time spent":"353.356663ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:41.195757Z","caller":"traceutil/trace.go:171","msg":"trace[1399375766] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"105.851962ms","start":"2026-05-07T17:27:41.089874Z","end":"2026-05-07T17:27:41.195726Z","steps":["trace[1399375766] 'process raft request' (duration: 105.631868ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:41.210058Z","caller":"traceutil/trace.go:171","msg":"trace[476729004] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"118.610694ms","start":"2026-05-07T17:27:41.091411Z","end":"2026-05-07T17:27:41.210022Z","steps":["trace[476729004] 'process raft request' (duration: 118.449431ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:41.37515Z","caller":"traceutil/trace.go:171","msg":"trace[1887156725] linearizableReadLoop","detail":"{readStateIndex:25522; appliedIndex:25520; }","duration":"159.293398ms","start":"2026-05-07T17:27:41.215822Z","end":"2026-05-07T17:27:41.375115Z","steps":["trace[1887156725] 'read index received' (duration: 64.064006ms)","trace[1887156725] 'applied index is now lower than readState.Index' (duration: 95.228172ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:41.376281Z","caller":"traceutil/trace.go:171","msg":"trace[121683032] transaction","detail":"{read_only:false; response_revision:24711; number_of_response:1; }","duration":"161.704176ms","start":"2026-05-07T17:27:41.21454Z","end":"2026-05-07T17:27:41.376244Z","steps":["trace[121683032] 'process raft request' (duration: 160.496823ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:41.376336Z","caller":"traceutil/trace.go:171","msg":"trace[1136723943] transaction","detail":"{read_only:false; response_revision:24710; number_of_response:1; }","duration":"162.274627ms","start":"2026-05-07T17:27:41.214023Z","end":"2026-05-07T17:27:41.376298Z","steps":["trace[1136723943] 'process raft request' (duration: 65.853202ms)","trace[1136723943] 'compare' (duration: 94.967877ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:41.376569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.734346ms","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-05-07T17:27:41.376632Z","caller":"traceutil/trace.go:171","msg":"trace[1196063352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24711; }","duration":"160.829119ms","start":"2026-05-07T17:27:41.215788Z","end":"2026-05-07T17:27:41.376618Z","steps":["trace[1196063352] 'agreement among raft nodes before linearized reading' (duration: 160.482572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:41.400938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.673883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-07T17:27:41.40101Z","caller":"traceutil/trace.go:171","msg":"trace[797754266] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:24712; }","duration":"126.802286ms","start":"2026-05-07T17:27:41.274189Z","end":"2026-05-07T17:27:41.400992Z","steps":["trace[797754266] 'agreement among raft nodes before linearized reading' (duration: 126.648983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:41.816083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.057036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:27:41.816288Z","caller":"traceutil/trace.go:171","msg":"trace[471860042] transaction","detail":"{read_only:false; response_revision:24715; number_of_response:1; }","duration":"171.889056ms","start":"2026-05-07T17:27:41.644381Z","end":"2026-05-07T17:27:41.81627Z","steps":["trace[471860042] 'process raft request' (duration: 171.803255ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:41.816519Z","caller":"traceutil/trace.go:171","msg":"trace[1603571559] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"382.258524ms","start":"2026-05-07T17:27:41.434248Z","end":"2026-05-07T17:27:41.816506Z","steps":["trace[1603571559] 'process raft request' (duration: 146.684058ms)","trace[1603571559] 'compare' (duration: 234.873082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:41.816593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:41.43422Z","time spent":"382.333575ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:41.836923Z","caller":"traceutil/trace.go:171","msg":"trace[49334223] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"135.390037ms","start":"2026-05-07T17:27:41.701493Z","end":"2026-05-07T17:27:41.836883Z","steps":["trace[49334223] 'process raft request' (duration: 135.223252ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:42.098962Z","caller":"traceutil/trace.go:171","msg":"trace[1330600221] transaction","detail":"{read_only:false; response_revision:24717; number_of_response:1; }","duration":"176.882956ms","start":"2026-05-07T17:27:41.922055Z","end":"2026-05-07T17:27:42.098938Z","steps":["trace[1330600221] 'process raft request' (duration: 176.716723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:42.364975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.981899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:27:42.365111Z","caller":"traceutil/trace.go:171","msg":"trace[277567519] transaction","detail":"{read_only:false; response_revision:24718; number_of_response:1; }","duration":"142.232612ms","start":"2026-05-07T17:27:42.22284Z","end":"2026-05-07T17:27:42.365073Z","steps":["trace[277567519] 'process raft request' (duration: 12.014048ms)","trace[277567519] 'compare' (duration: 129.740354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:42.665824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.766959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:27:42.665933Z","caller":"traceutil/trace.go:171","msg":"trace[1193075120] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"283.762018ms","start":"2026-05-07T17:27:42.382149Z","end":"2026-05-07T17:27:42.665911Z","steps":["trace[1193075120] 'process raft request' (duration: 133.811585ms)","trace[1193075120] 'compare' (duration: 149.489754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:42.982812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.115954ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-07T17:27:42.982907Z","caller":"traceutil/trace.go:171","msg":"trace[9632243] linearizableReadLoop","detail":"{readStateIndex:25532; appliedIndex:25531; }","duration":"124.925919ms","start":"2026-05-07T17:27:42.857965Z","end":"2026-05-07T17:27:42.982891Z","steps":["trace[9632243] 'read index received' (duration: 42.27µs)","trace[9632243] 'applied index is now lower than readState.Index' (duration: 124.882069ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:42.983049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.085463ms","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-05-07T17:27:42.983086Z","caller":"traceutil/trace.go:171","msg":"trace[882351753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24720; }","duration":"125.173255ms","start":"2026-05-07T17:27:42.857903Z","end":"2026-05-07T17:27:42.983076Z","steps":["trace[882351753] 'agreement among raft nodes before linearized reading' (duration: 125.030712ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:43.287486Z","caller":"traceutil/trace.go:171","msg":"trace[353753595] linearizableReadLoop","detail":"{readStateIndex:25534; appliedIndex:25533; }","duration":"292.565912ms","start":"2026-05-07T17:27:42.994898Z","end":"2026-05-07T17:27:43.287464Z","steps":["trace[353753595] 'read index received' (duration: 283.126225ms)","trace[353753595] 'applied index is now lower than readState.Index' (duration: 9.438277ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:43.287504Z","caller":"traceutil/trace.go:171","msg":"trace[41567618] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"300.969148ms","start":"2026-05-07T17:27:42.986508Z","end":"2026-05-07T17:27:43.287477Z","steps":["trace[41567618] 'process raft request' (duration: 291.511421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:43.287641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.737435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-07T17:27:43.28768Z","caller":"traceutil/trace.go:171","msg":"trace[1358126569] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24722; }","duration":"292.828687ms","start":"2026-05-07T17:27:42.994841Z","end":"2026-05-07T17:27:43.28767Z","steps":["trace[1358126569] 'agreement among raft nodes before linearized reading' (duration: 292.731485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:43.288558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:42.986485Z","time spent":"301.08295ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T17:27:43.298943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.014104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:43.299027Z","caller":"traceutil/trace.go:171","msg":"trace[80548527] 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:24724; }","duration":"237.163496ms","start":"2026-05-07T17:27:43.061842Z","end":"2026-05-07T17:27:43.299006Z","steps":["trace[80548527] 'agreement among raft nodes before linearized reading' (duration: 236.998283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:43.298996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.350169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:43.299086Z","caller":"traceutil/trace.go:171","msg":"trace[1966815675] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24724; }","duration":"156.531053ms","start":"2026-05-07T17:27:43.142537Z","end":"2026-05-07T17:27:43.299068Z","steps":["trace[1966815675] 'agreement among raft nodes before linearized reading' (duration: 156.293268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:43.299428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.199244ms","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-05-07T17:27:43.299462Z","caller":"traceutil/trace.go:171","msg":"trace[421821691] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24724; }","duration":"123.278796ms","start":"2026-05-07T17:27:43.176171Z","end":"2026-05-07T17:27:43.29945Z","steps":["trace[421821691] 'agreement among raft nodes before linearized reading' (duration: 123.140363ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:43.514697Z","caller":"traceutil/trace.go:171","msg":"trace[1401098608] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"130.876435ms","start":"2026-05-07T17:27:43.38377Z","end":"2026-05-07T17:27:43.514647Z","steps":["trace[1401098608] 'process raft request' (duration: 117.481821ms)","trace[1401098608] 'compare' (duration: 13.288332ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:43.555107Z","caller":"traceutil/trace.go:171","msg":"trace[1967226962] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"148.626447ms","start":"2026-05-07T17:27:43.406466Z","end":"2026-05-07T17:27:43.555093Z","steps":["trace[1967226962] 'process raft request' (duration: 148.486814ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:43.555063Z","caller":"traceutil/trace.go:171","msg":"trace[1624261562] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"170.925068ms","start":"2026-05-07T17:27:43.384107Z","end":"2026-05-07T17:27:43.555032Z","steps":["trace[1624261562] 'process raft request' (duration: 170.709484ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:43.760944Z","caller":"traceutil/trace.go:171","msg":"trace[1256260834] linearizableReadLoop","detail":"{readStateIndex:25541; appliedIndex:25540; }","duration":"178.895205ms","start":"2026-05-07T17:27:43.582013Z","end":"2026-05-07T17:27:43.760909Z","steps":["trace[1256260834] 'read index received' (duration: 103.514365ms)","trace[1256260834] 'applied index is now lower than readState.Index' (duration: 75.37932ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:43.761101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.072349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:43.761076Z","caller":"traceutil/trace.go:171","msg":"trace[959392959] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"201.884919ms","start":"2026-05-07T17:27:43.559141Z","end":"2026-05-07T17:27:43.761026Z","steps":["trace[959392959] 'process raft request' (duration: 126.452189ms)","trace[959392959] 'compare' (duration: 75.023282ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:43.761143Z","caller":"traceutil/trace.go:171","msg":"trace[911253952] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24729; }","duration":"179.16658ms","start":"2026-05-07T17:27:43.581965Z","end":"2026-05-07T17:27:43.761131Z","steps":["trace[911253952] 'agreement among raft nodes before linearized reading' (duration: 179.050398ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:44.293609Z","caller":"traceutil/trace.go:171","msg":"trace[521073172] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"185.674039ms","start":"2026-05-07T17:27:44.107907Z","end":"2026-05-07T17:27:44.293581Z","steps":["trace[521073172] 'process raft request' (duration: 185.509726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:44.649632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.346223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:27:44.649736Z","caller":"traceutil/trace.go:171","msg":"trace[638398107] linearizableReadLoop","detail":"{readStateIndex:25545; appliedIndex:25544; }","duration":"218.838575ms","start":"2026-05-07T17:27:44.430881Z","end":"2026-05-07T17:27:44.64972Z","steps":["trace[638398107] 'read index received' (duration: 71.296429ms)","trace[638398107] 'applied index is now lower than readState.Index' (duration: 147.540986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:44.649915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.036659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-07T17:27:44.649948Z","caller":"traceutil/trace.go:171","msg":"trace[1876890433] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24733; }","duration":"219.108431ms","start":"2026-05-07T17:27:44.430831Z","end":"2026-05-07T17:27:44.649939Z","steps":["trace[1876890433] 'agreement among raft nodes before linearized reading' (duration: 218.932497ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:44.650237Z","caller":"traceutil/trace.go:171","msg":"trace[1836998116] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"279.978533ms","start":"2026-05-07T17:27:44.370245Z","end":"2026-05-07T17:27:44.650223Z","steps":["trace[1836998116] 'process raft request' (duration: 131.943338ms)","trace[1836998116] 'compare' (duration: 146.643817ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:44.774388Z","caller":"traceutil/trace.go:171","msg":"trace[735596781] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"103.003166ms","start":"2026-05-07T17:27:44.671368Z","end":"2026-05-07T17:27:44.774371Z","steps":["trace[735596781] 'process raft request' (duration: 100.994856ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:45.091855Z","caller":"traceutil/trace.go:171","msg":"trace[415357076] linearizableReadLoop","detail":"{readStateIndex:25548; appliedIndex:25547; }","duration":"304.659581ms","start":"2026-05-07T17:27:44.787153Z","end":"2026-05-07T17:27:45.091813Z","steps":["trace[415357076] 'read index received' (duration: 260.134612ms)","trace[415357076] 'applied index is now lower than readState.Index' (duration: 44.523409ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:45.091893Z","caller":"traceutil/trace.go:171","msg":"trace[1476794327] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"306.955736ms","start":"2026-05-07T17:27:44.784909Z","end":"2026-05-07T17:27:45.091864Z","steps":["trace[1476794327] 'process raft request' (duration: 261.892185ms)","trace[1476794327] 'compare' (duration: 44.799196ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:45.092014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:44.784887Z","time spent":"307.066989ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T17:27:45.092034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.871295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:45.092075Z","caller":"traceutil/trace.go:171","msg":"trace[1947520726] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24736; }","duration":"304.979127ms","start":"2026-05-07T17:27:44.78708Z","end":"2026-05-07T17:27:45.092059Z","steps":["trace[1947520726] 'agreement among raft nodes before linearized reading' (duration: 304.894665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:45.092118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:44.787059Z","time spent":"305.048118ms","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-07T17:27:45.092247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.672062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-07T17:27:45.092303Z","caller":"traceutil/trace.go:171","msg":"trace[1452717026] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24736; }","duration":"303.738603ms","start":"2026-05-07T17:27:44.788552Z","end":"2026-05-07T17:27:45.092291Z","steps":["trace[1452717026] 'agreement among raft nodes before linearized reading' (duration: 303.627371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:45.092341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:44.788525Z","time spent":"303.807335ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":18,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-05-07T17:27:45.757439Z","caller":"traceutil/trace.go:171","msg":"trace[1269104284] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"397.504876ms","start":"2026-05-07T17:27:45.359909Z","end":"2026-05-07T17:27:45.757414Z","steps":["trace[1269104284] 'process raft request' (duration: 397.414644ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:45.757469Z","caller":"traceutil/trace.go:171","msg":"trace[577791285] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"452.905911ms","start":"2026-05-07T17:27:45.304528Z","end":"2026-05-07T17:27:45.757434Z","steps":["trace[577791285] 'process raft request' (duration: 452.611315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:45.757592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:45.359894Z","time spent":"397.618928ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:45.757617Z","caller":"traceutil/trace.go:171","msg":"trace[1065297017] linearizableReadLoop","detail":"{readStateIndex:25552; appliedIndex:25548; }","duration":"384.850405ms","start":"2026-05-07T17:27:45.372715Z","end":"2026-05-07T17:27:45.757565Z","steps":["trace[1065297017] 'read index received' (duration: 24.856122ms)","trace[1065297017] 'applied index is now lower than readState.Index' (duration: 359.993553ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:45.757687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:45.304516Z","time spent":"453.020774ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:45.757749Z","caller":"traceutil/trace.go:171","msg":"trace[113741022] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"396.370683ms","start":"2026-05-07T17:27:45.361368Z","end":"2026-05-07T17:27:45.757738Z","steps":["trace[113741022] 'process raft request' (duration: 396.004686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:45.757799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:45.361359Z","time spent":"396.409723ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T17:27:45.75784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.116871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:45.757889Z","caller":"traceutil/trace.go:171","msg":"trace[1081331957] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24740; }","duration":"385.192012ms","start":"2026-05-07T17:27:45.372685Z","end":"2026-05-07T17:27:45.757877Z","steps":["trace[1081331957] 'agreement among raft nodes before linearized reading' (duration: 385.11353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:45.75793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:45.372675Z","time spent":"385.244573ms","remote":"127.0.0.1:46156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-07T17:27:45.904509Z","caller":"traceutil/trace.go:171","msg":"trace[458902188] linearizableReadLoop","detail":"{readStateIndex:25553; appliedIndex:25552; }","duration":"146.840083ms","start":"2026-05-07T17:27:45.75765Z","end":"2026-05-07T17:27:45.90449Z","steps":["trace[458902188] 'read index received' (duration: 143.416724ms)","trace[458902188] 'applied index is now lower than readState.Index' (duration: 3.422459ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:45.904676Z","caller":"traceutil/trace.go:171","msg":"trace[2025519003] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"342.159473ms","start":"2026-05-07T17:27:45.562371Z","end":"2026-05-07T17:27:45.904531Z","steps":["trace[2025519003] 'process raft request' (duration: 338.679574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:45.904912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:45.562357Z","time spent":"342.452839ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T17:27:45.905044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.413997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-07T17:27:45.905045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.807345ms","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-05-07T17:27:45.905094Z","caller":"traceutil/trace.go:171","msg":"trace[1236881467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24742; }","duration":"343.477708ms","start":"2026-05-07T17:27:45.561605Z","end":"2026-05-07T17:27:45.905083Z","steps":["trace[1236881467] 'agreement among raft nodes before linearized reading' (duration: 343.003809ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:45.905102Z","caller":"traceutil/trace.go:171","msg":"trace[1255613831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24742; }","duration":"386.903896ms","start":"2026-05-07T17:27:45.518186Z","end":"2026-05-07T17:27:45.90509Z","steps":["trace[1255613831] 'agreement among raft nodes before linearized reading' (duration: 386.434547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:45.905137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:45.518167Z","time spent":"386.961687ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T17:27:45.905137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:45.561545Z","time spent":"343.574971ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T17:27:45.905284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.235401ms","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-05-07T17:27:45.905359Z","caller":"traceutil/trace.go:171","msg":"trace[1072982076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24743; }","duration":"140.343743ms","start":"2026-05-07T17:27:45.764997Z","end":"2026-05-07T17:27:45.905341Z","steps":["trace[1072982076] 'agreement among raft nodes before linearized reading' (duration: 140.085688ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:46.1112Z","caller":"traceutil/trace.go:171","msg":"trace[1374224487] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"201.421381ms","start":"2026-05-07T17:27:45.90976Z","end":"2026-05-07T17:27:46.111181Z","steps":["trace[1374224487] 'process raft request' (duration: 200.721447ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:46.111486Z","caller":"traceutil/trace.go:171","msg":"trace[35326060] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"201.547693ms","start":"2026-05-07T17:27:45.909911Z","end":"2026-05-07T17:27:46.111459Z","steps":["trace[35326060] 'process raft request' (duration: 201.204267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:46.459839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.836499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:46.459988Z","caller":"traceutil/trace.go:171","msg":"trace[1870801860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24746; }","duration":"162.068693ms","start":"2026-05-07T17:27:46.29789Z","end":"2026-05-07T17:27:46.459958Z","steps":["trace[1870801860] 'range keys from in-memory index tree' (duration: 161.688326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:46.460281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.86876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-05-07T17:27:46.460332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.972673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-05-07T17:27:46.460429Z","caller":"traceutil/trace.go:171","msg":"trace[1738632440] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24746; }","duration":"236.097436ms","start":"2026-05-07T17:27:46.224304Z","end":"2026-05-07T17:27:46.460401Z","steps":["trace[1738632440] 'range keys from in-memory index tree' (duration: 235.623856ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:46.46057Z","caller":"traceutil/trace.go:171","msg":"trace[710461727] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"158.448342ms","start":"2026-05-07T17:27:46.3021Z","end":"2026-05-07T17:27:46.460549Z","steps":["trace[710461727] 'process raft request' (duration: 42.222535ms)","trace[710461727] 'compare' (duration: 115.622435ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:46.780796Z","caller":"traceutil/trace.go:171","msg":"trace[54327434] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"124.962599ms","start":"2026-05-07T17:27:46.655814Z","end":"2026-05-07T17:27:46.780776Z","steps":["trace[54327434] 'process raft request' (duration: 124.825346ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:46.995691Z","caller":"traceutil/trace.go:171","msg":"trace[624788790] linearizableReadLoop","detail":"{readStateIndex:25561; appliedIndex:25559; }","duration":"217.404767ms","start":"2026-05-07T17:27:46.778257Z","end":"2026-05-07T17:27:46.995661Z","steps":["trace[624788790] 'read index received' (duration: 2.431668ms)","trace[624788790] 'applied index is now lower than readState.Index' (duration: 214.971669ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:46.995862Z","caller":"traceutil/trace.go:171","msg":"trace[157926849] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"315.444494ms","start":"2026-05-07T17:27:46.68039Z","end":"2026-05-07T17:27:46.995835Z","steps":["trace[157926849] 'process raft request' (duration: 235.468594ms)","trace[157926849] 'compare' (duration: 79.371139ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:46.995893Z","caller":"traceutil/trace.go:171","msg":"trace[268267576] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"215.790225ms","start":"2026-05-07T17:27:46.780078Z","end":"2026-05-07T17:27:46.995869Z","steps":["trace[268267576] 'process raft request' (duration: 215.364007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:46.995932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.651352ms","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-05-07T17:27:46.995992Z","caller":"traceutil/trace.go:171","msg":"trace[461626677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24750; }","duration":"217.767654ms","start":"2026-05-07T17:27:46.778208Z","end":"2026-05-07T17:27:46.995975Z","steps":["trace[461626677] 'agreement among raft nodes before linearized reading' (duration: 217.57358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:46.995998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:46.680363Z","time spent":"315.558947ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:47.069682Z","caller":"traceutil/trace.go:171","msg":"trace[924142752] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"247.699255ms","start":"2026-05-07T17:27:46.821963Z","end":"2026-05-07T17:27:47.069662Z","steps":["trace[924142752] 'process raft request' (duration: 247.308567ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:47.069828Z","caller":"traceutil/trace.go:171","msg":"trace[907091112] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"239.033994ms","start":"2026-05-07T17:27:46.830764Z","end":"2026-05-07T17:27:47.069798Z","steps":["trace[907091112] 'process raft request' (duration: 238.769099ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:47.86982Z","caller":"traceutil/trace.go:171","msg":"trace[1620041284] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"106.775261ms","start":"2026-05-07T17:27:47.763026Z","end":"2026-05-07T17:27:47.869801Z","steps":["trace[1620041284] 'process raft request' (duration: 106.631308ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.041574Z","caller":"traceutil/trace.go:171","msg":"trace[1029521961] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"275.546726ms","start":"2026-05-07T17:27:47.766006Z","end":"2026-05-07T17:27:48.041553Z","steps":["trace[1029521961] 'process raft request' (duration: 275.035856ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.052923Z","caller":"traceutil/trace.go:171","msg":"trace[1871635644] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"142.24912ms","start":"2026-05-07T17:27:47.91066Z","end":"2026-05-07T17:27:48.052909Z","steps":["trace[1871635644] 'process raft request' (duration: 142.157348ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.053174Z","caller":"traceutil/trace.go:171","msg":"trace[1914179984] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"141.607289ms","start":"2026-05-07T17:27:47.911545Z","end":"2026-05-07T17:27:48.053152Z","steps":["trace[1914179984] 'process raft request' (duration: 141.334732ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.053323Z","caller":"traceutil/trace.go:171","msg":"trace[367989547] linearizableReadLoop","detail":"{readStateIndex:25571; appliedIndex:25567; }","duration":"133.297705ms","start":"2026-05-07T17:27:47.92Z","end":"2026-05-07T17:27:48.053298Z","steps":["trace[367989547] 'read index received' (duration: 121.032472ms)","trace[367989547] 'applied index is now lower than readState.Index' (duration: 12.263813ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:48.05337Z","caller":"traceutil/trace.go:171","msg":"trace[1242340983] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"141.748962ms","start":"2026-05-07T17:27:47.911603Z","end":"2026-05-07T17:27:48.053352Z","steps":["trace[1242340983] 'process raft request' (duration: 141.454915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:48.053481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.471588ms","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-05-07T17:27:48.053517Z","caller":"traceutil/trace.go:171","msg":"trace[3823071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24759; }","duration":"133.544ms","start":"2026-05-07T17:27:47.919964Z","end":"2026-05-07T17:27:48.053508Z","steps":["trace[3823071] 'agreement among raft nodes before linearized reading' (duration: 133.444628ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.30173Z","caller":"traceutil/trace.go:171","msg":"trace[1922601142] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"183.374193ms","start":"2026-05-07T17:27:48.118332Z","end":"2026-05-07T17:27:48.301707Z","steps":["trace[1922601142] 'process raft request' (duration: 183.22552ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.364562Z","caller":"traceutil/trace.go:171","msg":"trace[1347279596] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"245.89013ms","start":"2026-05-07T17:27:48.118613Z","end":"2026-05-07T17:27:48.364503Z","steps":["trace[1347279596] 'process raft request' (duration: 243.636315ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.460402Z","caller":"traceutil/trace.go:171","msg":"trace[1037559864] linearizableReadLoop","detail":"{readStateIndex:25574; appliedIndex:25572; }","duration":"337.781716ms","start":"2026-05-07T17:27:48.122591Z","end":"2026-05-07T17:27:48.460373Z","steps":["trace[1037559864] 'read index received' (duration: 178.939487ms)","trace[1037559864] 'applied index is now lower than readState.Index' (duration: 158.840749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:48.460671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.059822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:48.460753Z","caller":"traceutil/trace.go:171","msg":"trace[1221945522] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24762; }","duration":"338.167463ms","start":"2026-05-07T17:27:48.122565Z","end":"2026-05-07T17:27:48.460732Z","steps":["trace[1221945522] 'agreement among raft nodes before linearized reading' (duration: 338.035571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:48.460805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:48.122556Z","time spent":"338.233414ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"info","ts":"2026-05-07T17:27:48.552165Z","caller":"traceutil/trace.go:171","msg":"trace[1881209154] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"127.541101ms","start":"2026-05-07T17:27:48.424595Z","end":"2026-05-07T17:27:48.552136Z","steps":["trace[1881209154] 'process raft request' (duration: 127.158704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:48.552189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.214576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-07T17:27:48.552232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.010719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:48.552279Z","caller":"traceutil/trace.go:171","msg":"trace[1058758949] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:24763; }","duration":"288.357519ms","start":"2026-05-07T17:27:48.263898Z","end":"2026-05-07T17:27:48.552255Z","steps":["trace[1058758949] 'agreement among raft nodes before linearized reading' (duration: 288.153455ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.55232Z","caller":"traceutil/trace.go:171","msg":"trace[98905240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24763; }","duration":"255.142442ms","start":"2026-05-07T17:27:48.29716Z","end":"2026-05-07T17:27:48.552302Z","steps":["trace[98905240] 'agreement among raft nodes before linearized reading' (duration: 254.983069ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:48.778508Z","caller":"traceutil/trace.go:171","msg":"trace[1776562152] linearizableReadLoop","detail":"{readStateIndex:25577; appliedIndex:25576; }","duration":"225.106919ms","start":"2026-05-07T17:27:48.55338Z","end":"2026-05-07T17:27:48.778487Z","steps":["trace[1776562152] 'read index received' (duration: 129.223844ms)","trace[1776562152] 'applied index is now lower than readState.Index' (duration: 95.882335ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:48.778533Z","caller":"traceutil/trace.go:171","msg":"trace[1370708933] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"312.552796ms","start":"2026-05-07T17:27:48.465953Z","end":"2026-05-07T17:27:48.778506Z","steps":["trace[1370708933] 'process raft request' (duration: 216.637451ms)","trace[1370708933] 'compare' (duration: 95.806033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:27:48.778638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.244221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-07T17:27:48.778653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:48.465933Z","time spent":"312.649149ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:48.778663Z","caller":"traceutil/trace.go:171","msg":"trace[1681246793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24764; }","duration":"225.311123ms","start":"2026-05-07T17:27:48.553345Z","end":"2026-05-07T17:27:48.778656Z","steps":["trace[1681246793] 'agreement among raft nodes before linearized reading' (duration: 225.252492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:48.778685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.9303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:48.778722Z","caller":"traceutil/trace.go:171","msg":"trace[722475057] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:24764; }","duration":"204.007162ms","start":"2026-05-07T17:27:48.574705Z","end":"2026-05-07T17:27:48.778712Z","steps":["trace[722475057] 'agreement among raft nodes before linearized reading' (duration: 203.94253ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:51.650019Z","caller":"traceutil/trace.go:171","msg":"trace[803052784] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"217.086399ms","start":"2026-05-07T17:27:51.432914Z","end":"2026-05-07T17:27:51.65Z","steps":["trace[803052784] 'process raft request' (duration: 216.673361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:52.110984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.454841ms","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-05-07T17:27:52.111085Z","caller":"traceutil/trace.go:171","msg":"trace[1273358463] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24782; }","duration":"247.608183ms","start":"2026-05-07T17:27:51.863455Z","end":"2026-05-07T17:27:52.111063Z","steps":["trace[1273358463] 'count revisions from in-memory index tree' (duration: 247.366988ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:52.338434Z","caller":"traceutil/trace.go:171","msg":"trace[367209085] linearizableReadLoop","detail":"{readStateIndex:25596; appliedIndex:25595; }","duration":"285.732567ms","start":"2026-05-07T17:27:52.052661Z","end":"2026-05-07T17:27:52.338394Z","steps":["trace[367209085] 'read index received' (duration: 285.580464ms)","trace[367209085] 'applied index is now lower than readState.Index' (duration: 150.373µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:27:52.338498Z","caller":"traceutil/trace.go:171","msg":"trace[1233133796] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"458.306867ms","start":"2026-05-07T17:27:51.880159Z","end":"2026-05-07T17:27:52.338465Z","steps":["trace[1233133796] 'process raft request' (duration: 457.992341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:52.338759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.073634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-07T17:27:52.338775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:27:51.88013Z","time spent":"458.45726ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:27:52.338805Z","caller":"traceutil/trace.go:171","msg":"trace[1748801051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24783; }","duration":"286.182616ms","start":"2026-05-07T17:27:52.05261Z","end":"2026-05-07T17:27:52.338793Z","steps":["trace[1748801051] 'agreement among raft nodes before linearized reading' (duration: 285.989932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:52.352823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.242287ms","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-05-07T17:27:52.352928Z","caller":"traceutil/trace.go:171","msg":"trace[1185113441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24787; }","duration":"243.40946ms","start":"2026-05-07T17:27:52.109498Z","end":"2026-05-07T17:27:52.352908Z","steps":["trace[1185113441] 'agreement among raft nodes before linearized reading' (duration: 243.176156ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:52.352943Z","caller":"traceutil/trace.go:171","msg":"trace[350313184] transaction","detail":"{read_only:false; response_revision:24787; number_of_response:1; }","duration":"277.629026ms","start":"2026-05-07T17:27:52.075283Z","end":"2026-05-07T17:27:52.352912Z","steps":["trace[350313184] 'process raft request' (duration: 277.32612ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:52.353137Z","caller":"traceutil/trace.go:171","msg":"trace[1626903279] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"290.339997ms","start":"2026-05-07T17:27:52.062785Z","end":"2026-05-07T17:27:52.353125Z","steps":["trace[1626903279] 'process raft request' (duration: 289.521122ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:52.353289Z","caller":"traceutil/trace.go:171","msg":"trace[375141930] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"287.680546ms","start":"2026-05-07T17:27:52.06558Z","end":"2026-05-07T17:27:52.353261Z","steps":["trace[375141930] 'process raft request' (duration: 286.91529ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:27:52.353335Z","caller":"traceutil/trace.go:171","msg":"trace[1642483985] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"287.672735ms","start":"2026-05-07T17:27:52.065651Z","end":"2026-05-07T17:27:52.353323Z","steps":["trace[1642483985] 'process raft request' (duration: 286.900809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:27:52.353489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.66693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:27:52.353544Z","caller":"traceutil/trace.go:171","msg":"trace[901701720] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24787; }","duration":"225.761582ms","start":"2026-05-07T17:27:52.127765Z","end":"2026-05-07T17:27:52.353527Z","steps":["trace[901701720] 'agreement among raft nodes before linearized reading' (duration: 225.680811ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.148347Z","caller":"traceutil/trace.go:171","msg":"trace[1287106017] linearizableReadLoop","detail":"{readStateIndex:25731; appliedIndex:25730; }","duration":"112.81487ms","start":"2026-05-07T17:28:02.035507Z","end":"2026-05-07T17:28:02.148322Z","steps":["trace[1287106017] 'read index received' (duration: 112.602566ms)","trace[1287106017] 'applied index is now lower than readState.Index' (duration: 211.244µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:02.14859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.058254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-685cee03-1d36-4bc6-8185-2639e880b775\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-07T17:28:02.148585Z","caller":"traceutil/trace.go:171","msg":"trace[37944312] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"138.881954ms","start":"2026-05-07T17:28:02.009676Z","end":"2026-05-07T17:28:02.148558Z","steps":["trace[37944312] 'process raft request' (duration: 138.468296ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.148638Z","caller":"traceutil/trace.go:171","msg":"trace[961010381] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-685cee03-1d36-4bc6-8185-2639e880b775; range_end:; response_count:1; response_revision:24915; }","duration":"113.163006ms","start":"2026-05-07T17:28:02.035458Z","end":"2026-05-07T17:28:02.148621Z","steps":["trace[961010381] 'agreement among raft nodes before linearized reading' (duration: 112.968033ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.453996Z","caller":"traceutil/trace.go:171","msg":"trace[1925685296] linearizableReadLoop","detail":"{readStateIndex:25732; appliedIndex:25731; }","duration":"155.404101ms","start":"2026-05-07T17:28:02.298569Z","end":"2026-05-07T17:28:02.453973Z","steps":["trace[1925685296] 'read index received' (duration: 155.297039ms)","trace[1925685296] 'applied index is now lower than readState.Index' (duration: 106.182µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:02.454023Z","caller":"traceutil/trace.go:171","msg":"trace[1372226774] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"298.609921ms","start":"2026-05-07T17:28:02.155383Z","end":"2026-05-07T17:28:02.453993Z","steps":["trace[1372226774] 'process raft request' (duration: 298.453078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:02.454087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.508843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:28:02.454119Z","caller":"traceutil/trace.go:171","msg":"trace[275018592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24916; }","duration":"155.585025ms","start":"2026-05-07T17:28:02.298525Z","end":"2026-05-07T17:28:02.45411Z","steps":["trace[275018592] 'agreement among raft nodes before linearized reading' (duration: 155.526124ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.631012Z","caller":"traceutil/trace.go:171","msg":"trace[95001522] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"236.557764ms","start":"2026-05-07T17:28:02.394434Z","end":"2026-05-07T17:28:02.630992Z","steps":["trace[95001522] 'process raft request' (duration: 236.502613ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.631056Z","caller":"traceutil/trace.go:171","msg":"trace[1618683238] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"263.918926ms","start":"2026-05-07T17:28:02.367101Z","end":"2026-05-07T17:28:02.631019Z","steps":["trace[1618683238] 'process raft request' (duration: 263.801894ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.631049Z","caller":"traceutil/trace.go:171","msg":"trace[2000345764] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"264.828584ms","start":"2026-05-07T17:28:02.36618Z","end":"2026-05-07T17:28:02.631009Z","steps":["trace[2000345764] 'process raft request' (duration: 264.586659ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.631274Z","caller":"traceutil/trace.go:171","msg":"trace[475456178] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"236.068216ms","start":"2026-05-07T17:28:02.395188Z","end":"2026-05-07T17:28:02.631256Z","steps":["trace[475456178] 'process raft request' (duration: 235.77324ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.631333Z","caller":"traceutil/trace.go:171","msg":"trace[1087467385] linearizableReadLoop","detail":"{readStateIndex:25737; appliedIndex:25732; }","duration":"177.251822ms","start":"2026-05-07T17:28:02.454052Z","end":"2026-05-07T17:28:02.631304Z","steps":["trace[1087467385] 'read index received' (duration: 176.668331ms)","trace[1087467385] 'applied index is now lower than readState.Index' (duration: 582.331µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:02.631463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.415683ms","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-05-07T17:28:02.631488Z","caller":"traceutil/trace.go:171","msg":"trace[1025720829] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"235.541606ms","start":"2026-05-07T17:28:02.395935Z","end":"2026-05-07T17:28:02.631476Z","steps":["trace[1025720829] 'process raft request' (duration: 235.237169ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.631502Z","caller":"traceutil/trace.go:171","msg":"trace[596340416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24921; }","duration":"232.487405ms","start":"2026-05-07T17:28:02.399004Z","end":"2026-05-07T17:28:02.631491Z","steps":["trace[596340416] 'agreement among raft nodes before linearized reading' (duration: 232.354191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:02.63151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.312972ms","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-05-07T17:28:02.631542Z","caller":"traceutil/trace.go:171","msg":"trace[477042212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24921; }","duration":"229.367503ms","start":"2026-05-07T17:28:02.402166Z","end":"2026-05-07T17:28:02.631534Z","steps":["trace[477042212] 'agreement among raft nodes before linearized reading' (duration: 229.188698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:02.631549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.556733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:28:02.631587Z","caller":"traceutil/trace.go:171","msg":"trace[1840030709] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24921; }","duration":"245.622125ms","start":"2026-05-07T17:28:02.385952Z","end":"2026-05-07T17:28:02.631574Z","steps":["trace[1840030709] 'agreement among raft nodes before linearized reading' (duration: 245.526313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:02.631598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.084693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-07T17:28:02.63159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.537585ms","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-05-07T17:28:02.63162Z","caller":"traceutil/trace.go:171","msg":"trace[1881361162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24921; }","duration":"208.132504ms","start":"2026-05-07T17:28:02.423481Z","end":"2026-05-07T17:28:02.631614Z","steps":["trace[1881361162] 'agreement among raft nodes before linearized reading' (duration: 208.074343ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.63164Z","caller":"traceutil/trace.go:171","msg":"trace[1113182892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24921; }","duration":"232.649388ms","start":"2026-05-07T17:28:02.398976Z","end":"2026-05-07T17:28:02.631625Z","steps":["trace[1113182892] 'agreement among raft nodes before linearized reading' (duration: 232.438734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:02.883791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.063937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:28:02.883905Z","caller":"traceutil/trace.go:171","msg":"trace[984385449] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"249.356138ms","start":"2026-05-07T17:28:02.634529Z","end":"2026-05-07T17:28:02.883885Z","steps":["trace[984385449] 'process raft request' (duration: 102.101327ms)","trace[984385449] 'compare' (duration: 146.809521ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:02.923668Z","caller":"traceutil/trace.go:171","msg":"trace[321617921] linearizableReadLoop","detail":"{readStateIndex:25741; appliedIndex:25737; }","duration":"287.740257ms","start":"2026-05-07T17:28:02.635901Z","end":"2026-05-07T17:28:02.923641Z","steps":["trace[321617921] 'read index received' (duration: 100.090779ms)","trace[321617921] 'applied index is now lower than readState.Index' (duration: 187.648448ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:02.923777Z","caller":"traceutil/trace.go:171","msg":"trace[12425826] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"288.217176ms","start":"2026-05-07T17:28:02.63553Z","end":"2026-05-07T17:28:02.923747Z","steps":["trace[12425826] 'process raft request' (duration: 288.028323ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.923801Z","caller":"traceutil/trace.go:171","msg":"trace[335384536] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"287.998632ms","start":"2026-05-07T17:28:02.635769Z","end":"2026-05-07T17:28:02.923768Z","steps":["trace[335384536] 'process raft request' (duration: 287.831349ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:02.923807Z","caller":"traceutil/trace.go:171","msg":"trace[1270298266] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"288.354389ms","start":"2026-05-07T17:28:02.635429Z","end":"2026-05-07T17:28:02.923783Z","steps":["trace[1270298266] 'process raft request' (duration: 287.980862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:02.923911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.988332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-05-07T17:28:02.923947Z","caller":"traceutil/trace.go:171","msg":"trace[1470295198] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24925; }","duration":"288.073333ms","start":"2026-05-07T17:28:02.635863Z","end":"2026-05-07T17:28:02.923937Z","steps":["trace[1470295198] 'agreement among raft nodes before linearized reading' (duration: 287.9037ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:03.271785Z","caller":"traceutil/trace.go:171","msg":"trace[536444213] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"450.785419ms","start":"2026-05-07T17:28:02.820969Z","end":"2026-05-07T17:28:03.271754Z","steps":["trace[536444213] 'process raft request' (duration: 388.199612ms)","trace[536444213] 'compare' (duration: 62.120178ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:03.271854Z","caller":"traceutil/trace.go:171","msg":"trace[557250857] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"214.07934ms","start":"2026-05-07T17:28:03.057754Z","end":"2026-05-07T17:28:03.271833Z","steps":["trace[557250857] 'process raft request' (duration: 214.017589ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:03.271906Z","caller":"traceutil/trace.go:171","msg":"trace[162095879] linearizableReadLoop","detail":"{readStateIndex:25743; appliedIndex:25741; }","duration":"348.13435ms","start":"2026-05-07T17:28:02.923733Z","end":"2026-05-07T17:28:03.271867Z","steps":["trace[162095879] 'read index received' (duration: 285.535623ms)","trace[162095879] 'applied index is now lower than readState.Index' (duration: 62.596487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:03.271939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:02.820936Z","time spent":"450.929672ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T17:28:03.27211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.527939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-685cee03-1d36-4bc6-8185-2639e880b775\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-05-07T17:28:03.272091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.442724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-07T17:28:03.272144Z","caller":"traceutil/trace.go:171","msg":"trace[396224368] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-685cee03-1d36-4bc6-8185-2639e880b775; range_end:; response_count:1; response_revision:24928; }","duration":"634.592721ms","start":"2026-05-07T17:28:02.637542Z","end":"2026-05-07T17:28:03.272134Z","steps":["trace[396224368] 'agreement among raft nodes before linearized reading' (duration: 634.450908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.27216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.839505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-07T17:28:03.272177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:02.637532Z","time spent":"634.638652ms","remote":"127.0.0.1:38516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-685cee03-1d36-4bc6-8185-2639e880b775\" "} {"level":"info","ts":"2026-05-07T17:28:03.272167Z","caller":"traceutil/trace.go:171","msg":"trace[1354871856] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:24928; }","duration":"566.585837ms","start":"2026-05-07T17:28:02.705566Z","end":"2026-05-07T17:28:03.272152Z","steps":["trace[1354871856] 'agreement among raft nodes before linearized reading' (duration: 566.333142ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:03.272199Z","caller":"traceutil/trace.go:171","msg":"trace[2044468931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24928; }","duration":"136.923106ms","start":"2026-05-07T17:28:03.135266Z","end":"2026-05-07T17:28:03.272189Z","steps":["trace[2044468931] 'agreement among raft nodes before linearized reading' (duration: 136.792024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.272222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:02.705546Z","time spent":"566.661679ms","remote":"127.0.0.1:38404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-05-07T17:28:03.272404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.642477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-07T17:28:03.272445Z","caller":"traceutil/trace.go:171","msg":"trace[1994306921] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:24928; }","duration":"318.738168ms","start":"2026-05-07T17:28:02.953697Z","end":"2026-05-07T17:28:03.272435Z","steps":["trace[1994306921] 'agreement among raft nodes before linearized reading' (duration: 318.657186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.272475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:02.953678Z","time spent":"318.78785ms","remote":"127.0.0.1:44274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-07T17:28:03.27259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.06648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-05-07T17:28:03.272653Z","caller":"traceutil/trace.go:171","msg":"trace[712773493] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:24928; }","duration":"341.159211ms","start":"2026-05-07T17:28:02.931482Z","end":"2026-05-07T17:28:03.272641Z","steps":["trace[712773493] 'agreement among raft nodes before linearized reading' (duration: 340.544969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.272691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:02.931463Z","time spent":"341.220503ms","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1279,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"info","ts":"2026-05-07T17:28:03.271855Z","caller":"traceutil/trace.go:171","msg":"trace[1965625204] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"446.078255ms","start":"2026-05-07T17:28:02.825741Z","end":"2026-05-07T17:28:03.271819Z","steps":["trace[1965625204] 'process raft request' (duration: 445.936282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.272848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:02.825692Z","time spent":"447.083425ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:28:03.504343Z","caller":"traceutil/trace.go:171","msg":"trace[2023705162] linearizableReadLoop","detail":"{readStateIndex:25746; appliedIndex:25744; }","duration":"227.932584ms","start":"2026-05-07T17:28:03.276383Z","end":"2026-05-07T17:28:03.504315Z","steps":["trace[2023705162] 'read index received' (duration: 84.821876ms)","trace[2023705162] 'applied index is now lower than readState.Index' (duration: 143.109608ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:03.504465Z","caller":"traceutil/trace.go:171","msg":"trace[740853874] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"228.135219ms","start":"2026-05-07T17:28:03.276317Z","end":"2026-05-07T17:28:03.504452Z","steps":["trace[740853874] 'process raft request' (duration: 227.770701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.504902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.469084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18ad58a1fcdab99f\" ","response":"range_response_count:1 size:742"} {"level":"info","ts":"2026-05-07T17:28:03.505023Z","caller":"traceutil/trace.go:171","msg":"trace[1579726326] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18ad58a1fcdab99f; range_end:; response_count:1; response_revision:24929; }","duration":"228.632378ms","start":"2026-05-07T17:28:03.276366Z","end":"2026-05-07T17:28:03.504998Z","steps":["trace[1579726326] 'agreement among raft nodes before linearized reading' (duration: 228.292782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.505235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.055651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:28:03.505251Z","caller":"traceutil/trace.go:171","msg":"trace[1760478648] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"142.285971ms","start":"2026-05-07T17:28:03.362903Z","end":"2026-05-07T17:28:03.505189Z","steps":["trace[1760478648] 'process raft request' (duration: 142.131148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.505266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.181229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-05-07T17:28:03.505304Z","caller":"traceutil/trace.go:171","msg":"trace[1666016040] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24930; }","duration":"126.188794ms","start":"2026-05-07T17:28:03.379098Z","end":"2026-05-07T17:28:03.505287Z","steps":["trace[1666016040] 'agreement among raft nodes before linearized reading' (duration: 126.045221ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:03.505316Z","caller":"traceutil/trace.go:171","msg":"trace[1368650852] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24930; }","duration":"228.309171ms","start":"2026-05-07T17:28:03.276996Z","end":"2026-05-07T17:28:03.505305Z","steps":["trace[1368650852] 'agreement among raft nodes before linearized reading' (duration: 228.130698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:03.505942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.571632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:28:03.505994Z","caller":"traceutil/trace.go:171","msg":"trace[1147623934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24930; }","duration":"206.655584ms","start":"2026-05-07T17:28:03.299325Z","end":"2026-05-07T17:28:03.505981Z","steps":["trace[1147623934] 'agreement among raft nodes before linearized reading' (duration: 206.581513ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:05.615325Z","caller":"traceutil/trace.go:171","msg":"trace[931700520] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"338.418039ms","start":"2026-05-07T17:28:05.276879Z","end":"2026-05-07T17:28:05.615297Z","steps":["trace[931700520] 'process raft request' (duration: 289.677085ms)","trace[931700520] 'compare' (duration: 48.061649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:05.615471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:05.276867Z","time spent":"338.531ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:28:05.615493Z","caller":"traceutil/trace.go:171","msg":"trace[436749336] linearizableReadLoop","detail":"{readStateIndex:25763; appliedIndex:25760; }","duration":"334.980221ms","start":"2026-05-07T17:28:05.280488Z","end":"2026-05-07T17:28:05.615468Z","steps":["trace[436749336] 'read index received' (duration: 93.921317ms)","trace[436749336] 'applied index is now lower than readState.Index' (duration: 241.057644ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:05.615635Z","caller":"traceutil/trace.go:171","msg":"trace[566669602] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"338.662693ms","start":"2026-05-07T17:28:05.276955Z","end":"2026-05-07T17:28:05.615618Z","steps":["trace[566669602] 'process raft request' (duration: 338.281285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:05.615843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:05.276931Z","time spent":"338.741535ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T17:28:05.615933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.421499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-685cee03-1d36-4bc6-8185-2639e880b775\" ","response":"range_response_count:1 size:3950"} {"level":"warn","ts":"2026-05-07T17:28:05.61593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.595537ms","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-05-07T17:28:05.615991Z","caller":"traceutil/trace.go:171","msg":"trace[791998863] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-685cee03-1d36-4bc6-8185-2639e880b775; range_end:; response_count:1; response_revision:24946; }","duration":"335.511031ms","start":"2026-05-07T17:28:05.280466Z","end":"2026-05-07T17:28:05.615977Z","steps":["trace[791998863] 'agreement among raft nodes before linearized reading' (duration: 335.199975ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:05.61602Z","caller":"traceutil/trace.go:171","msg":"trace[1918562661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24946; }","duration":"107.726479ms","start":"2026-05-07T17:28:05.508272Z","end":"2026-05-07T17:28:05.615999Z","steps":["trace[1918562661] 'agreement among raft nodes before linearized reading' (duration: 107.510195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:05.616028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:05.280459Z","time spent":"335.560211ms","remote":"127.0.0.1:38516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3971,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-685cee03-1d36-4bc6-8185-2639e880b775\" "} {"level":"warn","ts":"2026-05-07T17:28:05.61593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.371151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:28:05.616103Z","caller":"traceutil/trace.go:171","msg":"trace[1250260765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24946; }","duration":"319.550985ms","start":"2026-05-07T17:28:05.296536Z","end":"2026-05-07T17:28:05.616087Z","steps":["trace[1250260765] 'agreement among raft nodes before linearized reading' (duration: 319.185848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:05.616141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:05.296503Z","time spent":"319.626537ms","remote":"127.0.0.1:38368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T17:28:05.616476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.605009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-05-07T17:28:05.616512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.97051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-07T17:28:05.616522Z","caller":"traceutil/trace.go:171","msg":"trace[453244043] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24946; }","duration":"219.683631ms","start":"2026-05-07T17:28:05.396826Z","end":"2026-05-07T17:28:05.616509Z","steps":["trace[453244043] 'agreement among raft nodes before linearized reading' (duration: 218.907456ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:05.61658Z","caller":"traceutil/trace.go:171","msg":"trace[2134246073] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24946; }","duration":"167.053172ms","start":"2026-05-07T17:28:05.449517Z","end":"2026-05-07T17:28:05.61657Z","steps":["trace[2134246073] 'agreement among raft nodes before linearized reading' (duration: 166.213436ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:10.806454Z","caller":"traceutil/trace.go:171","msg":"trace[1907675633] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"150.259781ms","start":"2026-05-07T17:28:10.656138Z","end":"2026-05-07T17:28:10.806397Z","steps":["trace[1907675633] 'process raft request' (duration: 149.72299ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:10.806832Z","caller":"traceutil/trace.go:171","msg":"trace[1762376258] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"148.350882ms","start":"2026-05-07T17:28:10.658452Z","end":"2026-05-07T17:28:10.806802Z","steps":["trace[1762376258] 'process raft request' (duration: 147.861883ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:10.806989Z","caller":"traceutil/trace.go:171","msg":"trace[946755785] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"146.874294ms","start":"2026-05-07T17:28:10.660093Z","end":"2026-05-07T17:28:10.806967Z","steps":["trace[946755785] 'process raft request' (duration: 146.626419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:21.088797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.44107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-07T17:28:21.088915Z","caller":"traceutil/trace.go:171","msg":"trace[804152708] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25088; }","duration":"120.622364ms","start":"2026-05-07T17:28:20.968265Z","end":"2026-05-07T17:28:21.088887Z","steps":["trace[804152708] 'count revisions from in-memory index tree' (duration: 120.299917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:21.089019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.801038ms","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-05-07T17:28:21.089063Z","caller":"traceutil/trace.go:171","msg":"trace[488824224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25088; }","duration":"115.856579ms","start":"2026-05-07T17:28:20.973196Z","end":"2026-05-07T17:28:21.089053Z","steps":["trace[488824224] 'range keys from in-memory index tree' (duration: 115.709126ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:21.279159Z","caller":"traceutil/trace.go:171","msg":"trace[1801719078] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"185.481556ms","start":"2026-05-07T17:28:21.09364Z","end":"2026-05-07T17:28:21.279122Z","steps":["trace[1801719078] 'process raft request' (duration: 136.425367ms)","trace[1801719078] 'compare' (duration: 48.837025ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:21.366809Z","caller":"traceutil/trace.go:171","msg":"trace[447002083] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"272.708149ms","start":"2026-05-07T17:28:21.094082Z","end":"2026-05-07T17:28:21.366791Z","steps":["trace[447002083] 'process raft request' (duration: 272.658468ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:21.366815Z","caller":"traceutil/trace.go:171","msg":"trace[1347602023] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"272.75177ms","start":"2026-05-07T17:28:21.094029Z","end":"2026-05-07T17:28:21.366781Z","steps":["trace[1347602023] 'process raft request' (duration: 272.613447ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:21.366998Z","caller":"traceutil/trace.go:171","msg":"trace[169909642] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"272.811952ms","start":"2026-05-07T17:28:21.094163Z","end":"2026-05-07T17:28:21.366975Z","steps":["trace[169909642] 'process raft request' (duration: 272.604337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:21.367162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.779562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18746"} {"level":"info","ts":"2026-05-07T17:28:21.367191Z","caller":"traceutil/trace.go:171","msg":"trace[1329363949] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25093; }","duration":"272.845793ms","start":"2026-05-07T17:28:21.094338Z","end":"2026-05-07T17:28:21.367184Z","steps":["trace[1329363949] 'agreement among raft nodes before linearized reading' (duration: 272.72725ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:21.366977Z","caller":"traceutil/trace.go:171","msg":"trace[369578530] linearizableReadLoop","detail":"{readStateIndex:25914; appliedIndex:25910; }","duration":"272.570138ms","start":"2026-05-07T17:28:21.094379Z","end":"2026-05-07T17:28:21.366949Z","steps":["trace[369578530] 'read index received' (duration: 135.720573ms)","trace[369578530] 'applied index is now lower than readState.Index' (duration: 136.847495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:21.587591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.837728ms","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-05-07T17:28:21.587679Z","caller":"traceutil/trace.go:171","msg":"trace[200991569] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25093; }","duration":"166.98195ms","start":"2026-05-07T17:28:21.420673Z","end":"2026-05-07T17:28:21.587655Z","steps":["trace[200991569] 'range keys from in-memory index tree' (duration: 166.711934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:21.587801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.520805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:28:21.587904Z","caller":"traceutil/trace.go:171","msg":"trace[1773392934] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"157.284819ms","start":"2026-05-07T17:28:21.430601Z","end":"2026-05-07T17:28:21.587886Z","steps":["trace[1773392934] 'compare' (duration: 149.178839ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:21.993249Z","caller":"traceutil/trace.go:171","msg":"trace[298735262] linearizableReadLoop","detail":"{readStateIndex:25917; appliedIndex:25915; }","duration":"281.642552ms","start":"2026-05-07T17:28:21.711563Z","end":"2026-05-07T17:28:21.993206Z","steps":["trace[298735262] 'read index received' (duration: 17.830325ms)","trace[298735262] 'applied index is now lower than readState.Index' (duration: 263.811087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:21.993479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.903896ms","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-05-07T17:28:21.993477Z","caller":"traceutil/trace.go:171","msg":"trace[1230337097] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"322.878573ms","start":"2026-05-07T17:28:21.670568Z","end":"2026-05-07T17:28:21.993446Z","steps":["trace[1230337097] 'process raft request' (duration: 322.491526ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:21.993515Z","caller":"traceutil/trace.go:171","msg":"trace[460610104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25096; }","duration":"281.993828ms","start":"2026-05-07T17:28:21.711509Z","end":"2026-05-07T17:28:21.993503Z","steps":["trace[460610104] 'agreement among raft nodes before linearized reading' (duration: 281.816725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:21.993615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:28:21.670547Z","time spent":"322.989246ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:28:22.011663Z","caller":"traceutil/trace.go:171","msg":"trace[1632090480] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"230.376591ms","start":"2026-05-07T17:28:21.781262Z","end":"2026-05-07T17:28:22.011638Z","steps":["trace[1632090480] 'process raft request' (duration: 230.234539ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:22.149436Z","caller":"traceutil/trace.go:171","msg":"trace[676019387] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"151.700692ms","start":"2026-05-07T17:28:21.997712Z","end":"2026-05-07T17:28:22.149413Z","steps":["trace[676019387] 'process raft request' (duration: 110.512721ms)","trace[676019387] 'compare' (duration: 41.10386ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:22.405051Z","caller":"traceutil/trace.go:171","msg":"trace[17548645] linearizableReadLoop","detail":"{readStateIndex:25920; appliedIndex:25919; }","duration":"106.019992ms","start":"2026-05-07T17:28:22.299008Z","end":"2026-05-07T17:28:22.405028Z","steps":["trace[17548645] 'read index received' (duration: 15.56799ms)","trace[17548645] 'applied index is now lower than readState.Index' (duration: 90.450592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:22.405204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.186155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:28:22.405257Z","caller":"traceutil/trace.go:171","msg":"trace[1822238690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25099; }","duration":"106.290668ms","start":"2026-05-07T17:28:22.298958Z","end":"2026-05-07T17:28:22.405248Z","steps":["trace[1822238690] 'agreement among raft nodes before linearized reading' (duration: 106.151835ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:22.405438Z","caller":"traceutil/trace.go:171","msg":"trace[936533065] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"183.040547ms","start":"2026-05-07T17:28:22.222386Z","end":"2026-05-07T17:28:22.405426Z","steps":["trace[936533065] 'process raft request' (duration: 92.166297ms)","trace[936533065] 'compare' (duration: 90.3548ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:23.695056Z","caller":"traceutil/trace.go:171","msg":"trace[755591589] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"102.566234ms","start":"2026-05-07T17:28:23.592472Z","end":"2026-05-07T17:28:23.695039Z","steps":["trace[755591589] 'process raft request' (duration: 102.435302ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:30.926082Z","caller":"traceutil/trace.go:171","msg":"trace[1737930819] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"121.38601ms","start":"2026-05-07T17:28:30.804654Z","end":"2026-05-07T17:28:30.92604Z","steps":["trace[1737930819] 'process raft request' (duration: 121.119725ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:32.877012Z","caller":"traceutil/trace.go:171","msg":"trace[1582249554] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"128.682984ms","start":"2026-05-07T17:28:32.748285Z","end":"2026-05-07T17:28:32.876968Z","steps":["trace[1582249554] 'process raft request' (duration: 128.44271ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:36.266541Z","caller":"traceutil/trace.go:171","msg":"trace[608244230] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"226.380263ms","start":"2026-05-07T17:28:36.040142Z","end":"2026-05-07T17:28:36.266522Z","steps":["trace[608244230] 'process raft request' (duration: 226.278701ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:36.267444Z","caller":"traceutil/trace.go:171","msg":"trace[1507126828] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"209.327944ms","start":"2026-05-07T17:28:36.058094Z","end":"2026-05-07T17:28:36.267422Z","steps":["trace[1507126828] 'process raft request' (duration: 209.17896ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:46.023503Z","caller":"traceutil/trace.go:171","msg":"trace[1331349990] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"118.785567ms","start":"2026-05-07T17:28:45.904679Z","end":"2026-05-07T17:28:46.023464Z","steps":["trace[1331349990] 'process raft request' (duration: 98.604486ms)","trace[1331349990] 'compare' (duration: 19.971146ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:46.254786Z","caller":"traceutil/trace.go:171","msg":"trace[1896613892] linearizableReadLoop","detail":"{readStateIndex:26119; appliedIndex:26118; }","duration":"201.484849ms","start":"2026-05-07T17:28:46.053272Z","end":"2026-05-07T17:28:46.254757Z","steps":["trace[1896613892] 'read index received' (duration: 201.275515ms)","trace[1896613892] 'applied index is now lower than readState.Index' (duration: 208.474µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:46.255111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.819755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:28:46.255176Z","caller":"traceutil/trace.go:171","msg":"trace[405739192] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25293; }","duration":"201.963208ms","start":"2026-05-07T17:28:46.053196Z","end":"2026-05-07T17:28:46.25516Z","steps":["trace[405739192] 'agreement among raft nodes before linearized reading' (duration: 201.805765ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:46.25517Z","caller":"traceutil/trace.go:171","msg":"trace[228101678] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"226.591677ms","start":"2026-05-07T17:28:46.028538Z","end":"2026-05-07T17:28:46.25513Z","steps":["trace[228101678] 'process raft request' (duration: 225.879504ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:46.482372Z","caller":"traceutil/trace.go:171","msg":"trace[203834622] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26119; }","duration":"183.681935ms","start":"2026-05-07T17:28:46.29867Z","end":"2026-05-07T17:28:46.482352Z","steps":["trace[203834622] 'read index received' (duration: 125.348758ms)","trace[203834622] 'applied index is now lower than readState.Index' (duration: 58.332347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T17:28:46.482495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.804628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-07T17:28:46.482596Z","caller":"traceutil/trace.go:171","msg":"trace[2123283956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25294; }","duration":"183.91449ms","start":"2026-05-07T17:28:46.298664Z","end":"2026-05-07T17:28:46.482578Z","steps":["trace[2123283956] 'agreement among raft nodes before linearized reading' (duration: 183.771267ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:46.482704Z","caller":"traceutil/trace.go:171","msg":"trace[1038553391] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"227.161428ms","start":"2026-05-07T17:28:46.255403Z","end":"2026-05-07T17:28:46.482565Z","steps":["trace[1038553391] 'process raft request' (duration: 168.627626ms)","trace[1038553391] 'compare' (duration: 58.200245ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:46.506411Z","caller":"traceutil/trace.go:171","msg":"trace[1415865584] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"205.491079ms","start":"2026-05-07T17:28:46.300894Z","end":"2026-05-07T17:28:46.506385Z","steps":["trace[1415865584] 'process raft request' (duration: 205.333785ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:46.506653Z","caller":"traceutil/trace.go:171","msg":"trace[1502128736] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"202.52256ms","start":"2026-05-07T17:28:46.304094Z","end":"2026-05-07T17:28:46.506616Z","steps":["trace[1502128736] 'process raft request' (duration: 202.251984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:46.506825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.80707ms","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-05-07T17:28:46.50682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.850108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-07T17:28:46.506984Z","caller":"traceutil/trace.go:171","msg":"trace[67304141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25296; }","duration":"169.976723ms","start":"2026-05-07T17:28:46.336957Z","end":"2026-05-07T17:28:46.506934Z","steps":["trace[67304141] 'agreement among raft nodes before linearized reading' (duration: 169.688128ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:46.507076Z","caller":"traceutil/trace.go:171","msg":"trace[2011094221] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25296; }","duration":"197.107762ms","start":"2026-05-07T17:28:46.309925Z","end":"2026-05-07T17:28:46.507033Z","steps":["trace[2011094221] 'agreement among raft nodes before linearized reading' (duration: 196.714165ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:56.75125Z","caller":"traceutil/trace.go:171","msg":"trace[335222396] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"146.344385ms","start":"2026-05-07T17:28:56.604854Z","end":"2026-05-07T17:28:56.751198Z","steps":["trace[335222396] 'process raft request' (duration: 130.758535ms)","trace[335222396] 'compare' (duration: 15.14146ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:28:57.346925Z","caller":"traceutil/trace.go:171","msg":"trace[1758935380] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"108.658186ms","start":"2026-05-07T17:28:57.23824Z","end":"2026-05-07T17:28:57.346898Z","steps":["trace[1758935380] 'process raft request' (duration: 108.440232ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:57.347948Z","caller":"traceutil/trace.go:171","msg":"trace[2245554] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"108.872611ms","start":"2026-05-07T17:28:57.239054Z","end":"2026-05-07T17:28:57.347926Z","steps":["trace[2245554] 'process raft request' (duration: 108.683397ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:57.348145Z","caller":"traceutil/trace.go:171","msg":"trace[1229786683] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"106.637566ms","start":"2026-05-07T17:28:57.241483Z","end":"2026-05-07T17:28:57.348121Z","steps":["trace[1229786683] 'process raft request' (duration: 106.407621ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:28:57.34795Z","caller":"traceutil/trace.go:171","msg":"trace[1727378313] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"107.412882ms","start":"2026-05-07T17:28:57.240514Z","end":"2026-05-07T17:28:57.347927Z","steps":["trace[1727378313] 'process raft request' (duration: 107.346051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:28:57.603742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.977404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-07T17:28:57.603823Z","caller":"traceutil/trace.go:171","msg":"trace[728082044] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25374; }","duration":"105.116627ms","start":"2026-05-07T17:28:57.498687Z","end":"2026-05-07T17:28:57.603804Z","steps":["trace[728082044] 'range keys from in-memory index tree' (duration: 104.75902ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:29:17.575862Z","caller":"traceutil/trace.go:171","msg":"trace[718106047] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"128.199373ms","start":"2026-05-07T17:29:17.447618Z","end":"2026-05-07T17:29:17.575817Z","steps":["trace[718106047] 'process raft request' (duration: 92.440644ms)","trace[718106047] 'compare' (duration: 35.409682ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:30:16.55653Z","caller":"traceutil/trace.go:171","msg":"trace[510628397] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"384.002691ms","start":"2026-05-07T17:30:16.172512Z","end":"2026-05-07T17:30:16.556514Z","steps":["trace[510628397] 'process raft request' (duration: 383.835178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:30:16.556659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:30:16.172496Z","time spent":"384.107933ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T17:30:16.572054Z","caller":"traceutil/trace.go:171","msg":"trace[1017747110] linearizableReadLoop","detail":"{readStateIndex:27060; appliedIndex:27058; }","duration":"299.572645ms","start":"2026-05-07T17:30:16.272453Z","end":"2026-05-07T17:30:16.572026Z","steps":["trace[1017747110] 'read index received' (duration: 283.900175ms)","trace[1017747110] 'applied index is now lower than readState.Index' (duration: 15.67154ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T17:30:16.572099Z","caller":"traceutil/trace.go:171","msg":"trace[361179735] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"305.616005ms","start":"2026-05-07T17:30:16.266461Z","end":"2026-05-07T17:30:16.572077Z","steps":["trace[361179735] 'process raft request' (duration: 305.38021ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:30:16.572097Z","caller":"traceutil/trace.go:171","msg":"trace[1921058094] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"174.577984ms","start":"2026-05-07T17:30:16.397503Z","end":"2026-05-07T17:30:16.572081Z","steps":["trace[1921058094] 'process raft request' (duration: 174.476442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:30:16.572192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:30:16.266435Z","time spent":"305.708986ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T17:30:16.572331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.8447ms","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-05-07T17:30:16.572421Z","caller":"traceutil/trace.go:171","msg":"trace[1859550351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26214; }","duration":"299.980433ms","start":"2026-05-07T17:30:16.272418Z","end":"2026-05-07T17:30:16.572399Z","steps":["trace[1859550351] 'agreement among raft nodes before linearized reading' (duration: 299.710528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:30:16.572449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.470767ms","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-05-07T17:30:16.57254Z","caller":"traceutil/trace.go:171","msg":"trace[92769807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26214; }","duration":"274.562978ms","start":"2026-05-07T17:30:16.297942Z","end":"2026-05-07T17:30:16.572505Z","steps":["trace[92769807] 'agreement among raft nodes before linearized reading' (duration: 274.390295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:30:16.572549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.269678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-05-07T17:30:16.572567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.394918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-07T17:30:16.572601Z","caller":"traceutil/trace.go:171","msg":"trace[2053949679] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26214; }","duration":"240.351459ms","start":"2026-05-07T17:30:16.332237Z","end":"2026-05-07T17:30:16.572589Z","steps":["trace[2053949679] 'agreement among raft nodes before linearized reading' (duration: 240.179966ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:30:16.572633Z","caller":"traceutil/trace.go:171","msg":"trace[1969512393] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26214; }","duration":"252.46479ms","start":"2026-05-07T17:30:16.320152Z","end":"2026-05-07T17:30:16.572617Z","steps":["trace[1969512393] 'agreement among raft nodes before linearized reading' (duration: 252.298566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T17:30:16.57247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T17:30:16.272406Z","time spent":"300.055715ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T17:30:16.701308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.108974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-07T17:30:16.701387Z","caller":"traceutil/trace.go:171","msg":"trace[81325716] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"124.933679ms","start":"2026-05-07T17:30:16.576438Z","end":"2026-05-07T17:30:16.701371Z","steps":["trace[81325716] 'compare' (duration: 117.025773ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:30:16.828235Z","caller":"traceutil/trace.go:171","msg":"trace[1968854671] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"250.946481ms","start":"2026-05-07T17:30:16.577266Z","end":"2026-05-07T17:30:16.828212Z","steps":["trace[1968854671] 'process raft request' (duration: 245.38965ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:30:16.828486Z","caller":"traceutil/trace.go:171","msg":"trace[793773563] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"250.842928ms","start":"2026-05-07T17:30:16.577607Z","end":"2026-05-07T17:30:16.82845Z","steps":["trace[793773563] 'process raft request' (duration: 250.564613ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:30:29.492584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23461} {"level":"info","ts":"2026-05-07T17:30:29.648668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23461,"took":"153.098339ms","hash":123393026,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":36511744,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-07T17:30:29.648787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":123393026,"revision":23461,"compact-revision":20224} {"level":"info","ts":"2026-05-07T17:30:30.916771Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a28.wal"} {"level":"info","ts":"2026-05-07T17:33:15.351657Z","caller":"traceutil/trace.go:171","msg":"trace[615072622] transaction","detail":"{read_only:false; response_revision:27928; number_of_response:1; }","duration":"102.840821ms","start":"2026-05-07T17:33:15.248794Z","end":"2026-05-07T17:33:15.351635Z","steps":["trace[615072622] 'process raft request' (duration: 102.689918ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:33:15.355253Z","caller":"traceutil/trace.go:171","msg":"trace[1760898963] transaction","detail":"{read_only:false; response_revision:27929; number_of_response:1; }","duration":"105.451462ms","start":"2026-05-07T17:33:15.249783Z","end":"2026-05-07T17:33:15.355235Z","steps":["trace[1760898963] 'process raft request' (duration: 105.373001ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T17:35:29.498174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26311} {"level":"info","ts":"2026-05-07T17:35:29.632492Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26311,"took":"129.983589ms","hash":3204408696,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":34000896,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-07T17:35:29.632566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3204408696,"revision":26311,"compact-revision":23461} {"level":"info","ts":"2026-05-07T17:35:46.81163Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-07T17:35:46.815774Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-07T17:35:46.81598Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-07T17:40:29.505438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28949} {"level":"info","ts":"2026-05-07T17:40:29.652035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28949,"took":"143.051455ms","hash":2301117854,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":30265344,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-07T17:40:29.652106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2301117854,"revision":28949,"compact-revision":26311} {"level":"info","ts":"2026-05-07T17:45:29.516202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31169} {"level":"info","ts":"2026-05-07T17:45:29.640097Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31169,"took":"119.884446ms","hash":2627648733,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-07T17:45:29.640173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2627648733,"revision":31169,"compact-revision":28949} {"level":"info","ts":"2026-05-07T17:50:29.522884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33437} {"level":"info","ts":"2026-05-07T17:50:29.67108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33437,"took":"143.627978ms","hash":1647985829,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":32247808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-07T17:50:29.671165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1647985829,"revision":33437,"compact-revision":31169} {"level":"info","ts":"2026-05-07T17:55:29.533978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35729} {"level":"info","ts":"2026-05-07T17:55:29.672001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35729,"took":"133.650809ms","hash":3414845604,"current-db-size-bytes":60567552,"current-db-size":"61 MB","current-db-size-in-use-bytes":31490048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-07T17:55:29.672062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3414845604,"revision":35729,"compact-revision":33437} {"level":"info","ts":"2026-05-07T17:57:16.596404Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-07T17:57:16.600807Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-07T17:57:16.600926Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}