{"level":"warn","ts":"2026-05-09T21:42:57.333846Z","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-09T21:42:57.33396Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-09T21:42:57.334039Z","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-09T21:42:57.334056Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-09T21:42:57.334081Z","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-09T21:42:57.334762Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-05-09T21:42:57.33485Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-09T21:42:57.338701Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.599456ms"} {"level":"info","ts":"2026-05-09T21:42:57.343959Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-05-09T21:42:57.344047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T21:42:57.344073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-05-09T21:42:57.344092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T21:42:57.344107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-05-09T21:42:57.344152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-05-09T21:42:57.350366Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T21:42:57.352037Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T21:42:57.353335Z","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-09T21:42:57.356093Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T21:42:57.356323Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T21:42:57.356419Z","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-09T21:42:57.356647Z","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-09T21:42:57.356684Z","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-09T21:42:57.359562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-05-09T21:42:57.359766Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-09T21:42:57.361923Z","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-09T21:42:57.362122Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T21:42:57.362153Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T21:42:57.362148Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-09T21:42:57.362315Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-09T21:42:57.545057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T21:42:57.545135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T21:42:57.545177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-05-09T21:42:57.545204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-05-09T21:42:57.545217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-09T21:42:57.545239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-05-09T21:42:57.545272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-09T21:42:57.546419Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T21:42:57.546456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:42:57.54665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:42:57.546771Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T21:42:57.54685Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T21:42:57.55026Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T21:42:57.546493Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:42:57.551148Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-05-09T21:42:57.555654Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:42:57.555752Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:42:57.555779Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:43:29.388802Z","caller":"traceutil/trace.go:171","msg":"trace[659940833] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"107.600676ms","start":"2026-05-09T21:43:29.281171Z","end":"2026-05-09T21:43:29.388772Z","steps":["trace[659940833] 'process raft request' (duration: 107.410282ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:29.401759Z","caller":"traceutil/trace.go:171","msg":"trace[1211031988] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"120.392497ms","start":"2026-05-09T21:43:29.28134Z","end":"2026-05-09T21:43:29.401732Z","steps":["trace[1211031988] 'process raft request' (duration: 120.267474ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:33.765916Z","caller":"traceutil/trace.go:171","msg":"trace[696162133] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"362.075341ms","start":"2026-05-09T21:43:33.403756Z","end":"2026-05-09T21:43:33.765831Z","steps":["trace[696162133] 'read index received' (duration: 359.253722ms)","trace[696162133] 'applied index is now lower than readState.Index' (duration: 2.819309ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:43:33.76605Z","caller":"traceutil/trace.go:171","msg":"trace[961676997] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"368.122649ms","start":"2026-05-09T21:43:33.39785Z","end":"2026-05-09T21:43:33.765973Z","steps":["trace[961676997] 'process raft request' (duration: 365.195097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:33.766446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.637474ms","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-09T21:43:33.766699Z","caller":"traceutil/trace.go:171","msg":"trace[1116754792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"362.906779ms","start":"2026-05-09T21:43:33.40372Z","end":"2026-05-09T21:43:33.766627Z","steps":["trace[1116754792] 'agreement among raft nodes before linearized reading' (duration: 362.335837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:33.766925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:43:33.403704Z","time spent":"363.198235ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T21:43:33.767206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:43:33.397806Z","time spent":"368.358004ms","remote":"127.0.0.1:47338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:43:33.805452Z","caller":"traceutil/trace.go:171","msg":"trace[304168193] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"306.617428ms","start":"2026-05-09T21:43:33.498764Z","end":"2026-05-09T21:43:33.805381Z","steps":["trace[304168193] 'process raft request' (duration: 305.833662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:33.805628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.63648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:43:33.80582Z","caller":"traceutil/trace.go:171","msg":"trace[1239640003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"196.863075ms","start":"2026-05-09T21:43:33.608898Z","end":"2026-05-09T21:43:33.805761Z","steps":["trace[1239640003] 'agreement among raft nodes before linearized reading' (duration: 196.570599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:33.805656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:43:33.498732Z","time spent":"306.832093ms","remote":"127.0.0.1:47250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:43:33.805628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.279036ms","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-09T21:43:33.806061Z","caller":"traceutil/trace.go:171","msg":"trace[453871064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"380.756336ms","start":"2026-05-09T21:43:33.425282Z","end":"2026-05-09T21:43:33.806039Z","steps":["trace[453871064] 'agreement among raft nodes before linearized reading' (duration: 380.056231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:33.806136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:43:33.425265Z","time spent":"380.850048ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T21:45:08.424504Z","caller":"traceutil/trace.go:171","msg":"trace[1386073409] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"141.614609ms","start":"2026-05-09T21:45:08.282862Z","end":"2026-05-09T21:45:08.424476Z","steps":["trace[1386073409] 'read index received' (duration: 141.443186ms)","trace[1386073409] 'applied index is now lower than readState.Index' (duration: 170.063µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:08.424955Z","caller":"traceutil/trace.go:171","msg":"trace[1895613104] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"145.35647ms","start":"2026-05-09T21:45:08.279559Z","end":"2026-05-09T21:45:08.424915Z","steps":["trace[1895613104] 'process raft request' (duration: 144.747577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:08.4257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.318563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T21:45:08.425814Z","caller":"traceutil/trace.go:171","msg":"trace[1856691653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1815; }","duration":"116.455426ms","start":"2026-05-09T21:45:08.309293Z","end":"2026-05-09T21:45:08.425748Z","steps":["trace[1856691653] 'agreement among raft nodes before linearized reading' (duration: 116.182641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:08.425213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.316404ms","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-09T21:45:08.426027Z","caller":"traceutil/trace.go:171","msg":"trace[1577047153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1815; }","duration":"143.157261ms","start":"2026-05-09T21:45:08.282834Z","end":"2026-05-09T21:45:08.425991Z","steps":["trace[1577047153] 'agreement among raft nodes before linearized reading' (duration: 142.204501ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:53.671839Z","caller":"traceutil/trace.go:171","msg":"trace[841314212] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"136.990199ms","start":"2026-05-09T21:45:53.534826Z","end":"2026-05-09T21:45:53.671816Z","steps":["trace[841314212] 'process raft request' (duration: 136.861776ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:27.974378Z","caller":"traceutil/trace.go:171","msg":"trace[864415890] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"105.913817ms","start":"2026-05-09T21:47:27.868437Z","end":"2026-05-09T21:47:27.974351Z","steps":["trace[864415890] 'process raft request' (duration: 105.723043ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:25.368254Z","caller":"traceutil/trace.go:171","msg":"trace[119269481] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"165.747031ms","start":"2026-05-09T21:51:25.202481Z","end":"2026-05-09T21:51:25.368228Z","steps":["trace[119269481] 'process raft request' (duration: 165.549067ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:25.493198Z","caller":"traceutil/trace.go:171","msg":"trace[810215367] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"288.427754ms","start":"2026-05-09T21:51:25.204747Z","end":"2026-05-09T21:51:25.493175Z","steps":["trace[810215367] 'process raft request' (duration: 288.275261ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:25.49357Z","caller":"traceutil/trace.go:171","msg":"trace[1317225242] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"287.102716ms","start":"2026-05-09T21:51:25.206418Z","end":"2026-05-09T21:51:25.493521Z","steps":["trace[1317225242] 'process raft request' (duration: 286.722597ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:25.493642Z","caller":"traceutil/trace.go:171","msg":"trace[402514561] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"194.674157ms","start":"2026-05-09T21:51:25.298954Z","end":"2026-05-09T21:51:25.493628Z","steps":["trace[402514561] 'process raft request' (duration: 194.455852ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:25.493667Z","caller":"traceutil/trace.go:171","msg":"trace[1505560854] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5179; }","duration":"133.609877ms","start":"2026-05-09T21:51:25.360025Z","end":"2026-05-09T21:51:25.493635Z","steps":["trace[1505560854] 'read index received' (duration: 8.206305ms)","trace[1505560854] 'applied index is now lower than readState.Index' (duration: 125.402462ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:25.493758Z","caller":"traceutil/trace.go:171","msg":"trace[1199289353] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"128.637551ms","start":"2026-05-09T21:51:25.365058Z","end":"2026-05-09T21:51:25.493696Z","steps":["trace[1199289353] 'process raft request' (duration: 128.504668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:25.494024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.981744ms","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-09T21:51:25.494134Z","caller":"traceutil/trace.go:171","msg":"trace[489385545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4994; }","duration":"134.135557ms","start":"2026-05-09T21:51:25.359967Z","end":"2026-05-09T21:51:25.494103Z","steps":["trace[489385545] 'agreement among raft nodes before linearized reading' (duration: 133.849051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:25.499981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.170691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:720924"} {"level":"info","ts":"2026-05-09T21:51:25.500047Z","caller":"traceutil/trace.go:171","msg":"trace[982797707] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4995; }","duration":"121.291974ms","start":"2026-05-09T21:51:25.378737Z","end":"2026-05-09T21:51:25.500029Z","steps":["trace[982797707] 'agreement among raft nodes before linearized reading' (duration: 120.690111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:37.620292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.997779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:51:37.620434Z","caller":"traceutil/trace.go:171","msg":"trace[1109449076] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"216.231596ms","start":"2026-05-09T21:51:37.404191Z","end":"2026-05-09T21:51:37.620422Z","steps":["trace[1109449076] 'process raft request' (duration: 216.179954ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:37.620538Z","caller":"traceutil/trace.go:171","msg":"trace[1179178882] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"265.154608ms","start":"2026-05-09T21:51:37.355349Z","end":"2026-05-09T21:51:37.620503Z","steps":["trace[1179178882] 'process raft request' (duration: 103.806712ms)","trace[1179178882] 'compare' (duration: 160.904497ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:37.707797Z","caller":"traceutil/trace.go:171","msg":"trace[1584453216] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"168.451969ms","start":"2026-05-09T21:51:37.539325Z","end":"2026-05-09T21:51:37.707777Z","steps":["trace[1584453216] 'process raft request' (duration: 168.375908ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:37.708015Z","caller":"traceutil/trace.go:171","msg":"trace[629716960] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"170.082584ms","start":"2026-05-09T21:51:37.537893Z","end":"2026-05-09T21:51:37.707976Z","steps":["trace[629716960] 'process raft request' (duration: 169.688025ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:37.70811Z","caller":"traceutil/trace.go:171","msg":"trace[1115028469] linearizableReadLoop","detail":"{readStateIndex:5433; appliedIndex:5428; }","duration":"144.039199ms","start":"2026-05-09T21:51:37.564024Z","end":"2026-05-09T21:51:37.708063Z","steps":["trace[1115028469] 'read index received' (duration: 15.663914ms)","trace[1115028469] 'applied index is now lower than readState.Index' (duration: 128.373025ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:37.708204Z","caller":"traceutil/trace.go:171","msg":"trace[213105932] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"168.683053ms","start":"2026-05-09T21:51:37.539471Z","end":"2026-05-09T21:51:37.708154Z","steps":["trace[213105932] 'process raft request' (duration: 168.272055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:37.708325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.293544ms","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-09T21:51:37.708369Z","caller":"traceutil/trace.go:171","msg":"trace[692446070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5236; }","duration":"144.387227ms","start":"2026-05-09T21:51:37.563967Z","end":"2026-05-09T21:51:37.708355Z","steps":["trace[692446070] 'agreement among raft nodes before linearized reading' (duration: 144.232563ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:45.953224Z","caller":"traceutil/trace.go:171","msg":"trace[2027279119] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5415; }","duration":"219.225219ms","start":"2026-05-09T21:51:45.73397Z","end":"2026-05-09T21:51:45.953195Z","steps":["trace[2027279119] 'process raft request' (duration: 153.588872ms)","trace[2027279119] 'compare' (duration: 65.459634ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:45.953869Z","caller":"traceutil/trace.go:171","msg":"trace[1751710821] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"216.602494ms","start":"2026-05-09T21:51:45.737252Z","end":"2026-05-09T21:51:45.953854Z","steps":["trace[1751710821] 'process raft request' (duration: 216.308568ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:45.953923Z","caller":"traceutil/trace.go:171","msg":"trace[1734349236] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"219.357012ms","start":"2026-05-09T21:51:45.734497Z","end":"2026-05-09T21:51:45.953854Z","steps":["trace[1734349236] 'process raft request' (duration: 218.616527ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:45.953725Z","caller":"traceutil/trace.go:171","msg":"trace[101032096] linearizableReadLoop","detail":"{readStateIndex:5616; appliedIndex:5613; }","duration":"198.516099ms","start":"2026-05-09T21:51:45.755173Z","end":"2026-05-09T21:51:45.953689Z","steps":["trace[101032096] 'read index received' (duration: 132.33534ms)","trace[101032096] 'applied index is now lower than readState.Index' (duration: 66.179089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:51:45.95427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.081141ms","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-09T21:51:45.954517Z","caller":"traceutil/trace.go:171","msg":"trace[1917433950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5417; }","duration":"199.406217ms","start":"2026-05-09T21:51:45.755086Z","end":"2026-05-09T21:51:45.954492Z","steps":["trace[1917433950] 'agreement among raft nodes before linearized reading' (duration: 198.945158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:46.466508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.941026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T21:51:46.467016Z","caller":"traceutil/trace.go:171","msg":"trace[69924480] linearizableReadLoop","detail":"{readStateIndex:5621; appliedIndex:5619; }","duration":"400.291248ms","start":"2026-05-09T21:51:46.06669Z","end":"2026-05-09T21:51:46.466981Z","steps":["trace[69924480] 'read index received' (duration: 25.881732ms)","trace[69924480] 'applied index is now lower than readState.Index' (duration: 374.407896ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:51:46.467471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.762009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ghth8\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-05-09T21:51:46.467598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.252747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e39556b2-7288-4db3-b88b-421c1346ceea\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-09T21:51:46.467645Z","caller":"traceutil/trace.go:171","msg":"trace[1745397939] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ghth8; range_end:; response_count:1; response_revision:5421; }","duration":"400.911682ms","start":"2026-05-09T21:51:46.066635Z","end":"2026-05-09T21:51:46.467547Z","steps":["trace[1745397939] 'agreement among raft nodes before linearized reading' (duration: 400.562694ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:46.467665Z","caller":"traceutil/trace.go:171","msg":"trace[1530164341] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e39556b2-7288-4db3-b88b-421c1346ceea; range_end:; response_count:1; response_revision:5421; }","duration":"338.405489ms","start":"2026-05-09T21:51:46.12924Z","end":"2026-05-09T21:51:46.467645Z","steps":["trace[1530164341] 'agreement among raft nodes before linearized reading' (duration: 338.107533ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:46.467645Z","caller":"traceutil/trace.go:171","msg":"trace[238557910] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"408.970222ms","start":"2026-05-09T21:51:46.058616Z","end":"2026-05-09T21:51:46.467587Z","steps":["trace[238557910] 'process raft request' (duration: 408.140934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:46.467702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:51:46.066623Z","time spent":"401.069275ms","remote":"127.0.0.1:47338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-ghth8\" "} {"level":"warn","ts":"2026-05-09T21:51:46.467719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:51:46.129208Z","time spent":"338.500572ms","remote":"127.0.0.1:47338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e39556b2-7288-4db3-b88b-421c1346ceea\" "} {"level":"warn","ts":"2026-05-09T21:51:46.467856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:51:46.058601Z","time spent":"409.128506ms","remote":"127.0.0.1:47250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2581,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:51:46.594713Z","caller":"traceutil/trace.go:171","msg":"trace[1631810499] linearizableReadLoop","detail":"{readStateIndex:5622; appliedIndex:5621; }","duration":"118.629996ms","start":"2026-05-09T21:51:46.476057Z","end":"2026-05-09T21:51:46.594687Z","steps":["trace[1631810499] 'read index received' (duration: 90.804644ms)","trace[1631810499] 'applied index is now lower than readState.Index' (duration: 27.823302ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:46.594749Z","caller":"traceutil/trace.go:171","msg":"trace[1897638848] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"117.734348ms","start":"2026-05-09T21:51:46.476992Z","end":"2026-05-09T21:51:46.594726Z","steps":["trace[1897638848] 'process raft request' (duration: 117.598225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:46.595134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.243572ms","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-09T21:51:46.595145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.325671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-09T21:51:46.595185Z","caller":"traceutil/trace.go:171","msg":"trace[2054923000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5424; }","duration":"113.331154ms","start":"2026-05-09T21:51:46.481839Z","end":"2026-05-09T21:51:46.59517Z","steps":["trace[2054923000] 'agreement among raft nodes before linearized reading' (duration: 113.128969ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:46.595196Z","caller":"traceutil/trace.go:171","msg":"trace[725929178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5424; }","duration":"103.388973ms","start":"2026-05-09T21:51:46.49179Z","end":"2026-05-09T21:51:46.595179Z","steps":["trace[725929178] 'agreement among raft nodes before linearized reading' (duration: 103.217049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:46.595225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.53222ms","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-09T21:51:46.595273Z","caller":"traceutil/trace.go:171","msg":"trace[168310959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5424; }","duration":"107.612282ms","start":"2026-05-09T21:51:46.487652Z","end":"2026-05-09T21:51:46.595264Z","steps":["trace[168310959] 'agreement among raft nodes before linearized reading' (duration: 107.468849ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:46.595178Z","caller":"traceutil/trace.go:171","msg":"trace[1344220750] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"109.439511ms","start":"2026-05-09T21:51:46.485662Z","end":"2026-05-09T21:51:46.595101Z","steps":["trace[1344220750] 'process raft request' (duration: 108.991222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:46.595494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.432444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-09T21:51:46.595542Z","caller":"traceutil/trace.go:171","msg":"trace[702569081] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5424; }","duration":"119.515355ms","start":"2026-05-09T21:51:46.476016Z","end":"2026-05-09T21:51:46.595532Z","steps":["trace[702569081] 'agreement among raft nodes before linearized reading' (duration: 118.81542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:46.595508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.519688ms","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-09T21:51:46.594642Z","caller":"traceutil/trace.go:171","msg":"trace[1298568915] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"120.938527ms","start":"2026-05-09T21:51:46.473662Z","end":"2026-05-09T21:51:46.594601Z","steps":["trace[1298568915] 'process raft request' (duration: 93.183485ms)","trace[1298568915] 'compare' (duration: 27.586588ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:46.595629Z","caller":"traceutil/trace.go:171","msg":"trace[322038724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5424; }","duration":"113.696831ms","start":"2026-05-09T21:51:46.481901Z","end":"2026-05-09T21:51:46.595598Z","steps":["trace[322038724] 'agreement among raft nodes before linearized reading' (duration: 113.256052ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:52:57.614179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2985} {"level":"info","ts":"2026-05-09T21:52:57.687995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2985,"took":"72.177725ms","hash":2597314304,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":33820672,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T21:52:57.688068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2597314304,"revision":2985,"compact-revision":-1} {"level":"info","ts":"2026-05-09T21:57:57.622652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6071} {"level":"info","ts":"2026-05-09T21:57:57.736792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6071,"took":"112.046605ms","hash":1220308574,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":23289856,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-09T21:57:57.736876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1220308574,"revision":6071,"compact-revision":2985} {"level":"info","ts":"2026-05-09T21:58:00.482683Z","caller":"traceutil/trace.go:171","msg":"trace[1929855108] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"104.419283ms","start":"2026-05-09T21:58:00.378218Z","end":"2026-05-09T21:58:00.482638Z","steps":["trace[1929855108] 'process raft request' (duration: 104.199038ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:13.61424Z","caller":"traceutil/trace.go:171","msg":"trace[766002496] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"200.131511ms","start":"2026-05-09T21:58:13.414086Z","end":"2026-05-09T21:58:13.614218Z","steps":["trace[766002496] 'process raft request' (duration: 199.985348ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:13.650035Z","caller":"traceutil/trace.go:171","msg":"trace[1922228542] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"226.627862ms","start":"2026-05-09T21:58:13.423374Z","end":"2026-05-09T21:58:13.650002Z","steps":["trace[1922228542] 'process raft request' (duration: 226.412157ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:41.498527Z","caller":"traceutil/trace.go:171","msg":"trace[982962599] linearizableReadLoop","detail":"{readStateIndex:8857; appliedIndex:8856; }","duration":"175.917358ms","start":"2026-05-09T21:58:41.322571Z","end":"2026-05-09T21:58:41.498488Z","steps":["trace[982962599] 'read index received' (duration: 141.375795ms)","trace[982962599] 'applied index is now lower than readState.Index' (duration: 34.539583ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:58:41.498595Z","caller":"traceutil/trace.go:171","msg":"trace[1354255620] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"182.315184ms","start":"2026-05-09T21:58:41.316226Z","end":"2026-05-09T21:58:41.498542Z","steps":["trace[1354255620] 'process raft request' (duration: 147.678219ms)","trace[1354255620] 'compare' (duration: 34.361188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:58:41.498835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.233725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-09T21:58:41.498909Z","caller":"traceutil/trace.go:171","msg":"trace[837058599] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8552; }","duration":"176.364237ms","start":"2026-05-09T21:58:41.322526Z","end":"2026-05-09T21:58:41.49889Z","steps":["trace[837058599] 'agreement among raft nodes before linearized reading' (duration: 176.073251ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:41.509134Z","caller":"traceutil/trace.go:171","msg":"trace[1303856098] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"148.221381ms","start":"2026-05-09T21:58:41.360887Z","end":"2026-05-09T21:58:41.509109Z","steps":["trace[1303856098] 'process raft request' (duration: 147.998136ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:41.509149Z","caller":"traceutil/trace.go:171","msg":"trace[191468106] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"124.524718ms","start":"2026-05-09T21:58:41.384599Z","end":"2026-05-09T21:58:41.509124Z","steps":["trace[191468106] 'process raft request' (duration: 124.434486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:58:41.509612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.869493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-09T21:58:41.510572Z","caller":"traceutil/trace.go:171","msg":"trace[444300009] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8554; }","duration":"105.980495ms","start":"2026-05-09T21:58:41.403671Z","end":"2026-05-09T21:58:41.509652Z","steps":["trace[444300009] 'agreement among raft nodes before linearized reading' (duration: 105.532936ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:54.041658Z","caller":"traceutil/trace.go:171","msg":"trace[234211898] linearizableReadLoop","detail":"{readStateIndex:8973; appliedIndex:8972; }","duration":"204.526854ms","start":"2026-05-09T21:58:53.837097Z","end":"2026-05-09T21:58:54.041624Z","steps":["trace[234211898] 'read index received' (duration: 204.281598ms)","trace[234211898] 'applied index is now lower than readState.Index' (duration: 244.036µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:58:54.041715Z","caller":"traceutil/trace.go:171","msg":"trace[1337468437] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"204.595435ms","start":"2026-05-09T21:58:53.837076Z","end":"2026-05-09T21:58:54.041672Z","steps":["trace[1337468437] 'process raft request' (duration: 204.36638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:58:54.042048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.912591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qp6b7\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-05-09T21:58:54.042147Z","caller":"traceutil/trace.go:171","msg":"trace[180850112] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qp6b7; range_end:; response_count:1; response_revision:8662; }","duration":"205.073015ms","start":"2026-05-09T21:58:53.837051Z","end":"2026-05-09T21:58:54.042124Z","steps":["trace[180850112] 'agreement among raft nodes before linearized reading' (duration: 204.712287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:58:55.142996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.11668ms","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-09T21:58:55.143118Z","caller":"traceutil/trace.go:171","msg":"trace[821679903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8669; }","duration":"116.320764ms","start":"2026-05-09T21:58:55.02677Z","end":"2026-05-09T21:58:55.143091Z","steps":["trace[821679903] 'range keys from in-memory index tree' (duration: 115.878225ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:59:28.656961Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002468.wal"} {"level":"info","ts":"2026-05-09T22:00:13.736983Z","caller":"traceutil/trace.go:171","msg":"trace[1217680625] linearizableReadLoop","detail":"{readStateIndex:9759; appliedIndex:9758; }","duration":"153.551205ms","start":"2026-05-09T22:00:13.583407Z","end":"2026-05-09T22:00:13.736959Z","steps":["trace[1217680625] 'read index received' (duration: 152.950202ms)","trace[1217680625] 'applied index is now lower than readState.Index' (duration: 600.133µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:00:13.737171Z","caller":"traceutil/trace.go:171","msg":"trace[1876321326] transaction","detail":"{read_only:false; response_revision:9427; number_of_response:1; }","duration":"180.771342ms","start":"2026-05-09T22:00:13.55635Z","end":"2026-05-09T22:00:13.737121Z","steps":["trace[1876321326] 'process raft request' (duration: 180.073537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:00:13.737301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.059687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T22:00:13.737354Z","caller":"traceutil/trace.go:171","msg":"trace[498626683] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:9427; }","duration":"130.093978ms","start":"2026-05-09T22:00:13.607229Z","end":"2026-05-09T22:00:13.737323Z","steps":["trace[498626683] 'agreement among raft nodes before linearized reading' (duration: 130.036217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:00:13.737448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.498304ms","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-09T22:00:13.73756Z","caller":"traceutil/trace.go:171","msg":"trace[14918816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9427; }","duration":"153.653127ms","start":"2026-05-09T22:00:13.583884Z","end":"2026-05-09T22:00:13.737537Z","steps":["trace[14918816] 'agreement among raft nodes before linearized reading' (duration: 153.364241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:00:13.737234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.808451ms","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-09T22:00:13.737602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.628314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-09T22:00:13.737668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.30315ms","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-09T22:00:13.737733Z","caller":"traceutil/trace.go:171","msg":"trace[157269959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9427; }","duration":"137.427632ms","start":"2026-05-09T22:00:13.600289Z","end":"2026-05-09T22:00:13.737717Z","steps":["trace[157269959] 'agreement among raft nodes before linearized reading' (duration: 137.058065ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:00:13.737734Z","caller":"traceutil/trace.go:171","msg":"trace[924012584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9427; }","duration":"109.812967ms","start":"2026-05-09T22:00:13.627893Z","end":"2026-05-09T22:00:13.737706Z","steps":["trace[924012584] 'agreement among raft nodes before linearized reading' (duration: 109.391498ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:00:13.737706Z","caller":"traceutil/trace.go:171","msg":"trace[115455306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9427; }","duration":"154.27921ms","start":"2026-05-09T22:00:13.583389Z","end":"2026-05-09T22:00:13.737668Z","steps":["trace[115455306] 'agreement among raft nodes before linearized reading' (duration: 153.705888ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:00:46.264712Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:00:46.271738Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T22:00:46.271885Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T22:02:57.628937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8269} {"level":"info","ts":"2026-05-09T22:02:57.708339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8269,"took":"76.539921ms","hash":1004181298,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":24899584,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-09T22:02:57.708471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1004181298,"revision":8269,"compact-revision":6071} {"level":"info","ts":"2026-05-09T22:05:49.783961Z","caller":"traceutil/trace.go:171","msg":"trace[224010129] linearizableReadLoop","detail":"{readStateIndex:12258; appliedIndex:12257; }","duration":"124.488723ms","start":"2026-05-09T22:05:49.659447Z","end":"2026-05-09T22:05:49.783936Z","steps":["trace[224010129] 'read index received' (duration: 121.50027ms)","trace[224010129] 'applied index is now lower than readState.Index' (duration: 2.986773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:05:49.784189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.056926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-volume-7bc4c7fc88-l8vsn.18ae04eccc89c980\" ","response":"range_response_count:1 size:804"} {"level":"info","ts":"2026-05-09T22:05:49.784231Z","caller":"traceutil/trace.go:171","msg":"trace[1450895236] range","detail":"{range_begin:/registry/events/openstack/cinder-volume-7bc4c7fc88-l8vsn.18ae04eccc89c980; range_end:; response_count:1; response_revision:11846; }","duration":"125.148217ms","start":"2026-05-09T22:05:49.65907Z","end":"2026-05-09T22:05:49.784218Z","steps":["trace[1450895236] 'agreement among raft nodes before linearized reading' (duration: 124.978223ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:05:49.784205Z","caller":"traceutil/trace.go:171","msg":"trace[764320129] transaction","detail":"{read_only:false; response_revision:11846; number_of_response:1; }","duration":"152.942043ms","start":"2026-05-09T22:05:49.631221Z","end":"2026-05-09T22:05:49.784163Z","steps":["trace[764320129] 'process raft request' (duration: 149.771466ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:05:49.787347Z","caller":"traceutil/trace.go:171","msg":"trace[663868963] transaction","detail":"{read_only:false; response_revision:11847; number_of_response:1; }","duration":"123.741387ms","start":"2026-05-09T22:05:49.663587Z","end":"2026-05-09T22:05:49.787329Z","steps":["trace[663868963] 'process raft request' (duration: 123.595154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:05:50.995732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.359765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T22:05:50.995862Z","caller":"traceutil/trace.go:171","msg":"trace[1615601371] linearizableReadLoop","detail":"{readStateIndex:12266; appliedIndex:12265; }","duration":"190.279798ms","start":"2026-05-09T22:05:50.805563Z","end":"2026-05-09T22:05:50.995843Z","steps":["trace[1615601371] 'read index received' (duration: 22.681258ms)","trace[1615601371] 'applied index is now lower than readState.Index' (duration: 167.5969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:05:50.996021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.012594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:05:50.996061Z","caller":"traceutil/trace.go:171","msg":"trace[593395909] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11853; }","duration":"190.069704ms","start":"2026-05-09T22:05:50.805978Z","end":"2026-05-09T22:05:50.996048Z","steps":["trace[593395909] 'agreement among raft nodes before linearized reading' (duration: 189.977682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:05:50.996093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.519144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T22:05:50.996219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.502384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:05:50.99625Z","caller":"traceutil/trace.go:171","msg":"trace[1944912841] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11853; }","duration":"190.775179ms","start":"2026-05-09T22:05:50.805454Z","end":"2026-05-09T22:05:50.996229Z","steps":["trace[1944912841] 'agreement among raft nodes before linearized reading' (duration: 190.487853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:05:50.9963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.621405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:05:50.996261Z","caller":"traceutil/trace.go:171","msg":"trace[1091033071] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:11853; }","duration":"133.601206ms","start":"2026-05-09T22:05:50.862648Z","end":"2026-05-09T22:05:50.996249Z","steps":["trace[1091033071] 'agreement among raft nodes before linearized reading' (duration: 133.516154ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:05:50.996402Z","caller":"traceutil/trace.go:171","msg":"trace[425697361] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11853; }","duration":"189.748168ms","start":"2026-05-09T22:05:50.806639Z","end":"2026-05-09T22:05:50.996387Z","steps":["trace[425697361] 'agreement among raft nodes before linearized reading' (duration: 189.598545ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:05:50.996096Z","caller":"traceutil/trace.go:171","msg":"trace[1451023587] transaction","detail":"{read_only:false; response_revision:11853; number_of_response:1; }","duration":"190.75052ms","start":"2026-05-09T22:05:50.805312Z","end":"2026-05-09T22:05:50.996062Z","steps":["trace[1451023587] 'process raft request' (duration: 22.955754ms)","trace[1451023587] 'compare' (duration: 166.359045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:05:50.997031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.49858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-09T22:05:50.997086Z","caller":"traceutil/trace.go:171","msg":"trace[1784053145] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11853; }","duration":"187.604993ms","start":"2026-05-09T22:05:50.809469Z","end":"2026-05-09T22:05:50.997074Z","steps":["trace[1784053145] 'agreement among raft nodes before linearized reading' (duration: 187.267376ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:07:57.640536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10493} {"level":"info","ts":"2026-05-09T22:07:57.72703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10493,"took":"83.650289ms","hash":1087621156,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":25845760,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-09T22:07:57.727097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1087621156,"revision":10493,"compact-revision":8269} {"level":"info","ts":"2026-05-09T22:12:11.071906Z","caller":"traceutil/trace.go:171","msg":"trace[2094091987] transaction","detail":"{read_only:false; response_revision:14407; number_of_response:1; }","duration":"113.799615ms","start":"2026-05-09T22:12:10.958086Z","end":"2026-05-09T22:12:11.071886Z","steps":["trace[2094091987] 'process raft request' (duration: 111.352924ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:12:24.710571Z","caller":"traceutil/trace.go:171","msg":"trace[1578739708] linearizableReadLoop","detail":"{readStateIndex:14976; appliedIndex:14975; }","duration":"100.999486ms","start":"2026-05-09T22:12:24.609544Z","end":"2026-05-09T22:12:24.710543Z","steps":["trace[1578739708] 'read index received' (duration: 100.865063ms)","trace[1578739708] 'applied index is now lower than readState.Index' (duration: 133.013µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:12:24.710683Z","caller":"traceutil/trace.go:171","msg":"trace[1631360553] transaction","detail":"{read_only:false; response_revision:14473; number_of_response:1; }","duration":"194.80138ms","start":"2026-05-09T22:12:24.515846Z","end":"2026-05-09T22:12:24.710647Z","steps":["trace[1631360553] 'process raft request' (duration: 194.531904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:12:24.710775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.2153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:12:24.710838Z","caller":"traceutil/trace.go:171","msg":"trace[1762259165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14473; }","duration":"101.372084ms","start":"2026-05-09T22:12:24.609452Z","end":"2026-05-09T22:12:24.710825Z","steps":["trace[1762259165] 'agreement among raft nodes before linearized reading' (duration: 101.243961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:12:28.003659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.285589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T22:12:28.003829Z","caller":"traceutil/trace.go:171","msg":"trace[731192784] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:14509; }","duration":"107.558424ms","start":"2026-05-09T22:12:27.896247Z","end":"2026-05-09T22:12:28.003805Z","steps":["trace[731192784] 'count revisions from in-memory index tree' (duration: 107.129446ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:12:50.749002Z","caller":"traceutil/trace.go:171","msg":"trace[268957856] transaction","detail":"{read_only:false; response_revision:14818; number_of_response:1; }","duration":"155.227637ms","start":"2026-05-09T22:12:50.593739Z","end":"2026-05-09T22:12:50.748966Z","steps":["trace[268957856] 'process raft request' (duration: 155.033723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:12:50.749143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.807703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:12:50.749186Z","caller":"traceutil/trace.go:171","msg":"trace[117321920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14818; }","duration":"139.917195ms","start":"2026-05-09T22:12:50.609261Z","end":"2026-05-09T22:12:50.749179Z","steps":["trace[117321920] 'agreement among raft nodes before linearized reading' (duration: 139.813023ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:12:50.749024Z","caller":"traceutil/trace.go:171","msg":"trace[1780533868] linearizableReadLoop","detail":"{readStateIndex:15336; appliedIndex:15336; }","duration":"139.64671ms","start":"2026-05-09T22:12:50.609346Z","end":"2026-05-09T22:12:50.748993Z","steps":["trace[1780533868] 'read index received' (duration: 139.634379ms)","trace[1780533868] 'applied index is now lower than readState.Index' (duration: 9.811µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:12:50.761596Z","caller":"traceutil/trace.go:171","msg":"trace[2108612554] transaction","detail":"{read_only:false; response_revision:14819; number_of_response:1; }","duration":"112.951328ms","start":"2026-05-09T22:12:50.648627Z","end":"2026-05-09T22:12:50.761578Z","steps":["trace[2108612554] 'process raft request' (duration: 112.500847ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:12:57.647929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12714} {"level":"info","ts":"2026-05-09T22:12:57.730077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12714,"took":"76.795353ms","hash":3353198772,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":28639232,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-09T22:12:57.73015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3353198772,"revision":12714,"compact-revision":10493} {"level":"warn","ts":"2026-05-09T22:14:16.454458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.082538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T22:14:16.454765Z","caller":"traceutil/trace.go:171","msg":"trace[1699446184] transaction","detail":"{read_only:false; response_revision:15632; number_of_response:1; }","duration":"133.698769ms","start":"2026-05-09T22:14:16.321031Z","end":"2026-05-09T22:14:16.454729Z","steps":["trace[1699446184] 'process raft request' (duration: 18.236513ms)","trace[1699446184] 'compare' (duration: 114.838133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:14:16.710856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.80845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:14:16.710972Z","caller":"traceutil/trace.go:171","msg":"trace[636127371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15632; }","duration":"101.979703ms","start":"2026-05-09T22:14:16.608964Z","end":"2026-05-09T22:14:16.710943Z","steps":["trace[636127371] 'range keys from in-memory index tree' (duration: 101.696938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:14:16.711449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.521237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kqkx5\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-05-09T22:14:16.711502Z","caller":"traceutil/trace.go:171","msg":"trace[993555282] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kqkx5; range_end:; response_count:1; response_revision:15632; }","duration":"115.609719ms","start":"2026-05-09T22:14:16.595879Z","end":"2026-05-09T22:14:16.711489Z","steps":["trace[993555282] 'range keys from in-memory index tree' (duration: 114.648559ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:17:57.654858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14880} {"level":"info","ts":"2026-05-09T22:17:57.747738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14880,"took":"89.548314ms","hash":3282034912,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":30277632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T22:17:57.747827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3282034912,"revision":14880,"compact-revision":12714} {"level":"warn","ts":"2026-05-09T22:20:02.497478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.389039ms","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-09T22:20:02.497601Z","caller":"traceutil/trace.go:171","msg":"trace[1408777493] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:18196; }","duration":"113.570073ms","start":"2026-05-09T22:20:02.384008Z","end":"2026-05-09T22:20:02.497578Z","steps":["trace[1408777493] 'count revisions from in-memory index tree' (duration: 113.300938ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:08.749984Z","caller":"traceutil/trace.go:171","msg":"trace[61931707] transaction","detail":"{read_only:false; response_revision:18232; number_of_response:1; }","duration":"145.900466ms","start":"2026-05-09T22:20:08.604059Z","end":"2026-05-09T22:20:08.749959Z","steps":["trace[61931707] 'process raft request' (duration: 145.663891ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:08.750376Z","caller":"traceutil/trace.go:171","msg":"trace[1154653472] linearizableReadLoop","detail":"{readStateIndex:18862; appliedIndex:18860; }","duration":"141.985324ms","start":"2026-05-09T22:20:08.608363Z","end":"2026-05-09T22:20:08.750349Z","steps":["trace[1154653472] 'read index received' (duration: 141.448023ms)","trace[1154653472] 'applied index is now lower than readState.Index' (duration: 536.431µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:20:08.750507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.130537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:20:08.750541Z","caller":"traceutil/trace.go:171","msg":"trace[672457811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18234; }","duration":"142.179938ms","start":"2026-05-09T22:20:08.608348Z","end":"2026-05-09T22:20:08.750528Z","steps":["trace[672457811] 'agreement among raft nodes before linearized reading' (duration: 142.105446ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:08.751211Z","caller":"traceutil/trace.go:171","msg":"trace[249009586] transaction","detail":"{read_only:false; response_revision:18233; number_of_response:1; }","duration":"145.349324ms","start":"2026-05-09T22:20:08.605835Z","end":"2026-05-09T22:20:08.751184Z","steps":["trace[249009586] 'process raft request' (duration: 144.322913ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:09.010535Z","caller":"traceutil/trace.go:171","msg":"trace[1372354173] transaction","detail":"{read_only:false; response_revision:18235; number_of_response:1; }","duration":"150.548884ms","start":"2026-05-09T22:20:08.859959Z","end":"2026-05-09T22:20:09.010508Z","steps":["trace[1372354173] 'process raft request' (duration: 148.760886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:09.26967Z","caller":"traceutil/trace.go:171","msg":"trace[1549086831] transaction","detail":"{read_only:false; response_revision:18237; number_of_response:1; }","duration":"213.851033ms","start":"2026-05-09T22:20:09.055778Z","end":"2026-05-09T22:20:09.269629Z","steps":["trace[1549086831] 'process raft request' (duration: 213.619609ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:09.269719Z","caller":"traceutil/trace.go:171","msg":"trace[709237244] linearizableReadLoop","detail":"{readStateIndex:18865; appliedIndex:18864; }","duration":"245.420751ms","start":"2026-05-09T22:20:09.024266Z","end":"2026-05-09T22:20:09.269686Z","steps":["trace[709237244] 'read index received' (duration: 175.273807ms)","trace[709237244] 'applied index is now lower than readState.Index' (duration: 70.144834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:20:09.26993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.658406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:20:09.270099Z","caller":"traceutil/trace.go:171","msg":"trace[1717053473] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:18237; }","duration":"245.84294ms","start":"2026-05-09T22:20:09.024195Z","end":"2026-05-09T22:20:09.270038Z","steps":["trace[1717053473] 'agreement among raft nodes before linearized reading' (duration: 245.663346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:20:09.270239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.347179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kqkx5\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-09T22:20:09.270305Z","caller":"traceutil/trace.go:171","msg":"trace[335330573] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kqkx5; range_end:; response_count:1; response_revision:18237; }","duration":"192.46019ms","start":"2026-05-09T22:20:09.077831Z","end":"2026-05-09T22:20:09.270291Z","steps":["trace[335330573] 'agreement among raft nodes before linearized reading' (duration: 192.196215ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:09.269822Z","caller":"traceutil/trace.go:171","msg":"trace[1509926015] transaction","detail":"{read_only:false; response_revision:18236; number_of_response:1; }","duration":"254.968624ms","start":"2026-05-09T22:20:09.014812Z","end":"2026-05-09T22:20:09.269781Z","steps":["trace[1509926015] 'process raft request' (duration: 184.706878ms)","trace[1509926015] 'compare' (duration: 69.50842ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:20:17.801016Z","caller":"traceutil/trace.go:171","msg":"trace[1656618020] transaction","detail":"{read_only:false; response_revision:18404; number_of_response:1; }","duration":"120.169642ms","start":"2026-05-09T22:20:17.680823Z","end":"2026-05-09T22:20:17.800992Z","steps":["trace[1656618020] 'process raft request' (duration: 119.562288ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:17.808976Z","caller":"traceutil/trace.go:171","msg":"trace[560451087] transaction","detail":"{read_only:false; response_revision:18405; number_of_response:1; }","duration":"126.989086ms","start":"2026-05-09T22:20:17.681959Z","end":"2026-05-09T22:20:17.808948Z","steps":["trace[560451087] 'process raft request' (duration: 126.795492ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:20:17.809108Z","caller":"traceutil/trace.go:171","msg":"trace[2091055492] linearizableReadLoop","detail":"{readStateIndex:19039; appliedIndex:19037; }","duration":"124.872921ms","start":"2026-05-09T22:20:17.684223Z","end":"2026-05-09T22:20:17.809096Z","steps":["trace[2091055492] 'read index received' (duration: 116.263599ms)","trace[2091055492] 'applied index is now lower than readState.Index' (duration: 8.608482ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:20:17.809279Z","caller":"traceutil/trace.go:171","msg":"trace[1576948332] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18405; }","duration":"124.192858ms","start":"2026-05-09T22:20:17.685072Z","end":"2026-05-09T22:20:17.809265Z","steps":["trace[1576948332] 'process raft request' (duration: 123.835289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:20:17.809658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.396363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-05-09T22:20:17.816911Z","caller":"traceutil/trace.go:171","msg":"trace[1489965876] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:18405; }","duration":"125.518985ms","start":"2026-05-09T22:20:17.684209Z","end":"2026-05-09T22:20:17.809728Z","steps":["trace[1489965876] 'agreement among raft nodes before linearized reading' (duration: 125.085446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:20:17.821839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.13799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2213920"} {"level":"info","ts":"2026-05-09T22:20:17.821892Z","caller":"traceutil/trace.go:171","msg":"trace[728378321] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:18405; }","duration":"101.228171ms","start":"2026-05-09T22:20:17.720653Z","end":"2026-05-09T22:20:17.821881Z","steps":["trace[728378321] 'agreement among raft nodes before linearized reading' (duration: 88.958142ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:22:03.459908Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:22:03.469736Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T22:22:03.469889Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T22:22:57.660124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17336} {"level":"info","ts":"2026-05-09T22:22:57.756684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17336,"took":"93.137384ms","hash":3450348697,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":34267136,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T22:22:57.756746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3450348697,"revision":17336,"compact-revision":14880} {"level":"warn","ts":"2026-05-09T22:27:14.568309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.927343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:27:14.568561Z","caller":"traceutil/trace.go:171","msg":"trace[631419651] transaction","detail":"{read_only:false; response_revision:21333; number_of_response:1; }","duration":"197.038466ms","start":"2026-05-09T22:27:14.371492Z","end":"2026-05-09T22:27:14.56853Z","steps":["trace[631419651] 'process raft request' (duration: 84.747546ms)","trace[631419651] 'compare' (duration: 111.709627ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:27:14.576007Z","caller":"traceutil/trace.go:171","msg":"trace[344594580] transaction","detail":"{read_only:false; response_revision:21334; number_of_response:1; }","duration":"148.528828ms","start":"2026-05-09T22:27:14.427451Z","end":"2026-05-09T22:27:14.57598Z","steps":["trace[344594580] 'process raft request' (duration: 147.759672ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:14.576027Z","caller":"traceutil/trace.go:171","msg":"trace[376512934] transaction","detail":"{read_only:false; response_revision:21335; number_of_response:1; }","duration":"147.776162ms","start":"2026-05-09T22:27:14.428231Z","end":"2026-05-09T22:27:14.576007Z","steps":["trace[376512934] 'process raft request' (duration: 147.137069ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:14.803179Z","caller":"traceutil/trace.go:171","msg":"trace[1482327590] transaction","detail":"{read_only:false; response_revision:21336; number_of_response:1; }","duration":"221.113506ms","start":"2026-05-09T22:27:14.582036Z","end":"2026-05-09T22:27:14.803149Z","steps":["trace[1482327590] 'process raft request' (duration: 220.880541ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:14.803315Z","caller":"traceutil/trace.go:171","msg":"trace[142951692] linearizableReadLoop","detail":"{readStateIndex:22070; appliedIndex:22068; }","duration":"201.49917ms","start":"2026-05-09T22:27:14.601796Z","end":"2026-05-09T22:27:14.803295Z","steps":["trace[142951692] 'read index received' (duration: 201.179694ms)","trace[142951692] 'applied index is now lower than readState.Index' (duration: 318.566µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:27:14.803444Z","caller":"traceutil/trace.go:171","msg":"trace[767131627] transaction","detail":"{read_only:false; response_revision:21337; number_of_response:1; }","duration":"221.325001ms","start":"2026-05-09T22:27:14.58211Z","end":"2026-05-09T22:27:14.803435Z","steps":["trace[767131627] 'process raft request' (duration: 220.990194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:27:14.803776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.967971ms","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-09T22:27:14.803839Z","caller":"traceutil/trace.go:171","msg":"trace[873440722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21337; }","duration":"202.077462ms","start":"2026-05-09T22:27:14.601745Z","end":"2026-05-09T22:27:14.803823Z","steps":["trace[873440722] 'agreement among raft nodes before linearized reading' (duration: 201.818877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:27:14.97861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.21929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:27:14.979152Z","caller":"traceutil/trace.go:171","msg":"trace[1781636637] linearizableReadLoop","detail":"{readStateIndex:22071; appliedIndex:22070; }","duration":"175.748965ms","start":"2026-05-09T22:27:14.803367Z","end":"2026-05-09T22:27:14.979116Z","steps":["trace[1781636637] 'read index received' (duration: 69.949353ms)","trace[1781636637] 'applied index is now lower than readState.Index' (duration: 105.796612ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:27:14.979202Z","caller":"traceutil/trace.go:171","msg":"trace[1761491948] transaction","detail":"{read_only:false; response_revision:21338; number_of_response:1; }","duration":"293.393228ms","start":"2026-05-09T22:27:14.685784Z","end":"2026-05-09T22:27:14.979177Z","steps":["trace[1761491948] 'process raft request' (duration: 187.507634ms)","trace[1761491948] 'compare' (duration: 104.868573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:27:14.979304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.730403ms","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-09T22:27:14.979372Z","caller":"traceutil/trace.go:171","msg":"trace[1120084054] transaction","detail":"{read_only:false; response_revision:21339; number_of_response:1; }","duration":"171.330641ms","start":"2026-05-09T22:27:14.808031Z","end":"2026-05-09T22:27:14.979362Z","steps":["trace[1120084054] 'process raft request' (duration: 170.701838ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:14.979411Z","caller":"traceutil/trace.go:171","msg":"trace[497490998] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21339; }","duration":"199.895296ms","start":"2026-05-09T22:27:14.779488Z","end":"2026-05-09T22:27:14.979383Z","steps":["trace[497490998] 'agreement among raft nodes before linearized reading' (duration: 199.652961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:27:14.979587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.935991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-09T22:27:14.979685Z","caller":"traceutil/trace.go:171","msg":"trace[1868883751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21339; }","duration":"304.104744ms","start":"2026-05-09T22:27:14.675555Z","end":"2026-05-09T22:27:14.97966Z","steps":["trace[1868883751] 'agreement among raft nodes before linearized reading' (duration: 303.667785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:27:14.979663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.728799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-09T22:27:14.97977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:27:14.67554Z","time spent":"304.217627ms","remote":"127.0.0.1:47338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-09T22:27:14.979816Z","caller":"traceutil/trace.go:171","msg":"trace[1825066690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21339; }","duration":"288.928544ms","start":"2026-05-09T22:27:14.690849Z","end":"2026-05-09T22:27:14.979777Z","steps":["trace[1825066690] 'agreement among raft nodes before linearized reading' (duration: 288.321331ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:15.119994Z","caller":"traceutil/trace.go:171","msg":"trace[953314532] linearizableReadLoop","detail":"{readStateIndex:22073; appliedIndex:22072; }","duration":"134.670104ms","start":"2026-05-09T22:27:14.985286Z","end":"2026-05-09T22:27:15.119956Z","steps":["trace[953314532] 'read index received' (duration: 133.407717ms)","trace[953314532] 'applied index is now lower than readState.Index' (duration: 1.260607ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:27:15.120061Z","caller":"traceutil/trace.go:171","msg":"trace[148434105] transaction","detail":"{read_only:false; response_revision:21340; number_of_response:1; }","duration":"135.839168ms","start":"2026-05-09T22:27:14.984186Z","end":"2026-05-09T22:27:15.120025Z","steps":["trace[148434105] 'process raft request' (duration: 134.460309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:27:15.120299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.990111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18259"} {"level":"info","ts":"2026-05-09T22:27:15.120358Z","caller":"traceutil/trace.go:171","msg":"trace[2144433774] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21340; }","duration":"135.114064ms","start":"2026-05-09T22:27:14.985228Z","end":"2026-05-09T22:27:15.120342Z","steps":["trace[2144433774] 'agreement among raft nodes before linearized reading' (duration: 134.871739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:27:15.400884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.945688ms","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-09T22:27:15.400989Z","caller":"traceutil/trace.go:171","msg":"trace[262490588] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21340; }","duration":"133.100781ms","start":"2026-05-09T22:27:15.267865Z","end":"2026-05-09T22:27:15.400965Z","steps":["trace[262490588] 'range keys from in-memory index tree' (duration: 132.878266ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:57.666074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19689} {"level":"info","ts":"2026-05-09T22:27:57.819821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19689,"took":"148.962007ms","hash":3194498215,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":28315648,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T22:27:57.819891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3194498215,"revision":19689,"compact-revision":17336} {"level":"warn","ts":"2026-05-09T22:31:06.292859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.390382ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T22:31:06.293346Z","caller":"traceutil/trace.go:171","msg":"trace[2016342732] transaction","detail":"{read_only:false; response_revision:23779; number_of_response:1; }","duration":"354.754244ms","start":"2026-05-09T22:31:05.93857Z","end":"2026-05-09T22:31:06.293324Z","steps":["trace[2016342732] 'process raft request' (duration: 354.409627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:06.293586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:05.938542Z","time spent":"354.879546ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:31:06.295124Z","caller":"traceutil/trace.go:171","msg":"trace[1649195893] transaction","detail":"{read_only:false; response_revision:23780; number_of_response:1; }","duration":"352.633077ms","start":"2026-05-09T22:31:05.942475Z","end":"2026-05-09T22:31:06.295108Z","steps":["trace[1649195893] 'process raft request' (duration: 350.83667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:06.295231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:05.942463Z","time spent":"352.703239ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:31:06.295331Z","caller":"traceutil/trace.go:171","msg":"trace[825428848] linearizableReadLoop","detail":"{readStateIndex:24584; appliedIndex:24581; }","duration":"264.662836ms","start":"2026-05-09T22:31:06.030659Z","end":"2026-05-09T22:31:06.295322Z","steps":["trace[825428848] 'read index received' (duration: 89.881543ms)","trace[825428848] 'applied index is now lower than readState.Index' (duration: 174.780433ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:06.295459Z","caller":"traceutil/trace.go:171","msg":"trace[881485681] transaction","detail":"{read_only:false; response_revision:23781; number_of_response:1; }","duration":"205.161696ms","start":"2026-05-09T22:31:06.090187Z","end":"2026-05-09T22:31:06.295349Z","steps":["trace[881485681] 'process raft request' (duration: 204.873169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:06.295488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.83096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-09T22:31:06.295822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.832259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-09T22:31:06.295875Z","caller":"traceutil/trace.go:171","msg":"trace[1252171076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23782; }","duration":"265.227008ms","start":"2026-05-09T22:31:06.030596Z","end":"2026-05-09T22:31:06.295823Z","steps":["trace[1252171076] 'agreement among raft nodes before linearized reading' (duration: 264.765638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:06.296015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.850326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T22:31:06.295917Z","caller":"traceutil/trace.go:171","msg":"trace[34126404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23782; }","duration":"264.978552ms","start":"2026-05-09T22:31:06.030916Z","end":"2026-05-09T22:31:06.295894Z","steps":["trace[34126404] 'agreement among raft nodes before linearized reading' (duration: 264.721727ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:06.296098Z","caller":"traceutil/trace.go:171","msg":"trace[815366720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23782; }","duration":"100.965729ms","start":"2026-05-09T22:31:06.195123Z","end":"2026-05-09T22:31:06.296089Z","steps":["trace[815366720] 'agreement among raft nodes before linearized reading' (duration: 100.828276ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:32:57.67293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21822} {"level":"info","ts":"2026-05-09T22:32:57.78176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21822,"took":"105.000504ms","hash":2049469413,"current-db-size-bytes":58142720,"current-db-size":"58 MB","current-db-size-in-use-bytes":42418176,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-09T22:32:57.781837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2049469413,"revision":21822,"compact-revision":19689} {"level":"warn","ts":"2026-05-09T22:34:22.441757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.562822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:22.441876Z","caller":"traceutil/trace.go:171","msg":"trace[825024301] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"214.556585ms","start":"2026-05-09T22:34:22.2273Z","end":"2026-05-09T22:34:22.441856Z","steps":["trace[825024301] 'process raft request' (duration: 95.813019ms)","trace[825024301] 'compare' (duration: 118.286335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:22.732625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.443119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:22.732951Z","caller":"traceutil/trace.go:171","msg":"trace[20449886] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"501.468401ms","start":"2026-05-09T22:34:22.231443Z","end":"2026-05-09T22:34:22.732911Z","steps":["trace[20449886] 'process raft request' (duration: 364.626223ms)","trace[20449886] 'compare' (duration: 136.283016ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:22.733053Z","caller":"traceutil/trace.go:171","msg":"trace[455997516] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"397.341326ms","start":"2026-05-09T22:34:22.335686Z","end":"2026-05-09T22:34:22.733027Z","steps":["trace[455997516] 'process raft request' (duration: 397.278534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:22.733117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:22.231417Z","time spent":"501.614984ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:34:22.733151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:22.335664Z","time spent":"397.441777ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:22.733428Z","caller":"traceutil/trace.go:171","msg":"trace[1034441391] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"501.069923ms","start":"2026-05-09T22:34:22.232346Z","end":"2026-05-09T22:34:22.733416Z","steps":["trace[1034441391] 'process raft request' (duration: 500.445089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:22.734435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:22.232315Z","time spent":"501.139793ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:22.734607Z","caller":"traceutil/trace.go:171","msg":"trace[1056053927] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"445.198229ms","start":"2026-05-09T22:34:22.289393Z","end":"2026-05-09T22:34:22.734592Z","steps":["trace[1056053927] 'process raft request' (duration: 443.482993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:22.734689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:22.289367Z","time spent":"445.279571ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:27.216425Z","caller":"traceutil/trace.go:171","msg":"trace[1236727396] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"148.137797ms","start":"2026-05-09T22:34:27.068268Z","end":"2026-05-09T22:34:27.216405Z","steps":["trace[1236727396] 'process raft request' (duration: 148.006254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:27.373648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.865609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:27.37418Z","caller":"traceutil/trace.go:171","msg":"trace[1589312263] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"207.083856ms","start":"2026-05-09T22:34:27.16706Z","end":"2026-05-09T22:34:27.374143Z","steps":["trace[1589312263] 'process raft request' (duration: 60.619974ms)","trace[1589312263] 'compare' (duration: 145.548133ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:27.374343Z","caller":"traceutil/trace.go:171","msg":"trace[908804029] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"207.045036ms","start":"2026-05-09T22:34:27.167284Z","end":"2026-05-09T22:34:27.374329Z","steps":["trace[908804029] 'process raft request' (duration: 206.78093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:27.674701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.064102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:27.674828Z","caller":"traceutil/trace.go:171","msg":"trace[657263832] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"251.464686ms","start":"2026-05-09T22:34:27.423342Z","end":"2026-05-09T22:34:27.674807Z","steps":["trace[657263832] 'process raft request' (duration: 106.2096ms)","trace[657263832] 'compare' (duration: 144.93633ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:27.676458Z","caller":"traceutil/trace.go:171","msg":"trace[1321485712] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"250.78294ms","start":"2026-05-09T22:34:27.425656Z","end":"2026-05-09T22:34:27.676438Z","steps":["trace[1321485712] 'process raft request' (duration: 250.580946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:27.676597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.825615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:27.676671Z","caller":"traceutil/trace.go:171","msg":"trace[228519951] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25793; }","duration":"159.952438ms","start":"2026-05-09T22:34:27.516701Z","end":"2026-05-09T22:34:27.676654Z","steps":["trace[228519951] 'agreement among raft nodes before linearized reading' (duration: 159.821385ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:27.676318Z","caller":"traceutil/trace.go:171","msg":"trace[793029823] linearizableReadLoop","detail":"{readStateIndex:26652; appliedIndex:26649; }","duration":"159.532919ms","start":"2026-05-09T22:34:27.516756Z","end":"2026-05-09T22:34:27.676289Z","steps":["trace[793029823] 'read index received' (duration: 12.807242ms)","trace[793029823] 'applied index is now lower than readState.Index' (duration: 146.724697ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:27.67697Z","caller":"traceutil/trace.go:171","msg":"trace[833135654] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"252.61153ms","start":"2026-05-09T22:34:27.423732Z","end":"2026-05-09T22:34:27.676344Z","steps":["trace[833135654] 'process raft request' (duration: 252.366565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:27.677131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.824226ms","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-09T22:34:27.677176Z","caller":"traceutil/trace.go:171","msg":"trace[1045609574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25793; }","duration":"126.908927ms","start":"2026-05-09T22:34:27.550254Z","end":"2026-05-09T22:34:27.677163Z","steps":["trace[1045609574] 'agreement among raft nodes before linearized reading' (duration: 126.617381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:28.014475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.742562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:28.014699Z","caller":"traceutil/trace.go:171","msg":"trace[1732092752] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"280.263536ms","start":"2026-05-09T22:34:27.734414Z","end":"2026-05-09T22:34:28.014678Z","steps":["trace[1732092752] 'process raft request' (duration: 280.190165ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:28.014918Z","caller":"traceutil/trace.go:171","msg":"trace[1569140084] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"332.637625ms","start":"2026-05-09T22:34:27.682268Z","end":"2026-05-09T22:34:28.014906Z","steps":["trace[1569140084] 'process raft request' (duration: 157.367973ms)","trace[1569140084] 'compare' (duration: 174.1966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:28.014976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:27.68225Z","time spent":"332.691377ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:28.015359Z","caller":"traceutil/trace.go:171","msg":"trace[179152950] linearizableReadLoop","detail":"{readStateIndex:26653; appliedIndex:26652; }","duration":"325.207138ms","start":"2026-05-09T22:34:27.690124Z","end":"2026-05-09T22:34:28.015331Z","steps":["trace[179152950] 'read index received' (duration: 149.530227ms)","trace[179152950] 'applied index is now lower than readState.Index' (duration: 175.675551ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:28.01557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.417862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:28.015617Z","caller":"traceutil/trace.go:171","msg":"trace[549423785] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25795; }","duration":"325.502283ms","start":"2026-05-09T22:34:27.690099Z","end":"2026-05-09T22:34:28.015601Z","steps":["trace[549423785] 'agreement among raft nodes before linearized reading' (duration: 325.404531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:28.015652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:27.690088Z","time spent":"325.554605ms","remote":"127.0.0.1:51254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:34:28.015915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.747862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T22:34:28.015944Z","caller":"traceutil/trace.go:171","msg":"trace[523504983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25795; }","duration":"260.813153ms","start":"2026-05-09T22:34:27.755121Z","end":"2026-05-09T22:34:28.015934Z","steps":["trace[523504983] 'agreement among raft nodes before linearized reading' (duration: 260.694391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:28.016169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.782036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:28.016199Z","caller":"traceutil/trace.go:171","msg":"trace[1504926752] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25795; }","duration":"178.857498ms","start":"2026-05-09T22:34:27.837332Z","end":"2026-05-09T22:34:28.016189Z","steps":["trace[1504926752] 'agreement among raft nodes before linearized reading' (duration: 178.800936ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:28.707333Z","caller":"traceutil/trace.go:171","msg":"trace[285841069] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"244.031538ms","start":"2026-05-09T22:34:28.463263Z","end":"2026-05-09T22:34:28.707294Z","steps":["trace[285841069] 'process raft request' (duration: 243.772883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:28.907713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.850968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:28.908013Z","caller":"traceutil/trace.go:171","msg":"trace[1386284709] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"152.762756ms","start":"2026-05-09T22:34:28.75523Z","end":"2026-05-09T22:34:28.907992Z","steps":["trace[1386284709] 'process raft request' (duration: 152.722075ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:28.908351Z","caller":"traceutil/trace.go:171","msg":"trace[1521748977] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"274.124896ms","start":"2026-05-09T22:34:28.634214Z","end":"2026-05-09T22:34:28.908339Z","steps":["trace[1521748977] 'process raft request' (duration: 137.553614ms)","trace[1521748977] 'compare' (duration: 135.422128ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:28.908527Z","caller":"traceutil/trace.go:171","msg":"trace[186671762] linearizableReadLoop","detail":"{readStateIndex:26659; appliedIndex:26658; }","duration":"201.307344ms","start":"2026-05-09T22:34:28.70721Z","end":"2026-05-09T22:34:28.908518Z","steps":["trace[186671762] 'read index received' (duration: 64.478926ms)","trace[186671762] 'applied index is now lower than readState.Index' (duration: 136.827388ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:28.908765Z","caller":"traceutil/trace.go:171","msg":"trace[1903616960] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"154.783388ms","start":"2026-05-09T22:34:28.75397Z","end":"2026-05-09T22:34:28.908754Z","steps":["trace[1903616960] 'process raft request' (duration: 153.873639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:28.908888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.123441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:28.90892Z","caller":"traceutil/trace.go:171","msg":"trace[839651166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25803; }","duration":"194.168182ms","start":"2026-05-09T22:34:28.714744Z","end":"2026-05-09T22:34:28.908912Z","steps":["trace[839651166] 'agreement among raft nodes before linearized reading' (duration: 194.112631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:28.90892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.585446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:28.908972Z","caller":"traceutil/trace.go:171","msg":"trace[977750176] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"154.807089ms","start":"2026-05-09T22:34:28.754137Z","end":"2026-05-09T22:34:28.908944Z","steps":["trace[977750176] 'process raft request' (duration: 153.776388ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:28.909014Z","caller":"traceutil/trace.go:171","msg":"trace[118431864] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25803; }","duration":"266.738719ms","start":"2026-05-09T22:34:28.642258Z","end":"2026-05-09T22:34:28.908996Z","steps":["trace[118431864] 'agreement among raft nodes before linearized reading' (duration: 266.567536ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:29.343783Z","caller":"traceutil/trace.go:171","msg":"trace[2057979809] linearizableReadLoop","detail":"{readStateIndex:26663; appliedIndex:26662; }","duration":"116.331103ms","start":"2026-05-09T22:34:29.227434Z","end":"2026-05-09T22:34:29.343765Z","steps":["trace[2057979809] 'read index received' (duration: 116.089818ms)","trace[2057979809] 'applied index is now lower than readState.Index' (duration: 240.485µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:29.344017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.559978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-09T22:34:29.344047Z","caller":"traceutil/trace.go:171","msg":"trace[815010882] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25804; }","duration":"116.64005ms","start":"2026-05-09T22:34:29.227399Z","end":"2026-05-09T22:34:29.344039Z","steps":["trace[815010882] 'agreement among raft nodes before linearized reading' (duration: 116.481546ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:29.343865Z","caller":"traceutil/trace.go:171","msg":"trace[2061145252] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"121.396801ms","start":"2026-05-09T22:34:29.222421Z","end":"2026-05-09T22:34:29.343817Z","steps":["trace[2061145252] 'process raft request' (duration: 121.178177ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:29.610741Z","caller":"traceutil/trace.go:171","msg":"trace[2031535458] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"230.630936ms","start":"2026-05-09T22:34:29.380081Z","end":"2026-05-09T22:34:29.610712Z","steps":["trace[2031535458] 'process raft request' (duration: 230.425761ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:29.793569Z","caller":"traceutil/trace.go:171","msg":"trace[1340000062] linearizableReadLoop","detail":"{readStateIndex:26665; appliedIndex:26663; }","duration":"398.00852ms","start":"2026-05-09T22:34:29.395535Z","end":"2026-05-09T22:34:29.793544Z","steps":["trace[1340000062] 'read index received' (duration: 215.044945ms)","trace[1340000062] 'applied index is now lower than readState.Index' (duration: 182.962835ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:29.793725Z","caller":"traceutil/trace.go:171","msg":"trace[144125165] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"411.448905ms","start":"2026-05-09T22:34:29.382237Z","end":"2026-05-09T22:34:29.793686Z","steps":["trace[144125165] 'process raft request' (duration: 411.109007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.793808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.255864ms","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-09T22:34:29.793849Z","caller":"traceutil/trace.go:171","msg":"trace[482569310] 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:25806; }","duration":"398.342277ms","start":"2026-05-09T22:34:29.395492Z","end":"2026-05-09T22:34:29.793834Z","steps":["trace[482569310] 'agreement among raft nodes before linearized reading' (duration: 398.219584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.793883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:29.39546Z","time spent":"398.413088ms","remote":"127.0.0.1:43358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-09T22:34:29.793919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:29.382214Z","time spent":"411.612038ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:34:29.794154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.682661ms","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-09T22:34:29.794188Z","caller":"traceutil/trace.go:171","msg":"trace[451573130] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25806; }","duration":"345.753303ms","start":"2026-05-09T22:34:29.448423Z","end":"2026-05-09T22:34:29.794177Z","steps":["trace[451573130] 'agreement among raft nodes before linearized reading' (duration: 345.677051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.794219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:29.4484Z","time spent":"345.811314ms","remote":"127.0.0.1:47646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-09T22:34:29.794295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.078294ms","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-09T22:34:29.794334Z","caller":"traceutil/trace.go:171","msg":"trace[2059346992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25806; }","duration":"113.143006ms","start":"2026-05-09T22:34:29.681172Z","end":"2026-05-09T22:34:29.794315Z","steps":["trace[2059346992] 'agreement among raft nodes before linearized reading' (duration: 113.034073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.794489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.194731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:29.794582Z","caller":"traceutil/trace.go:171","msg":"trace[972751507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25806; }","duration":"184.351614ms","start":"2026-05-09T22:34:29.610206Z","end":"2026-05-09T22:34:29.794558Z","steps":["trace[972751507] 'agreement among raft nodes before linearized reading' (duration: 184.19086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.794608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.859764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T22:34:29.79465Z","caller":"traceutil/trace.go:171","msg":"trace[256094372] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25806; }","duration":"293.951085ms","start":"2026-05-09T22:34:29.500688Z","end":"2026-05-09T22:34:29.794639Z","steps":["trace[256094372] 'agreement among raft nodes before linearized reading' (duration: 293.725151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.794837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.497133ms","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-09T22:34:29.794875Z","caller":"traceutil/trace.go:171","msg":"trace[2114422903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25806; }","duration":"113.591165ms","start":"2026-05-09T22:34:29.681276Z","end":"2026-05-09T22:34:29.794867Z","steps":["trace[2114422903] 'agreement among raft nodes before linearized reading' (duration: 113.504083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.795197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.225211ms","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-09T22:34:29.795249Z","caller":"traceutil/trace.go:171","msg":"trace[1678232608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25806; }","duration":"115.375295ms","start":"2026-05-09T22:34:29.679861Z","end":"2026-05-09T22:34:29.795236Z","steps":["trace[1678232608] 'agreement among raft nodes before linearized reading' (duration: 115.162909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.995849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.73761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:29.995988Z","caller":"traceutil/trace.go:171","msg":"trace[173470240] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"193.293703ms","start":"2026-05-09T22:34:29.80267Z","end":"2026-05-09T22:34:29.995963Z","steps":["trace[173470240] 'process raft request' (duration: 87.314629ms)","trace[173470240] 'compare' (duration: 105.581536ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:29.998898Z","caller":"traceutil/trace.go:171","msg":"trace[1383843966] linearizableReadLoop","detail":"{readStateIndex:26668; appliedIndex:26665; }","duration":"194.408727ms","start":"2026-05-09T22:34:29.804433Z","end":"2026-05-09T22:34:29.998842Z","steps":["trace[1383843966] 'read index received' (duration: 85.527511ms)","trace[1383843966] 'applied index is now lower than readState.Index' (duration: 108.880186ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:29.999161Z","caller":"traceutil/trace.go:171","msg":"trace[57687586] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"196.223396ms","start":"2026-05-09T22:34:29.80292Z","end":"2026-05-09T22:34:29.999144Z","steps":["trace[57687586] 'process raft request' (duration: 195.747946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:29.999509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.05635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-09T22:34:29.999548Z","caller":"traceutil/trace.go:171","msg":"trace[912480303] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"196.517322ms","start":"2026-05-09T22:34:29.803018Z","end":"2026-05-09T22:34:29.999536Z","steps":["trace[912480303] 'process raft request' (duration: 195.776026ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:29.999997Z","caller":"traceutil/trace.go:171","msg":"trace[118820031] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25809; }","duration":"195.170814ms","start":"2026-05-09T22:34:29.804418Z","end":"2026-05-09T22:34:29.999589Z","steps":["trace[118820031] 'agreement among raft nodes before linearized reading' (duration: 194.812235ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:30.180579Z","caller":"traceutil/trace.go:171","msg":"trace[797838742] linearizableReadLoop","detail":"{readStateIndex:26669; appliedIndex:26668; }","duration":"181.576636ms","start":"2026-05-09T22:34:29.998973Z","end":"2026-05-09T22:34:30.180549Z","steps":["trace[797838742] 'read index received' (duration: 169.886468ms)","trace[797838742] 'applied index is now lower than readState.Index' (duration: 11.689138ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:30.180606Z","caller":"traceutil/trace.go:171","msg":"trace[2089072859] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"220.716694ms","start":"2026-05-09T22:34:29.959851Z","end":"2026-05-09T22:34:30.180568Z","steps":["trace[2089072859] 'process raft request' (duration: 209.029987ms)","trace[2089072859] 'compare' (duration: 11.524844ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:30.18077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.949472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:30.180845Z","caller":"traceutil/trace.go:171","msg":"trace[17671326] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25810; }","duration":"376.048685ms","start":"2026-05-09T22:34:29.804778Z","end":"2026-05-09T22:34:30.180826Z","steps":["trace[17671326] 'agreement among raft nodes before linearized reading' (duration: 375.910842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:30.180889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:29.804769Z","time spent":"376.111276ms","remote":"127.0.0.1:47454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-05-09T22:34:30.18142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.93063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"warn","ts":"2026-05-09T22:34:30.181441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.096338ms","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-09T22:34:30.181474Z","caller":"traceutil/trace.go:171","msg":"trace[1529826346] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25810; }","duration":"180.060683ms","start":"2026-05-09T22:34:30.001401Z","end":"2026-05-09T22:34:30.181462Z","steps":["trace[1529826346] 'agreement among raft nodes before linearized reading' (duration: 179.320627ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:30.181547Z","caller":"traceutil/trace.go:171","msg":"trace[2145108640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25810; }","duration":"158.260551ms","start":"2026-05-09T22:34:30.023267Z","end":"2026-05-09T22:34:30.181527Z","steps":["trace[2145108640] 'agreement among raft nodes before linearized reading' (duration: 158.029686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:30.182125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.362169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:30.182227Z","caller":"traceutil/trace.go:171","msg":"trace[1205352255] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25810; }","duration":"274.952543ms","start":"2026-05-09T22:34:29.907252Z","end":"2026-05-09T22:34:30.182205Z","steps":["trace[1205352255] 'agreement among raft nodes before linearized reading' (duration: 273.306508ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:30.335707Z","caller":"traceutil/trace.go:171","msg":"trace[64138659] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"150.378294ms","start":"2026-05-09T22:34:30.185304Z","end":"2026-05-09T22:34:30.335682Z","steps":["trace[64138659] 'process raft request' (duration: 135.725504ms)","trace[64138659] 'compare' (duration: 13.815243ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:30.335897Z","caller":"traceutil/trace.go:171","msg":"trace[1138573776] linearizableReadLoop","detail":"{readStateIndex:26670; appliedIndex:26669; }","duration":"148.463805ms","start":"2026-05-09T22:34:30.187422Z","end":"2026-05-09T22:34:30.335886Z","steps":["trace[1138573776] 'read index received' (duration: 136.234995ms)","trace[1138573776] 'applied index is now lower than readState.Index' (duration: 12.227409ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:30.335985Z","caller":"traceutil/trace.go:171","msg":"trace[238744298] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"147.041584ms","start":"2026-05-09T22:34:30.188931Z","end":"2026-05-09T22:34:30.335973Z","steps":["trace[238744298] 'process raft request' (duration: 146.217166ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:30.33615Z","caller":"traceutil/trace.go:171","msg":"trace[268425421] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"130.060586ms","start":"2026-05-09T22:34:30.206079Z","end":"2026-05-09T22:34:30.33614Z","steps":["trace[268425421] 'process raft request' (duration: 129.116695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:30.336452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.008776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:30.336498Z","caller":"traceutil/trace.go:171","msg":"trace[979394170] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25814; }","duration":"149.091297ms","start":"2026-05-09T22:34:30.187395Z","end":"2026-05-09T22:34:30.336487Z","steps":["trace[979394170] 'agreement among raft nodes before linearized reading' (duration: 149.000405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:30.336688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.729673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-09T22:34:30.33673Z","caller":"traceutil/trace.go:171","msg":"trace[1912546104] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25814; }","duration":"142.798075ms","start":"2026-05-09T22:34:30.193922Z","end":"2026-05-09T22:34:30.33672Z","steps":["trace[1912546104] 'agreement among raft nodes before linearized reading' (duration: 142.712483ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:30.838257Z","caller":"traceutil/trace.go:171","msg":"trace[569051720] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"118.309485ms","start":"2026-05-09T22:34:30.719921Z","end":"2026-05-09T22:34:30.838231Z","steps":["trace[569051720] 'process raft request' (duration: 117.731363ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:31.13296Z","caller":"traceutil/trace.go:171","msg":"trace[1433370181] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"217.7182ms","start":"2026-05-09T22:34:30.915205Z","end":"2026-05-09T22:34:31.132923Z","steps":["trace[1433370181] 'process raft request' (duration: 217.412773ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:31.13348Z","caller":"traceutil/trace.go:171","msg":"trace[1952171665] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"181.545634ms","start":"2026-05-09T22:34:30.951909Z","end":"2026-05-09T22:34:31.133455Z","steps":["trace[1952171665] 'process raft request' (duration: 181.493173ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:31.133502Z","caller":"traceutil/trace.go:171","msg":"trace[1736802589] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"218.140858ms","start":"2026-05-09T22:34:30.915289Z","end":"2026-05-09T22:34:31.13343Z","steps":["trace[1736802589] 'process raft request' (duration: 217.581167ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:31.133536Z","caller":"traceutil/trace.go:171","msg":"trace[1376663817] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"218.135188ms","start":"2026-05-09T22:34:30.915382Z","end":"2026-05-09T22:34:31.133517Z","steps":["trace[1376663817] 'process raft request' (duration: 217.916564ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:31.283739Z","caller":"traceutil/trace.go:171","msg":"trace[1338653790] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"179.236484ms","start":"2026-05-09T22:34:31.104456Z","end":"2026-05-09T22:34:31.283693Z","steps":["trace[1338653790] 'process raft request' (duration: 174.672967ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:31.633736Z","caller":"traceutil/trace.go:171","msg":"trace[1005525881] linearizableReadLoop","detail":"{readStateIndex:26681; appliedIndex:26680; }","duration":"198.585183ms","start":"2026-05-09T22:34:31.435113Z","end":"2026-05-09T22:34:31.633698Z","steps":["trace[1005525881] 'read index received' (duration: 179.486169ms)","trace[1005525881] 'applied index is now lower than readState.Index' (duration: 19.097594ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:31.634066Z","caller":"traceutil/trace.go:171","msg":"trace[1967207650] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"282.886549ms","start":"2026-05-09T22:34:31.35116Z","end":"2026-05-09T22:34:31.634047Z","steps":["trace[1967207650] 'process raft request' (duration: 263.446847ms)","trace[1967207650] 'compare' (duration: 18.780338ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:31.634752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.235157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:34:31.634853Z","caller":"traceutil/trace.go:171","msg":"trace[1563346758] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25821; }","duration":"199.796309ms","start":"2026-05-09T22:34:31.435038Z","end":"2026-05-09T22:34:31.634835Z","steps":["trace[1563346758] 'agreement among raft nodes before linearized reading' (duration: 199.232427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:32.211951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.775002ms","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-09T22:34:32.212056Z","caller":"traceutil/trace.go:171","msg":"trace[107894705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25823; }","duration":"211.931775ms","start":"2026-05-09T22:34:32.000102Z","end":"2026-05-09T22:34:32.212034Z","steps":["trace[107894705] 'range keys from in-memory index tree' (duration: 211.540457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:32.212377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.33706ms","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-09T22:34:32.212473Z","caller":"traceutil/trace.go:171","msg":"trace[1719248168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25823; }","duration":"209.432782ms","start":"2026-05-09T22:34:32.002976Z","end":"2026-05-09T22:34:32.212409Z","steps":["trace[1719248168] 'range keys from in-memory index tree' (duration: 209.152766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:32.212683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.555675ms","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-09T22:34:32.212721Z","caller":"traceutil/trace.go:171","msg":"trace[372308630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25823; }","duration":"209.615237ms","start":"2026-05-09T22:34:32.003097Z","end":"2026-05-09T22:34:32.212712Z","steps":["trace[372308630] 'range keys from in-memory index tree' (duration: 209.447203ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:32.365243Z","caller":"traceutil/trace.go:171","msg":"trace[155769599] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"136.145543ms","start":"2026-05-09T22:34:32.229072Z","end":"2026-05-09T22:34:32.365217Z","steps":["trace[155769599] 'process raft request' (duration: 136.095912ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:32.365604Z","caller":"traceutil/trace.go:171","msg":"trace[1077517129] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"136.52538ms","start":"2026-05-09T22:34:32.229055Z","end":"2026-05-09T22:34:32.36558Z","steps":["trace[1077517129] 'process raft request' (duration: 135.971209ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:32.597102Z","caller":"traceutil/trace.go:171","msg":"trace[262381894] linearizableReadLoop","detail":"{readStateIndex:26686; appliedIndex:26683; }","duration":"297.476657ms","start":"2026-05-09T22:34:32.299598Z","end":"2026-05-09T22:34:32.597075Z","steps":["trace[262381894] 'read index received' (duration: 65.369324ms)","trace[262381894] 'applied index is now lower than readState.Index' (duration: 232.106453ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:32.597115Z","caller":"traceutil/trace.go:171","msg":"trace[930614032] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"367.511909ms","start":"2026-05-09T22:34:32.229568Z","end":"2026-05-09T22:34:32.59708Z","steps":["trace[930614032] 'process raft request' (duration: 336.688487ms)","trace[930614032] 'compare' (duration: 30.474575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:32.597282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:32.229555Z","time spent":"367.665202ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:32.597509Z","caller":"traceutil/trace.go:171","msg":"trace[1039001317] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"255.350135ms","start":"2026-05-09T22:34:32.342123Z","end":"2026-05-09T22:34:32.597473Z","steps":["trace[1039001317] 'process raft request' (duration: 254.898655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:32.597532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.939357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:32.597558Z","caller":"traceutil/trace.go:171","msg":"trace[1075287056] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25827; }","duration":"297.966947ms","start":"2026-05-09T22:34:32.299579Z","end":"2026-05-09T22:34:32.597546Z","steps":["trace[1075287056] 'agreement among raft nodes before linearized reading' (duration: 297.898096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:32.597888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.89379ms","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-09T22:34:32.598074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.162842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:32.598115Z","caller":"traceutil/trace.go:171","msg":"trace[1961246066] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25827; }","duration":"238.242793ms","start":"2026-05-09T22:34:32.359864Z","end":"2026-05-09T22:34:32.598106Z","steps":["trace[1961246066] 'agreement among raft nodes before linearized reading' (duration: 237.777883ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:32.598202Z","caller":"traceutil/trace.go:171","msg":"trace[952129987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25827; }","duration":"258.126074ms","start":"2026-05-09T22:34:32.339959Z","end":"2026-05-09T22:34:32.598085Z","steps":["trace[952129987] 'agreement among raft nodes before linearized reading' (duration: 257.633564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:32.598258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.563527ms","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-09T22:34:32.598301Z","caller":"traceutil/trace.go:171","msg":"trace[1992851692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25827; }","duration":"253.627549ms","start":"2026-05-09T22:34:32.34466Z","end":"2026-05-09T22:34:32.598288Z","steps":["trace[1992851692] 'agreement among raft nodes before linearized reading' (duration: 253.019436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:32.881443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.919174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:32.881723Z","caller":"traceutil/trace.go:171","msg":"trace[1140009714] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"279.121848ms","start":"2026-05-09T22:34:32.602575Z","end":"2026-05-09T22:34:32.881697Z","steps":["trace[1140009714] 'process raft request' (duration: 279.018895ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:32.881724Z","caller":"traceutil/trace.go:171","msg":"trace[511240116] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"279.181099ms","start":"2026-05-09T22:34:32.602494Z","end":"2026-05-09T22:34:32.881675Z","steps":["trace[511240116] 'process raft request' (duration: 136.918428ms)","trace[511240116] 'compare' (duration: 141.769751ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:32.881716Z","caller":"traceutil/trace.go:171","msg":"trace[690224260] linearizableReadLoop","detail":"{readStateIndex:26689; appliedIndex:26687; }","duration":"272.247503ms","start":"2026-05-09T22:34:32.609447Z","end":"2026-05-09T22:34:32.881694Z","steps":["trace[690224260] 'read index received' (duration: 130.238988ms)","trace[690224260] 'applied index is now lower than readState.Index' (duration: 142.006515ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:32.881857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.405967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:32.881939Z","caller":"traceutil/trace.go:171","msg":"trace[666467447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25829; }","duration":"272.530189ms","start":"2026-05-09T22:34:32.609396Z","end":"2026-05-09T22:34:32.881926Z","steps":["trace[666467447] 'agreement among raft nodes before linearized reading' (duration: 272.383726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:33.185955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.811013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:33.186075Z","caller":"traceutil/trace.go:171","msg":"trace[1791520532] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25830; }","duration":"109.006287ms","start":"2026-05-09T22:34:33.077042Z","end":"2026-05-09T22:34:33.186048Z","steps":["trace[1791520532] 'count revisions from in-memory index tree' (duration: 108.709362ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:33.381146Z","caller":"traceutil/trace.go:171","msg":"trace[1219969486] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"242.765499ms","start":"2026-05-09T22:34:33.138352Z","end":"2026-05-09T22:34:33.381118Z","steps":["trace[1219969486] 'process raft request' (duration: 212.932967ms)","trace[1219969486] 'compare' (duration: 29.628188ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:33.381375Z","caller":"traceutil/trace.go:171","msg":"trace[890530865] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"242.897442ms","start":"2026-05-09T22:34:33.138453Z","end":"2026-05-09T22:34:33.381351Z","steps":["trace[890530865] 'process raft request' (duration: 242.615526ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:40.65412Z","caller":"traceutil/trace.go:171","msg":"trace[2037935665] linearizableReadLoop","detail":"{readStateIndex:26741; appliedIndex:26740; }","duration":"250.753729ms","start":"2026-05-09T22:34:40.403343Z","end":"2026-05-09T22:34:40.654096Z","steps":["trace[2037935665] 'read index received' (duration: 250.681527ms)","trace[2037935665] 'applied index is now lower than readState.Index' (duration: 71.422µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:40.65424Z","caller":"traceutil/trace.go:171","msg":"trace[978418514] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"251.406753ms","start":"2026-05-09T22:34:40.402789Z","end":"2026-05-09T22:34:40.654196Z","steps":["trace[978418514] 'process raft request' (duration: 251.161088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:40.654316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.958863ms","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-09T22:34:40.654344Z","caller":"traceutil/trace.go:171","msg":"trace[1291517754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25880; }","duration":"251.016134ms","start":"2026-05-09T22:34:40.403317Z","end":"2026-05-09T22:34:40.654334Z","steps":["trace[1291517754] 'agreement among raft nodes before linearized reading' (duration: 250.889482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.069209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.438289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:34:41.069443Z","caller":"traceutil/trace.go:171","msg":"trace[1121639391] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"664.312512ms","start":"2026-05-09T22:34:40.40509Z","end":"2026-05-09T22:34:41.069403Z","steps":["trace[1121639391] 'process raft request' (duration: 338.566647ms)","trace[1121639391] 'compare' (duration: 325.225135ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:41.069528Z","caller":"traceutil/trace.go:171","msg":"trace[1570414399] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"411.392169ms","start":"2026-05-09T22:34:40.658076Z","end":"2026-05-09T22:34:41.069468Z","steps":["trace[1570414399] 'process raft request' (duration: 411.336768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.069608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.658057Z","time spent":"411.511511ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:34:41.06965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.405067Z","time spent":"664.482545ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:41.069787Z","caller":"traceutil/trace.go:171","msg":"trace[1136116173] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"444.184792ms","start":"2026-05-09T22:34:40.625569Z","end":"2026-05-09T22:34:41.069753Z","steps":["trace[1136116173] 'process raft request' (duration: 443.758723ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.069794Z","caller":"traceutil/trace.go:171","msg":"trace[591217282] linearizableReadLoop","detail":"{readStateIndex:26743; appliedIndex:26741; }","duration":"415.576767ms","start":"2026-05-09T22:34:40.654184Z","end":"2026-05-09T22:34:41.06976Z","steps":["trace[591217282] 'read index received' (duration: 89.482784ms)","trace[591217282] 'applied index is now lower than readState.Index' (duration: 326.090313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:41.069938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.625547Z","time spent":"444.305455ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:34:41.069929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.383616ms","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-09T22:34:41.070017Z","caller":"traceutil/trace.go:171","msg":"trace[1538634705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25883; }","duration":"444.55445ms","start":"2026-05-09T22:34:40.625443Z","end":"2026-05-09T22:34:41.069997Z","steps":["trace[1538634705] 'agreement among raft nodes before linearized reading' (duration: 444.328475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.070009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.232747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-09T22:34:41.070073Z","caller":"traceutil/trace.go:171","msg":"trace[1473556584] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25883; }","duration":"644.332529ms","start":"2026-05-09T22:34:40.425727Z","end":"2026-05-09T22:34:41.07006Z","steps":["trace[1473556584] 'agreement among raft nodes before linearized reading' (duration: 644.117165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.070126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.425714Z","time spent":"644.40657ms","remote":"127.0.0.1:47338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T22:34:41.070068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.625418Z","time spent":"444.641921ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T22:34:41.570944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831703762982835,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T22:34:41.628878Z","caller":"traceutil/trace.go:171","msg":"trace[1161577430] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"554.397076ms","start":"2026-05-09T22:34:41.074447Z","end":"2026-05-09T22:34:41.628844Z","steps":["trace[1161577430] 'process raft request' (duration: 553.764213ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.628878Z","caller":"traceutil/trace.go:171","msg":"trace[741556844] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"715.839874ms","start":"2026-05-09T22:34:40.912994Z","end":"2026-05-09T22:34:41.628834Z","steps":["trace[741556844] 'process raft request' (duration: 700.798034ms)","trace[741556844] 'compare' (duration: 13.442975ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:41.62901Z","caller":"traceutil/trace.go:171","msg":"trace[1480978818] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"482.821571ms","start":"2026-05-09T22:34:41.146127Z","end":"2026-05-09T22:34:41.628949Z","steps":["trace[1480978818] 'process raft request' (duration: 482.228119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.629054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:41.074421Z","time spent":"554.548459ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:34:41.629081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.912964Z","time spent":"716.020567ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:34:41.629216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:41.146106Z","time spent":"482.990964ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:41.629179Z","caller":"traceutil/trace.go:171","msg":"trace[260901264] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"410.546941ms","start":"2026-05-09T22:34:41.218598Z","end":"2026-05-09T22:34:41.629145Z","steps":["trace[260901264] 'process raft request' (duration: 409.835826ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.629302Z","caller":"traceutil/trace.go:171","msg":"trace[163013592] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"222.182593ms","start":"2026-05-09T22:34:41.407107Z","end":"2026-05-09T22:34:41.629289Z","steps":["trace[163013592] 'process raft request' (duration: 221.958229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.629427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:41.218564Z","time spent":"410.720965ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:41.629413Z","caller":"traceutil/trace.go:171","msg":"trace[950994538] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"220.991808ms","start":"2026-05-09T22:34:41.408391Z","end":"2026-05-09T22:34:41.629382Z","steps":["trace[950994538] 'process raft request' (duration: 220.841935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.629594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.005205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T22:34:41.629636Z","caller":"traceutil/trace.go:171","msg":"trace[1393373252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25889; }","duration":"715.079046ms","start":"2026-05-09T22:34:40.914545Z","end":"2026-05-09T22:34:41.629624Z","steps":["trace[1393373252] 'agreement among raft nodes before linearized reading' (duration: 714.847351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.629669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.914532Z","time spent":"715.129898ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T22:34:41.629659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.064038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-09T22:34:41.629723Z","caller":"traceutil/trace.go:171","msg":"trace[777739670] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:25889; }","duration":"871.184841ms","start":"2026-05-09T22:34:40.758527Z","end":"2026-05-09T22:34:41.629712Z","steps":["trace[777739670] 'agreement among raft nodes before linearized reading' (duration: 870.990967ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.629422Z","caller":"traceutil/trace.go:171","msg":"trace[783917559] linearizableReadLoop","detail":"{readStateIndex:26750; appliedIndex:26744; }","duration":"559.519964ms","start":"2026-05-09T22:34:41.069856Z","end":"2026-05-09T22:34:41.629376Z","steps":["trace[783917559] 'read index received' (duration: 543.956734ms)","trace[783917559] 'applied index is now lower than readState.Index' (duration: 15.56159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:41.629773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.758505Z","time spent":"871.256933ms","remote":"127.0.0.1:47176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-05-09T22:34:41.630098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.663269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-09T22:34:41.630196Z","caller":"traceutil/trace.go:171","msg":"trace[1927864089] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25889; }","duration":"553.809963ms","start":"2026-05-09T22:34:41.076363Z","end":"2026-05-09T22:34:41.630173Z","steps":["trace[1927864089] 'agreement among raft nodes before linearized reading' (duration: 553.515796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.630209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.411931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-09T22:34:41.630242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:41.076337Z","time spent":"553.895064ms","remote":"127.0.0.1:47338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-09T22:34:41.630258Z","caller":"traceutil/trace.go:171","msg":"trace[1927857975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25889; }","duration":"713.501313ms","start":"2026-05-09T22:34:40.916737Z","end":"2026-05-09T22:34:41.630238Z","steps":["trace[1927857975] 'agreement among raft nodes before linearized reading' (duration: 712.642425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:41.630291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:40.916713Z","time spent":"713.570554ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T22:34:41.930955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:41.630879Z","time spent":"300.070362ms","remote":"127.0.0.1:47176","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-09T22:34:41.931386Z","caller":"traceutil/trace.go:171","msg":"trace[905141119] linearizableReadLoop","detail":"{readStateIndex:26751; appliedIndex:26751; }","duration":"299.453769ms","start":"2026-05-09T22:34:41.63192Z","end":"2026-05-09T22:34:41.931374Z","steps":["trace[905141119] 'read index received' (duration: 299.449969ms)","trace[905141119] 'applied index is now lower than readState.Index' (duration: 3.06µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:41.931516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.584151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-09T22:34:41.931536Z","caller":"traceutil/trace.go:171","msg":"trace[1942078453] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25889; }","duration":"299.647753ms","start":"2026-05-09T22:34:41.631883Z","end":"2026-05-09T22:34:41.93153Z","steps":["trace[1942078453] 'agreement among raft nodes before linearized reading' (duration: 299.530201ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.932286Z","caller":"traceutil/trace.go:171","msg":"trace[883515423] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"297.291663ms","start":"2026-05-09T22:34:41.634986Z","end":"2026-05-09T22:34:41.932277Z","steps":["trace[883515423] 'process raft request' (duration: 297.248282ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.932368Z","caller":"traceutil/trace.go:171","msg":"trace[750217325] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"297.502247ms","start":"2026-05-09T22:34:41.634797Z","end":"2026-05-09T22:34:41.932299Z","steps":["trace[750217325] 'process raft request' (duration: 297.341714ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.932539Z","caller":"traceutil/trace.go:171","msg":"trace[810571443] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"171.576122ms","start":"2026-05-09T22:34:41.760957Z","end":"2026-05-09T22:34:41.932533Z","steps":["trace[810571443] 'process raft request' (duration: 171.555522ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.932458Z","caller":"traceutil/trace.go:171","msg":"trace[754393247] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"296.436905ms","start":"2026-05-09T22:34:41.636015Z","end":"2026-05-09T22:34:41.932452Z","steps":["trace[754393247] 'process raft request' (duration: 296.243521ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:41.932654Z","caller":"traceutil/trace.go:171","msg":"trace[166668456] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"174.282529ms","start":"2026-05-09T22:34:41.758308Z","end":"2026-05-09T22:34:41.93259Z","steps":["trace[166668456] 'process raft request' (duration: 174.118826ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:42.261858Z","caller":"traceutil/trace.go:171","msg":"trace[1264486343] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"327.491192ms","start":"2026-05-09T22:34:41.934324Z","end":"2026-05-09T22:34:42.261815Z","steps":["trace[1264486343] 'process raft request' (duration: 300.059482ms)","trace[1264486343] 'compare' (duration: 26.737275ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:42.262023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.276234ms","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-09T22:34:42.262107Z","caller":"traceutil/trace.go:171","msg":"trace[1434454539] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25896; }","duration":"306.371756ms","start":"2026-05-09T22:34:41.955717Z","end":"2026-05-09T22:34:42.262088Z","steps":["trace[1434454539] 'agreement among raft nodes before linearized reading' (duration: 306.175022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:42.262149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:41.955665Z","time spent":"306.475348ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-09T22:34:42.262245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:34:41.934304Z","time spent":"327.635525ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:34:42.262253Z","caller":"traceutil/trace.go:171","msg":"trace[6195155] linearizableReadLoop","detail":"{readStateIndex:26758; appliedIndex:26757; }","duration":"306.002188ms","start":"2026-05-09T22:34:41.955735Z","end":"2026-05-09T22:34:42.261738Z","steps":["trace[6195155] 'read index received' (duration: 278.574407ms)","trace[6195155] 'applied index is now lower than readState.Index' (duration: 27.426621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:42.262465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.211131ms","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-09T22:34:42.26251Z","caller":"traceutil/trace.go:171","msg":"trace[1109478758] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25896; }","duration":"263.316194ms","start":"2026-05-09T22:34:41.999179Z","end":"2026-05-09T22:34:42.262495Z","steps":["trace[1109478758] 'agreement among raft nodes before linearized reading' (duration: 263.222932ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:44.046931Z","caller":"traceutil/trace.go:171","msg":"trace[1915009347] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"108.100339ms","start":"2026-05-09T22:34:43.938804Z","end":"2026-05-09T22:34:44.046905Z","steps":["trace[1915009347] 'process raft request' (duration: 107.920145ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:44.174485Z","caller":"traceutil/trace.go:171","msg":"trace[1694118604] linearizableReadLoop","detail":"{readStateIndex:26829; appliedIndex:26825; }","duration":"192.527154ms","start":"2026-05-09T22:34:43.98193Z","end":"2026-05-09T22:34:44.174457Z","steps":["trace[1694118604] 'read index received' (duration: 64.819792ms)","trace[1694118604] 'applied index is now lower than readState.Index' (duration: 127.706392ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:44.174604Z","caller":"traceutil/trace.go:171","msg":"trace[1981000140] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"235.591188ms","start":"2026-05-09T22:34:43.938988Z","end":"2026-05-09T22:34:44.174579Z","steps":["trace[1981000140] 'process raft request' (duration: 235.197479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:44.174833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.879663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-10a97c6a-5f6b-4b5e-a512-428fd1104f57\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-09T22:34:44.174855Z","caller":"traceutil/trace.go:171","msg":"trace[1566304263] transaction","detail":"{read_only:false; response_revision:25966; number_of_response:1; }","duration":"234.141597ms","start":"2026-05-09T22:34:43.940702Z","end":"2026-05-09T22:34:44.174844Z","steps":["trace[1566304263] 'process raft request' (duration: 233.709897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:44.17488Z","caller":"traceutil/trace.go:171","msg":"trace[428568498] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-10a97c6a-5f6b-4b5e-a512-428fd1104f57; range_end:; response_count:1; response_revision:25966; }","duration":"192.968845ms","start":"2026-05-09T22:34:43.981899Z","end":"2026-05-09T22:34:44.174867Z","steps":["trace[428568498] 'agreement among raft nodes before linearized reading' (duration: 192.673549ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:44.174858Z","caller":"traceutil/trace.go:171","msg":"trace[225062450] transaction","detail":"{read_only:false; response_revision:25965; number_of_response:1; }","duration":"234.154747ms","start":"2026-05-09T22:34:43.940653Z","end":"2026-05-09T22:34:44.174808Z","steps":["trace[225062450] 'process raft request' (duration: 233.709207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:46.350148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.950244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:46.350219Z","caller":"traceutil/trace.go:171","msg":"trace[1399215017] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25979; }","duration":"201.056957ms","start":"2026-05-09T22:34:46.149145Z","end":"2026-05-09T22:34:46.350202Z","steps":["trace[1399215017] 'count revisions from in-memory index tree' (duration: 200.888903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:46.350345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.204628ms","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-09T22:34:46.350448Z","caller":"traceutil/trace.go:171","msg":"trace[1281455122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25979; }","duration":"166.34389ms","start":"2026-05-09T22:34:46.184078Z","end":"2026-05-09T22:34:46.350422Z","steps":["trace[1281455122] 'range keys from in-memory index tree' (duration: 166.027454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:46.350428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.359261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T22:34:46.350529Z","caller":"traceutil/trace.go:171","msg":"trace[991731079] transaction","detail":"{read_only:false; response_revision:25980; number_of_response:1; }","duration":"164.632494ms","start":"2026-05-09T22:34:46.185886Z","end":"2026-05-09T22:34:46.350518Z","steps":["trace[991731079] 'process raft request' (duration: 78.563393ms)","trace[991731079] 'compare' (duration: 85.55461ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:46.350544Z","caller":"traceutil/trace.go:171","msg":"trace[745352499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25979; }","duration":"166.548545ms","start":"2026-05-09T22:34:46.183971Z","end":"2026-05-09T22:34:46.35052Z","steps":["trace[745352499] 'range keys from in-memory index tree' (duration: 166.025814ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:46.350439Z","caller":"traceutil/trace.go:171","msg":"trace[858262227] linearizableReadLoop","detail":"{readStateIndex:26843; appliedIndex:26842; }","duration":"147.511142ms","start":"2026-05-09T22:34:46.2029Z","end":"2026-05-09T22:34:46.350411Z","steps":["trace[858262227] 'read index received' (duration: 61.487982ms)","trace[858262227] 'applied index is now lower than readState.Index' (duration: 86.02225ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:34:46.350712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.789478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:34:46.350751Z","caller":"traceutil/trace.go:171","msg":"trace[639272875] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25980; }","duration":"147.88358ms","start":"2026-05-09T22:34:46.202859Z","end":"2026-05-09T22:34:46.350743Z","steps":["trace[639272875] 'agreement among raft nodes before linearized reading' (duration: 147.637305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:46.518939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.010167ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T22:34:46.519508Z","caller":"traceutil/trace.go:171","msg":"trace[1460772720] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"234.402022ms","start":"2026-05-09T22:34:46.285082Z","end":"2026-05-09T22:34:46.519484Z","steps":["trace[1460772720] 'process raft request' (duration: 233.989202ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:46.571167Z","caller":"traceutil/trace.go:171","msg":"trace[831199204] linearizableReadLoop","detail":"{readStateIndex:26845; appliedIndex:26843; }","duration":"220.441237ms","start":"2026-05-09T22:34:46.350701Z","end":"2026-05-09T22:34:46.571143Z","steps":["trace[831199204] 'read index received' (duration: 8.228974ms)","trace[831199204] 'applied index is now lower than readState.Index' (duration: 212.211323ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:46.57139Z","caller":"traceutil/trace.go:171","msg":"trace[2023521101] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"216.993553ms","start":"2026-05-09T22:34:46.35438Z","end":"2026-05-09T22:34:46.571374Z","steps":["trace[2023521101] 'process raft request' (duration: 165.093854ms)","trace[2023521101] 'compare' (duration: 51.113812ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:46.571571Z","caller":"traceutil/trace.go:171","msg":"trace[1581667308] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"216.37588ms","start":"2026-05-09T22:34:46.355167Z","end":"2026-05-09T22:34:46.571543Z","steps":["trace[1581667308] 'process raft request' (duration: 215.852129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:46.571643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.089886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:34:46.571692Z","caller":"traceutil/trace.go:171","msg":"trace[790068612] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25983; }","duration":"244.184869ms","start":"2026-05-09T22:34:46.327496Z","end":"2026-05-09T22:34:46.571681Z","steps":["trace[790068612] 'agreement among raft nodes before linearized reading' (duration: 244.088897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:46.572256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.464745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:34:46.572312Z","caller":"traceutil/trace.go:171","msg":"trace[135182853] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25984; }","duration":"116.569157ms","start":"2026-05-09T22:34:46.455729Z","end":"2026-05-09T22:34:46.572298Z","steps":["trace[135182853] 'agreement among raft nodes before linearized reading' (duration: 116.470435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:52.361754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.927065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-09T22:34:52.361838Z","caller":"traceutil/trace.go:171","msg":"trace[1178102054] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26050; }","duration":"143.021947ms","start":"2026-05-09T22:34:52.218797Z","end":"2026-05-09T22:34:52.361819Z","steps":["trace[1178102054] 'range keys from in-memory index tree' (duration: 142.752132ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:52.579208Z","caller":"traceutil/trace.go:171","msg":"trace[183128361] linearizableReadLoop","detail":"{readStateIndex:26916; appliedIndex:26915; }","duration":"198.077993ms","start":"2026-05-09T22:34:52.3811Z","end":"2026-05-09T22:34:52.579178Z","steps":["trace[183128361] 'read index received' (duration: 197.885699ms)","trace[183128361] 'applied index is now lower than readState.Index' (duration: 191.134µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:52.579329Z","caller":"traceutil/trace.go:171","msg":"trace[1615956926] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"200.729439ms","start":"2026-05-09T22:34:52.378559Z","end":"2026-05-09T22:34:52.579288Z","steps":["trace[1615956926] 'process raft request' (duration: 200.413832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:52.579462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.337858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-09T22:34:52.579499Z","caller":"traceutil/trace.go:171","msg":"trace[2021428936] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26051; }","duration":"198.391569ms","start":"2026-05-09T22:34:52.381097Z","end":"2026-05-09T22:34:52.579488Z","steps":["trace[2021428936] 'agreement among raft nodes before linearized reading' (duration: 198.227536ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:34:52.833801Z","caller":"traceutil/trace.go:171","msg":"trace[1130347304] linearizableReadLoop","detail":"{readStateIndex:26919; appliedIndex:26918; }","duration":"142.814243ms","start":"2026-05-09T22:34:52.690952Z","end":"2026-05-09T22:34:52.833766Z","steps":["trace[1130347304] 'read index received' (duration: 141.677068ms)","trace[1130347304] 'applied index is now lower than readState.Index' (duration: 1.134885ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:34:52.833951Z","caller":"traceutil/trace.go:171","msg":"trace[842225666] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"170.900868ms","start":"2026-05-09T22:34:52.66304Z","end":"2026-05-09T22:34:52.833941Z","steps":["trace[842225666] 'process raft request' (duration: 169.591709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:34:52.834256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.287743ms","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-09T22:34:52.834322Z","caller":"traceutil/trace.go:171","msg":"trace[944670261] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26054; }","duration":"143.362674ms","start":"2026-05-09T22:34:52.690942Z","end":"2026-05-09T22:34:52.834305Z","steps":["trace[944670261] 'agreement among raft nodes before linearized reading' (duration: 143.267622ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:03.37903Z","caller":"traceutil/trace.go:171","msg":"trace[797825720] linearizableReadLoop","detail":"{readStateIndex:27020; appliedIndex:27019; }","duration":"189.090433ms","start":"2026-05-09T22:35:03.189914Z","end":"2026-05-09T22:35:03.379005Z","steps":["trace[797825720] 'read index received' (duration: 188.895309ms)","trace[797825720] 'applied index is now lower than readState.Index' (duration: 194.294µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:03.379083Z","caller":"traceutil/trace.go:171","msg":"trace[581692989] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"216.958053ms","start":"2026-05-09T22:35:03.162081Z","end":"2026-05-09T22:35:03.379039Z","steps":["trace[581692989] 'process raft request' (duration: 216.751809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:03.379198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.263317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T22:35:03.379239Z","caller":"traceutil/trace.go:171","msg":"trace[1823545830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26153; }","duration":"189.373589ms","start":"2026-05-09T22:35:03.189856Z","end":"2026-05-09T22:35:03.379229Z","steps":["trace[1823545830] 'agreement among raft nodes before linearized reading' (duration: 189.234376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:03.379262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.841372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-09T22:35:03.379297Z","caller":"traceutil/trace.go:171","msg":"trace[14946934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26153; }","duration":"183.903033ms","start":"2026-05-09T22:35:03.195385Z","end":"2026-05-09T22:35:03.379288Z","steps":["trace[14946934] 'agreement among raft nodes before linearized reading' (duration: 183.76883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:03.68602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.037822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:03.686527Z","caller":"traceutil/trace.go:171","msg":"trace[1294312337] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"301.903981ms","start":"2026-05-09T22:35:03.384558Z","end":"2026-05-09T22:35:03.686462Z","steps":["trace[1294312337] 'process raft request' (duration: 35.298316ms)","trace[1294312337] 'compare' (duration: 265.926249ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:03.686573Z","caller":"traceutil/trace.go:171","msg":"trace[1580346349] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"301.199486ms","start":"2026-05-09T22:35:03.385347Z","end":"2026-05-09T22:35:03.686547Z","steps":["trace[1580346349] 'process raft request' (duration: 300.811468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:03.68669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:03.385326Z","time spent":"301.307858ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:03.686746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:03.384542Z","time spent":"302.081545ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:03.964763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.591285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:03.964902Z","caller":"traceutil/trace.go:171","msg":"trace[1418084290] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"247.134651ms","start":"2026-05-09T22:35:03.717745Z","end":"2026-05-09T22:35:03.96488Z","steps":["trace[1418084290] 'process raft request' (duration: 124.332371ms)","trace[1418084290] 'compare' (duration: 122.429462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:04.281121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.353258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:04.281413Z","caller":"traceutil/trace.go:171","msg":"trace[1252449850] linearizableReadLoop","detail":"{readStateIndex:27025; appliedIndex:27022; }","duration":"560.157568ms","start":"2026-05-09T22:35:03.721237Z","end":"2026-05-09T22:35:04.281394Z","steps":["trace[1252449850] 'read index received' (duration: 120.92586ms)","trace[1252449850] 'applied index is now lower than readState.Index' (duration: 439.230628ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:04.281558Z","caller":"traceutil/trace.go:171","msg":"trace[949039631] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"563.335145ms","start":"2026-05-09T22:35:03.718209Z","end":"2026-05-09T22:35:04.281544Z","steps":["trace[949039631] 'process raft request' (duration: 365.454906ms)","trace[949039631] 'compare' (duration: 197.094322ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:04.28163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:03.718149Z","time spent":"563.445127ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:04.281653Z","caller":"traceutil/trace.go:171","msg":"trace[1170504311] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"560.618467ms","start":"2026-05-09T22:35:03.721008Z","end":"2026-05-09T22:35:04.281627Z","steps":["trace[1170504311] 'process raft request' (duration: 560.233719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:04.281738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:03.720976Z","time spent":"560.718689ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:04.28184Z","caller":"traceutil/trace.go:171","msg":"trace[63378665] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"560.359652ms","start":"2026-05-09T22:35:03.72147Z","end":"2026-05-09T22:35:04.28183Z","steps":["trace[63378665] 'process raft request' (duration: 559.872981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:04.281997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.77125ms","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-09T22:35:04.282259Z","caller":"traceutil/trace.go:171","msg":"trace[1074116745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26159; }","duration":"561.069676ms","start":"2026-05-09T22:35:03.721166Z","end":"2026-05-09T22:35:04.282236Z","steps":["trace[1074116745] 'agreement among raft nodes before linearized reading' (duration: 560.712859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:04.28228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:03.721429Z","time spent":"560.738ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:04.282325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:03.721157Z","time spent":"561.149959ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T22:35:04.346704Z","caller":"traceutil/trace.go:171","msg":"trace[68225280] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"184.106856ms","start":"2026-05-09T22:35:04.162565Z","end":"2026-05-09T22:35:04.346672Z","steps":["trace[68225280] 'process raft request' (duration: 183.878902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:04.346775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.424372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:04.346879Z","caller":"traceutil/trace.go:171","msg":"trace[2089574231] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26160; }","duration":"620.560055ms","start":"2026-05-09T22:35:03.726295Z","end":"2026-05-09T22:35:04.346855Z","steps":["trace[2089574231] 'agreement among raft nodes before linearized reading' (duration: 620.342001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:04.346933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:03.726272Z","time spent":"620.649137ms","remote":"127.0.0.1:43364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"info","ts":"2026-05-09T22:35:04.420054Z","caller":"traceutil/trace.go:171","msg":"trace[875321840] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"132.457565ms","start":"2026-05-09T22:35:04.28756Z","end":"2026-05-09T22:35:04.420017Z","steps":["trace[875321840] 'process raft request' (duration: 130.784829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:04.708018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.157138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:35:04.708177Z","caller":"traceutil/trace.go:171","msg":"trace[1157179845] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26161; }","duration":"142.362702ms","start":"2026-05-09T22:35:04.565769Z","end":"2026-05-09T22:35:04.708131Z","steps":["trace[1157179845] 'count revisions from in-memory index tree' (duration: 141.997575ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:04.893867Z","caller":"traceutil/trace.go:171","msg":"trace[1871019963] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"262.65572ms","start":"2026-05-09T22:35:04.631182Z","end":"2026-05-09T22:35:04.893838Z","steps":["trace[1871019963] 'process raft request' (duration: 262.347874ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:05.408974Z","caller":"traceutil/trace.go:171","msg":"trace[1834067831] linearizableReadLoop","detail":"{readStateIndex:27030; appliedIndex:27028; }","duration":"698.887134ms","start":"2026-05-09T22:35:04.710062Z","end":"2026-05-09T22:35:05.408949Z","steps":["trace[1834067831] 'read index received' (duration: 183.502905ms)","trace[1834067831] 'applied index is now lower than readState.Index' (duration: 515.382929ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:05.40923Z","caller":"traceutil/trace.go:171","msg":"trace[1309101807] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"776.469417ms","start":"2026-05-09T22:35:04.63275Z","end":"2026-05-09T22:35:05.409219Z","steps":["trace[1309101807] 'process raft request' (duration: 776.038107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:05.409354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:04.632721Z","time spent":"776.541649ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:05.409623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.520548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:05.409778Z","caller":"traceutil/trace.go:171","msg":"trace[1580562643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26163; }","duration":"699.738713ms","start":"2026-05-09T22:35:04.710008Z","end":"2026-05-09T22:35:05.409747Z","steps":["trace[1580562643] 'agreement among raft nodes before linearized reading' (duration: 699.429687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:05.409829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:04.709985Z","time spent":"699.835205ms","remote":"127.0.0.1:47168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T22:35:05.499413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.488204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T22:35:05.499488Z","caller":"traceutil/trace.go:171","msg":"trace[1997689298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26164; }","duration":"782.581656ms","start":"2026-05-09T22:35:04.716891Z","end":"2026-05-09T22:35:05.499472Z","steps":["trace[1997689298] 'agreement among raft nodes before linearized reading' (duration: 782.360961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:05.49953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:04.716881Z","time spent":"782.641547ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T22:35:05.499605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.981015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-09T22:35:05.499423Z","caller":"traceutil/trace.go:171","msg":"trace[855222054] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"113.722058ms","start":"2026-05-09T22:35:05.385652Z","end":"2026-05-09T22:35:05.499374Z","steps":["trace[855222054] 'process raft request' (duration: 111.46082ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:05.499684Z","caller":"traceutil/trace.go:171","msg":"trace[1731479675] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26164; }","duration":"175.077107ms","start":"2026-05-09T22:35:05.324571Z","end":"2026-05-09T22:35:05.499648Z","steps":["trace[1731479675] 'agreement among raft nodes before linearized reading' (duration: 174.884673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:05.499759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.596863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-09T22:35:05.4994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.20859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:05.499828Z","caller":"traceutil/trace.go:171","msg":"trace[136965382] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26164; }","duration":"531.689455ms","start":"2026-05-09T22:35:04.968117Z","end":"2026-05-09T22:35:05.499807Z","steps":["trace[136965382] 'agreement among raft nodes before linearized reading' (duration: 531.560322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:05.499617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.304068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-09T22:35:05.499884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:04.968093Z","time spent":"531.781106ms","remote":"127.0.0.1:47260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-05-09T22:35:05.499998Z","caller":"traceutil/trace.go:171","msg":"trace[39164691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26164; }","duration":"609.707067ms","start":"2026-05-09T22:35:04.890274Z","end":"2026-05-09T22:35:05.499981Z","steps":["trace[39164691] 'agreement among raft nodes before linearized reading' (duration: 609.120064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:05.500038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.62624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274760"} {"level":"warn","ts":"2026-05-09T22:35:05.50007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:04.890193Z","time spent":"609.84305ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T22:35:05.500085Z","caller":"traceutil/trace.go:171","msg":"trace[1673743226] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26164; }","duration":"699.727642ms","start":"2026-05-09T22:35:04.800346Z","end":"2026-05-09T22:35:05.500074Z","steps":["trace[1673743226] 'agreement among raft nodes before linearized reading' (duration: 699.279932ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:05.499863Z","caller":"traceutil/trace.go:171","msg":"trace[1912511365] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26164; }","duration":"450.738661ms","start":"2026-05-09T22:35:05.0491Z","end":"2026-05-09T22:35:05.499839Z","steps":["trace[1912511365] 'agreement among raft nodes before linearized reading' (duration: 450.21674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:05.500122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:04.800319Z","time spent":"699.792493ms","remote":"127.0.0.1:47646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":274783,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-05-09T22:35:05.500158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:05.049064Z","time spent":"451.078568ms","remote":"127.0.0.1:43588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"info","ts":"2026-05-09T22:35:07.637255Z","caller":"traceutil/trace.go:171","msg":"trace[1466443548] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"117.017127ms","start":"2026-05-09T22:35:07.520217Z","end":"2026-05-09T22:35:07.637235Z","steps":["trace[1466443548] 'process raft request' (duration: 116.762112ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:07.637314Z","caller":"traceutil/trace.go:171","msg":"trace[410086747] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"118.136011ms","start":"2026-05-09T22:35:07.519028Z","end":"2026-05-09T22:35:07.637164Z","steps":["trace[410086747] 'process raft request' (duration: 49.843215ms)","trace[410086747] 'compare' (duration: 67.740584ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:21.68875Z","caller":"traceutil/trace.go:171","msg":"trace[853281966] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"136.830126ms","start":"2026-05-09T22:35:21.551883Z","end":"2026-05-09T22:35:21.688713Z","steps":["trace[853281966] 'process raft request' (duration: 136.649523ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:21.831796Z","caller":"traceutil/trace.go:171","msg":"trace[1908563458] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"181.855189ms","start":"2026-05-09T22:35:21.649903Z","end":"2026-05-09T22:35:21.831758Z","steps":["trace[1908563458] 'process raft request' (duration: 177.586658ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:21.831811Z","caller":"traceutil/trace.go:171","msg":"trace[1957100171] linearizableReadLoop","detail":"{readStateIndex:27182; appliedIndex:27181; }","duration":"142.225381ms","start":"2026-05-09T22:35:21.68955Z","end":"2026-05-09T22:35:21.831775Z","steps":["trace[1957100171] 'read index received' (duration: 138.021331ms)","trace[1957100171] 'applied index is now lower than readState.Index' (duration: 4.20244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:21.832095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.259434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:21.832133Z","caller":"traceutil/trace.go:171","msg":"trace[491328952] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:26312; }","duration":"136.371907ms","start":"2026-05-09T22:35:21.695751Z","end":"2026-05-09T22:35:21.832123Z","steps":["trace[491328952] 'agreement among raft nodes before linearized reading' (duration: 136.271725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:21.832212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.641889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:21.832326Z","caller":"traceutil/trace.go:171","msg":"trace[461685442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26312; }","duration":"142.787972ms","start":"2026-05-09T22:35:21.689503Z","end":"2026-05-09T22:35:21.832291Z","steps":["trace[461685442] 'agreement among raft nodes before linearized reading' (duration: 142.448475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:21.832361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.546061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T22:35:21.83239Z","caller":"traceutil/trace.go:171","msg":"trace[639098490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26312; }","duration":"128.596672ms","start":"2026-05-09T22:35:21.703786Z","end":"2026-05-09T22:35:21.832383Z","steps":["trace[639098490] 'agreement among raft nodes before linearized reading' (duration: 128.48366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:21.832436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.113123ms","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-09T22:35:21.832497Z","caller":"traceutil/trace.go:171","msg":"trace[956353952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26312; }","duration":"128.206294ms","start":"2026-05-09T22:35:21.704275Z","end":"2026-05-09T22:35:21.832481Z","steps":["trace[956353952] 'agreement among raft nodes before linearized reading' (duration: 127.99242ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:21.963611Z","caller":"traceutil/trace.go:171","msg":"trace[791068705] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"126.724583ms","start":"2026-05-09T22:35:21.836853Z","end":"2026-05-09T22:35:21.963577Z","steps":["trace[791068705] 'process raft request' (duration: 125.330373ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:21.963894Z","caller":"traceutil/trace.go:171","msg":"trace[1880531821] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"126.844774ms","start":"2026-05-09T22:35:21.837017Z","end":"2026-05-09T22:35:21.963862Z","steps":["trace[1880531821] 'process raft request' (duration: 126.493247ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:21.964461Z","caller":"traceutil/trace.go:171","msg":"trace[1518256092] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"127.267604ms","start":"2026-05-09T22:35:21.837174Z","end":"2026-05-09T22:35:21.964441Z","steps":["trace[1518256092] 'process raft request' (duration: 126.582139ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:21.966283Z","caller":"traceutil/trace.go:171","msg":"trace[1772923698] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"110.392497ms","start":"2026-05-09T22:35:21.855881Z","end":"2026-05-09T22:35:21.966273Z","steps":["trace[1772923698] 'process raft request' (duration: 109.644201ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:24.071622Z","caller":"traceutil/trace.go:171","msg":"trace[1127472358] linearizableReadLoop","detail":"{readStateIndex:27199; appliedIndex:27198; }","duration":"103.262995ms","start":"2026-05-09T22:35:23.968332Z","end":"2026-05-09T22:35:24.071595Z","steps":["trace[1127472358] 'read index received' (duration: 28.136375ms)","trace[1127472358] 'applied index is now lower than readState.Index' (duration: 75.12562ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:24.071735Z","caller":"traceutil/trace.go:171","msg":"trace[425408617] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"140.914354ms","start":"2026-05-09T22:35:23.930751Z","end":"2026-05-09T22:35:24.071665Z","steps":["trace[425408617] 'process raft request' (duration: 65.826014ms)","trace[425408617] 'compare' (duration: 74.768172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:24.07188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.03405ms","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-09T22:35:24.071921Z","caller":"traceutil/trace.go:171","msg":"trace[1399557507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26328; }","duration":"102.133642ms","start":"2026-05-09T22:35:23.969777Z","end":"2026-05-09T22:35:24.07191Z","steps":["trace[1399557507] 'agreement among raft nodes before linearized reading' (duration: 102.000569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:24.07188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.532732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-09T22:35:24.071968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.51873ms","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-09T22:35:24.072053Z","caller":"traceutil/trace.go:171","msg":"trace[482158135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26328; }","duration":"101.644233ms","start":"2026-05-09T22:35:23.970386Z","end":"2026-05-09T22:35:24.07203Z","steps":["trace[482158135] 'agreement among raft nodes before linearized reading' (duration: 101.431518ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:24.072047Z","caller":"traceutil/trace.go:171","msg":"trace[1401112014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26328; }","duration":"103.721425ms","start":"2026-05-09T22:35:23.968302Z","end":"2026-05-09T22:35:24.072023Z","steps":["trace[1401112014] 'agreement among raft nodes before linearized reading' (duration: 103.427939ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:24.222347Z","caller":"traceutil/trace.go:171","msg":"trace[1798873200] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"145.380767ms","start":"2026-05-09T22:35:24.076941Z","end":"2026-05-09T22:35:24.222322Z","steps":["trace[1798873200] 'process raft request' (duration: 137.839168ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:24.222538Z","caller":"traceutil/trace.go:171","msg":"trace[881837379] linearizableReadLoop","detail":"{readStateIndex:27202; appliedIndex:27199; }","duration":"129.199195ms","start":"2026-05-09T22:35:24.093308Z","end":"2026-05-09T22:35:24.222507Z","steps":["trace[881837379] 'read index received' (duration: 74.565029ms)","trace[881837379] 'applied index is now lower than readState.Index' (duration: 54.632276ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:24.222591Z","caller":"traceutil/trace.go:171","msg":"trace[1901180383] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"145.543481ms","start":"2026-05-09T22:35:24.077016Z","end":"2026-05-09T22:35:24.22256Z","steps":["trace[1901180383] 'process raft request' (duration: 145.243685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:24.222727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.396829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:35:24.222769Z","caller":"traceutil/trace.go:171","msg":"trace[469019093] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26331; }","duration":"129.485181ms","start":"2026-05-09T22:35:24.093274Z","end":"2026-05-09T22:35:24.222759Z","steps":["trace[469019093] 'agreement among raft nodes before linearized reading' (duration: 129.361749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:31.124608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.818496ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-09T22:35:31.124699Z","caller":"traceutil/trace.go:171","msg":"trace[382644915] linearizableReadLoop","detail":"{readStateIndex:27257; appliedIndex:27256; }","duration":"148.900762ms","start":"2026-05-09T22:35:30.975785Z","end":"2026-05-09T22:35:31.124686Z","steps":["trace[382644915] 'read index received' (duration: 65.551µs)","trace[382644915] 'applied index is now lower than readState.Index' (duration: 148.834161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:31.12477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.988583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:35:31.124786Z","caller":"traceutil/trace.go:171","msg":"trace[1085916582] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:26384; }","duration":"149.013645ms","start":"2026-05-09T22:35:30.975766Z","end":"2026-05-09T22:35:31.12478Z","steps":["trace[1085916582] 'agreement among raft nodes before linearized reading' (duration: 148.949973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:31.124922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:30.808771Z","time spent":"316.147852ms","remote":"127.0.0.1:47176","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-09T22:35:31.204087Z","caller":"traceutil/trace.go:171","msg":"trace[1408741324] transaction","detail":"{read_only:false; response_revision:26385; number_of_response:1; }","duration":"160.923507ms","start":"2026-05-09T22:35:31.04313Z","end":"2026-05-09T22:35:31.204054Z","steps":["trace[1408741324] 'process raft request' (duration: 160.733312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:31.508485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.005148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:31.508629Z","caller":"traceutil/trace.go:171","msg":"trace[1353486007] transaction","detail":"{read_only:false; response_revision:26386; number_of_response:1; }","duration":"382.653859ms","start":"2026-05-09T22:35:31.12595Z","end":"2026-05-09T22:35:31.508604Z","steps":["trace[1353486007] 'process raft request' (duration: 102.424727ms)","trace[1353486007] 'compare' (duration: 279.687281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:31.508691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:31.125935Z","time spent":"382.726261ms","remote":"127.0.0.1:47176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:31.775277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.665444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T22:35:31.775267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.090716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:31.775349Z","caller":"traceutil/trace.go:171","msg":"trace[1255848057] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:26386; }","duration":"162.765905ms","start":"2026-05-09T22:35:31.612566Z","end":"2026-05-09T22:35:31.775332Z","steps":["trace[1255848057] 'count revisions from in-memory index tree' (duration: 162.577212ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:31.775362Z","caller":"traceutil/trace.go:171","msg":"trace[1101339117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26386; }","duration":"166.254079ms","start":"2026-05-09T22:35:31.609093Z","end":"2026-05-09T22:35:31.775347Z","steps":["trace[1101339117] 'range keys from in-memory index tree' (duration: 165.999663ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:31.939535Z","caller":"traceutil/trace.go:171","msg":"trace[1920719306] transaction","detail":"{read_only:false; response_revision:26387; number_of_response:1; }","duration":"174.527214ms","start":"2026-05-09T22:35:31.764982Z","end":"2026-05-09T22:35:31.939509Z","steps":["trace[1920719306] 'process raft request' (duration: 174.35714ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:31.994899Z","caller":"traceutil/trace.go:171","msg":"trace[1078230212] transaction","detail":"{read_only:false; response_revision:26388; number_of_response:1; }","duration":"143.63945ms","start":"2026-05-09T22:35:31.851225Z","end":"2026-05-09T22:35:31.994864Z","steps":["trace[1078230212] 'process raft request' (duration: 142.419364ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:54.596962Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b8b.wal"} {"level":"info","ts":"2026-05-09T22:37:57.681658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24941} {"level":"info","ts":"2026-05-09T22:37:57.828644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24941,"took":"142.232251ms","hash":2272711961,"current-db-size-bytes":59830272,"current-db-size":"60 MB","current-db-size-in-use-bytes":36724736,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-09T22:37:57.828739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2272711961,"revision":24941,"compact-revision":21822} {"level":"info","ts":"2026-05-09T22:38:47.442157Z","caller":"traceutil/trace.go:171","msg":"trace[601996952] transaction","detail":"{read_only:false; response_revision:28352; number_of_response:1; }","duration":"101.139321ms","start":"2026-05-09T22:38:47.340988Z","end":"2026-05-09T22:38:47.442127Z","steps":["trace[601996952] 'process raft request' (duration: 96.437032ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:38:47.442806Z","caller":"traceutil/trace.go:171","msg":"trace[519979086] transaction","detail":"{read_only:false; response_revision:28353; number_of_response:1; }","duration":"101.253714ms","start":"2026-05-09T22:38:47.341538Z","end":"2026-05-09T22:38:47.442792Z","steps":["trace[519979086] 'process raft request' (duration: 100.523348ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:40:04.941253Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:40:04.947469Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T22:40:04.9476Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T22:42:57.689151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27783} {"level":"info","ts":"2026-05-09T22:42:57.826786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27783,"took":"131.591605ms","hash":598020253,"current-db-size-bytes":59830272,"current-db-size":"60 MB","current-db-size-in-use-bytes":32485376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T22:42:57.82688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":598020253,"revision":27783,"compact-revision":24941} {"level":"info","ts":"2026-05-09T22:47:57.695665Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30345} {"level":"info","ts":"2026-05-09T22:47:57.830756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30345,"took":"130.863321ms","hash":1523907249,"current-db-size-bytes":59830272,"current-db-size":"60 MB","current-db-size-in-use-bytes":30257152,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T22:47:57.83084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1523907249,"revision":30345,"compact-revision":27783} {"level":"info","ts":"2026-05-09T22:52:57.701095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32604} {"level":"info","ts":"2026-05-09T22:52:57.848246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32604,"took":"143.410199ms","hash":3187514676,"current-db-size-bytes":60870656,"current-db-size":"61 MB","current-db-size-in-use-bytes":30040064,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T22:52:57.848338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3187514676,"revision":32604,"compact-revision":30345} {"level":"info","ts":"2026-05-09T22:53:26.814062Z","caller":"traceutil/trace.go:171","msg":"trace[2050174366] transaction","detail":"{read_only:false; response_revision:35109; number_of_response:1; }","duration":"125.50341ms","start":"2026-05-09T22:53:26.688486Z","end":"2026-05-09T22:53:26.813989Z","steps":["trace[2050174366] 'process raft request' (duration: 79.169452ms)","trace[2050174366] 'compare' (duration: 45.681895ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:53:26.814094Z","caller":"traceutil/trace.go:171","msg":"trace[1993181356] transaction","detail":"{read_only:false; response_revision:35110; number_of_response:1; }","duration":"124.253204ms","start":"2026-05-09T22:53:26.689793Z","end":"2026-05-09T22:53:26.814046Z","steps":["trace[1993181356] 'process raft request' (duration: 124.08598ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:57:57.709943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34864} {"level":"info","ts":"2026-05-09T22:57:57.84287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34864,"took":"125.747586ms","hash":680505148,"current-db-size-bytes":60870656,"current-db-size":"61 MB","current-db-size-in-use-bytes":31813632,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T22:57:57.843069Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":680505148,"revision":34864,"compact-revision":32604} {"level":"info","ts":"2026-05-09T23:01:24.441549Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T23:01:24.446762Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-09T23:01:24.446975Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-09T23:02:57.716573Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37129} {"level":"info","ts":"2026-05-09T23:02:57.843512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37129,"took":"122.444036ms","hash":262203739,"current-db-size-bytes":62992384,"current-db-size":"63 MB","current-db-size-in-use-bytes":32522240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T23:02:57.843587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":262203739,"revision":37129,"compact-revision":34864}