Skip to content
This repository was archived by the owner on Jan 17, 2023. It is now read-only.

Commit 4fc1f73

Browse files
authored
perf: Amortize clearing unsorted cache entries (Juno genesis fix) (#12885)
This change fixes a bounty by the Juno team. Juno's invariant checks took 10 hours during their most recent chain halt. This PR cuts that down to 30 seconds. See https://github.com/CosmosContracts/bounties#improve-speed-of-invariant-checks. The root problem is deep in the `can-withdraw` invariant check, which calls this repeatedly: https://github.com/cosmos/cosmos-sdk/blob/main/x/distribution/keeper/store.go#L337. Iterators have a chain of parents and in this case creates an iterator from the `cachekv` store. For the genesis file, it has a cache of 500,000+ unsorted entries, which are sorted as strings here: https://github.com/cosmos/cosmos-sdk/blob/main/store/cachekv/store.go#L314. Each delegation from `can-withdraw` uses this cache and many of the cache checks miss or are a very small range. This means very few entries get removed from the unsorted cache and they have to be re-sorted on the next call. With a full cache it takes about 180ms on my machine to sort them. This change introduce a minimum number of entries that will get processed and removed from the unsorted list. It's set at the same value that directs the code to sort them in the first place. This ensures the unsorted values get removed in a relative short amount of time, and amortizes the cost to ensure an individual check does not have to process the entire cache. ## Benchmarks On running the benchmarks included in this change produces: ```shell name old time/op new time/op delta LargeUnsortedMisses-32 21.2s ± 9% 0.0s ± 1% -99.91% (p=0.000 n=20+17) name old alloc/op new alloc/op delta LargeUnsortedMisses-32 1.64GB ± 0% 0.00GB ± 0% -99.83% (p=0.000 n=19+19) name old allocs/op new allocs/op delta LargeUnsortedMisses-32 20.0k ± 0% 41.1k ± 0% +105.23% (p=0.000 n=19+20) ``` ## Invariant checks results This is what the invariant checks for Juno look like with this change (on a Hetzner AX101): ```shell INF starting node with ABCI Tendermint in-process 4:11PM INF Starting multiAppConn service impl=multiAppConn module=proxy 4:11PM INF Starting localClient service connection=query impl=localClient module=abci-client 4:11PM INF Starting localClient service connection=snapshot impl=localClient module=abci-client 4:11PM INF Starting localClient service connection=mempool impl=localClient module=abci-client 4:11PM INF Starting localClient service connection=consensus impl=localClient module=abci-client 4:11PM INF Starting EventBus service impl=EventBus module=events 4:11PM INF Starting PubSub service impl=PubSub module=pubsub 4:11PM INF Starting IndexerService service impl=IndexerService module=txindex 4:11PM INF ABCI Handshake App Info hash= height=0 module=consensus protocol-version=0 software-version=v9.0.0-36-g8fd6f16 4:11PM INF ABCI Replay Blocks appHeight=0 module=consensus stateHeight=0 storeHeight=0 4:12PM INF asserting crisis invariants inv=1/11 module=x/crisis name=gov/module-account 4:12PM INF asserting crisis invariants inv=2/11 module=x/crisis name=distribution/nonnegative-outstanding 4:12PM INF asserting crisis invariants inv=3/11 module=x/crisis name=distribution/can-withdraw 4:12PM INF asserting crisis invariants inv=4/11 module=x/crisis name=distribution/reference-count 4:12PM INF asserting crisis invariants inv=5/11 module=x/crisis name=distribution/module-account 4:12PM INF asserting crisis invariants inv=6/11 module=x/crisis name=bank/nonnegative-outstanding 4:12PM INF asserting crisis invariants inv=7/11 module=x/crisis name=bank/total-supply 4:12PM INF asserting crisis invariants inv=8/11 module=x/crisis name=staking/module-accounts 4:12PM INF asserting crisis invariants inv=9/11 module=x/crisis name=staking/nonnegative-power 4:12PM INF asserting crisis invariants inv=10/11 module=x/crisis name=staking/positive-delegation 4:12PM INF asserting crisis invariants inv=11/11 module=x/crisis name=staking/delegator-shares 4:12PM INF asserted all invariants duration=28383.559601 height=4136532 module=x/crisis ``` ## Alternatives There is another PR which fixes this problem for the Juno genesis file cosmos/cosmos-sdk#12886. However, because of its concurrent nature, it happens to hit a large range relatively early, clearing the unsorted entries and allowing the rest of the checks to not sort it.
1 parent 8e9e330 commit 4fc1f73

File tree

3 files changed

+59
-1
lines changed

3 files changed

+59
-1
lines changed

CHANGELOG.md

Lines changed: 1 addition & 0 deletions
Original file line numberDiff line numberDiff line change
@@ -62,6 +62,7 @@ Ref: https://keepachangelog.com/en/1.0.0/
6262
* [#12187](https://github.com/cosmos/cosmos-sdk/pull/12187) Add batch operation for x/nft module.
6363
* [#12693](https://github.com/cosmos/cosmos-sdk/pull/12693) Make sure the order of each node is consistent when emitting proto events.
6464
* [#12455](https://github.com/cosmos/cosmos-sdk/pull/12455) Show attempts count in error for signing.
65+
* [#12886](https://github.com/cosmos/cosmos-sdk/pull/12886) Amortize cost of processing cache KV store
6566

6667
### State Machine Breaking
6768

Lines changed: 43 additions & 0 deletions
Original file line numberDiff line numberDiff line change
@@ -0,0 +1,43 @@
1+
package cachekv
2+
3+
import (
4+
db "github.com/tendermint/tm-db"
5+
"strconv"
6+
"testing"
7+
)
8+
9+
func BenchmarkLargeUnsortedMisses(b *testing.B) {
10+
for i := 0; i < b.N; i++ {
11+
b.StopTimer()
12+
store := generateStore()
13+
b.StartTimer()
14+
15+
for k := 0; k < 10000; k++ {
16+
// cache has A + Z values
17+
// these are within range, but match nothing
18+
store.dirtyItems([]byte("B1"), []byte("B2"))
19+
}
20+
}
21+
}
22+
23+
func generateStore() *Store {
24+
cache := map[string]*cValue{}
25+
unsorted := map[string]struct{}{}
26+
for i := 0; i < 5000; i++ {
27+
key := "A" + strconv.Itoa(i)
28+
unsorted[key] = struct{}{}
29+
cache[key] = &cValue{}
30+
}
31+
32+
for i := 0; i < 5000; i++ {
33+
key := "Z" + strconv.Itoa(i)
34+
unsorted[key] = struct{}{}
35+
cache[key] = &cValue{}
36+
}
37+
38+
return &Store{
39+
cache: cache,
40+
unsortedCache: unsorted,
41+
sortedCache: db.NewMemDB(),
42+
}
43+
}

store/cachekv/store.go

Lines changed: 15 additions & 1 deletion
Original file line numberDiff line numberDiff line change
@@ -13,6 +13,7 @@ import (
1313
"github.com/cosmos/cosmos-sdk/store/tracekv"
1414
"github.com/cosmos/cosmos-sdk/store/types"
1515
"github.com/cosmos/cosmos-sdk/types/kv"
16+
"github.com/tendermint/tendermint/libs/math"
1617
)
1718

1819
// cValue represents a cached value.
@@ -278,6 +279,8 @@ const (
278279
stateAlreadySorted
279280
)
280281

282+
const minSortSize = 1024
283+
281284
// Constructs a slice of dirty items, to use w/ memIterator.
282285
func (store *Store) dirtyItems(start, end []byte) {
283286
startStr, endStr := conv.UnsafeBytesToStr(start), conv.UnsafeBytesToStr(end)
@@ -294,7 +297,7 @@ func (store *Store) dirtyItems(start, end []byte) {
294297
// O(N^2) overhead.
295298
// Even without that, too many range checks eventually becomes more expensive
296299
// than just not having the cache.
297-
if n < 1024 {
300+
if n < minSortSize {
298301
for key := range store.unsortedCache {
299302
// dbm.IsKeyInDomain is nil safe and returns true iff key is greater than start
300303
if dbm.IsKeyInDomain(conv.UnsafeStrToBytes(key), start, end) {
@@ -331,6 +334,17 @@ func (store *Store) dirtyItems(start, end []byte) {
331334
endIndex = len(strL) - 1
332335
}
333336

337+
// Since we spent cycles to sort the values, we should process and remove a reasonable amount
338+
// ensure start to end is at least minSortSize in size
339+
// if below minSortSize, expand it to cover additional values
340+
// this amortizes the cost of processing elements across multiple calls
341+
if endIndex-startIndex < minSortSize {
342+
endIndex = math.MinInt(startIndex+minSortSize, len(strL)-1)
343+
if endIndex-startIndex < minSortSize {
344+
startIndex = math.MaxInt(endIndex-minSortSize, 0)
345+
}
346+
}
347+
334348
kvL := make([]*kv.Pair, 0)
335349
for i := startIndex; i <= endIndex; i++ {
336350
key := strL[i]

0 commit comments

Comments
 (0)