[DEVNET] Add Deploy Script + Fix Restart Issue #514

patrick-ogrady · 2023-10-04T15:35:36Z

Replaces: #433

TODO

patrick-ogrady · 2023-10-06T18:58:34Z

Getting error:

[10-06|18:57:48.807] WARN <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> vm/vm.go:744 BuildBlock failed {"error": "unexpected state root: unable to load parent view"}
[10-06|18:57:48.812] WARN <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> gossiper/proposer.go:154 no transactions to gossip
[10-06|18:57:48.833] INFO <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:826 block not processed {"height": 1, "blkID": "29dERvK9j295TqDfcUYveiGKj6ui5uzwh1VoHA6RSsZJgNBwin", "attemptVerify": false}
[10-06|18:57:48.833] INFO <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:878 verifying block when view requested {"height": 1, "blkID": "29dERvK9j295TqDfcUYveiGKj6ui5uzwh1VoHA6RSsZJgNBwin", "accepted": true}
[10-06|18:57:48.833] ERROR <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: unable to load parent view"}
[10-06|18:57:48.833] WARN <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: unable to load parent view"}
[10-06|18:57:48.833] WARN <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> vm/vm.go:744 BuildBlock failed {"error": "unexpected state root: unable to load parent view"}
[10-06|18:57:48.858] INFO <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:826 block not processed {"height": 1, "blkID": "29dERvK9j295TqDfcUYveiGKj6ui5uzwh1VoHA6RSsZJgNBwin", "attemptVerify": false}
[10-06|18:57:48.858] INFO <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:878 verifying block when view requested {"height": 1, "blkID": "29dERvK9j295TqDfcUYveiGKj6ui5uzwh1VoHA6RSsZJgNBwin", "accepted": true}
[10-06|18:57:48.858] ERROR <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: unable to load parent view"}
[10-06|18:57:48.858] WARN <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: unable to load parent view"}
[10-06|18:57:48.858] WARN <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> vm/vm.go:744 BuildBlock failed {"error": "unexpected state root: unable to load parent view"}
[10-06|18:57:48.862] WARN <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> gossiper/proposer.go:154 no transactions to gossip
[10-06|18:57:48.884] INFO <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:826 block not processed {"height": 1, "blkID": "29dERvK9j295TqDfcUYveiGKj6ui5uzwh1VoHA6RSsZJgNBwin", "attemptVerify": false}
[10-06|18:57:48.884] INFO <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:878 verifying block when view requested {"height": 1, "blkID": "29dERvK9j295TqDfcUYveiGKj6ui5uzwh1VoHA6RSsZJgNBwin", "accepted": true}
[10-06|18:57:48.884] ERROR <QAqr9nGRCENWC5Y38JNwXEco7GppZLqVeD11Bk1MCqqcJUXWv Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: unable to load parent view"}

patrick-ogrady · 2023-10-06T22:24:31Z

{"level":"info","timestamp":"2023-10-06T22:19:34.073Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"subprocess/runtime.go:143","msg":"plugin handshake succeeded","addr":"127.0.0.1:43797"}
{"level":"info","timestamp":"2023-10-06T22:19:34.077Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"rpcchainvm/vm_client.go:169","msg":"grpc: serving database","version":"v1.4.5","address":"127.0.0.1:45803"}
{"level":"info","timestamp":"2023-10-06T22:19:34.077Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"rpcchainvm/vm_client.go:195","msg":"grpc: serving vm services","address":"127.0.0.1:34291"}
[10-06|22:19:34.078] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/warp_manager.go:70 starting warp manager
[10-06|22:19:34.078] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> controller/controller.go:91 initialized config {"loaded": false, "contents": {"gossipMaxSize":2044723,"gossipProposerDiff":4,"gossipProposerDepth":1,"noGossipBuilderDiff":4,"verifyTimeout":30000,"traceEnabled":false,"traceSampleRate":0,"continuousProfilerDir":"","streamingBacklogSize":1024,"mempoolSize":2048,"mempoolPayerSize":32,"mempoolExemptPayers":null,"maxOrdersPerPair":1024,"trackedPairs":null,"verifySignatures":true,"storeTransactions":true,"testMode":false,"logLevel":"INFO","parallelism":12,"stateSyncServerDelay":0}}
[10-06|22:19:34.078] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> controller/controller.go:100 loaded genesis {"genesis": {"hrp":"token","minBlockGap":250,"minEmptyBlockGap":2500,"minUnitPrice":[100,100,100,100,100],"unitPriceChangeDenominator":[48,48,48,48,48],"windowTargetUnits":[1800000,18446744073709551615,18446744073709551615,18446744073709551615,18446744073709551615],"maxBlockUnits":[18446744073709551615,18446744073709551615,18446744073709551615,18446744073709551615,18446744073709551615],"validityWindow":60000,"baseUnits":1,"baseWarpUnits":1024,"warpUnitsPerSigner":128,"outgoingWarpComputeUnits":1024,"coldStorageKeyReadUnits":5,"coldStorageValueReadUnits":2,"warmStorageKeyReadUnits":1,"warmStorageValueReadUnits":1,"storageKeyCreateUnits":20,"storageKeyValueUnits":5,"coldStorageKeyModificationUnits":10,"coldStorageValueModificationUnits":3,"warmStorageKeyModificationUnits":5,"warmStorageValueModificationUnits":3,"customAllocation":[{"address":"token1rvzhmceq997zntgvravfagsks6w0ryud3rylh4cdvayry0dl97nsjzf3yp","balance":1000000000000000}]}}
[10-06|22:19:34.130] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:311 genesis state created {"root": "A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:34.130] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:339 set genesis unit price {"dimension": 0, "price": 100}
[10-06|22:19:34.130] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:339 set genesis unit price {"dimension": 1, "price": 100}
[10-06|22:19:34.130] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:339 set genesis unit price {"dimension": 2, "price": 100}
[10-06|22:19:34.130] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:339 set genesis unit price {"dimension": 3, "price": 100}
[10-06|22:19:34.130] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:339 set genesis unit price {"dimension": 4, "price": 100}
[10-06|22:19:34.134] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:363 initialized vm from genesis {"block": "2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5", "pre-execution root": "A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs", "post-execution root": "peSb41ossvpDGGJqgrF12SprP849Da4evydhav9zi8pkY9158"}
{"level":"info","timestamp":"2023-10-06T22:19:34.135Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"proposervm/vm.go:415","msg":"block height index was successfully verified"}
{"level":"info","timestamp":"2023-10-06T22:19:34.135Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"proposervm/vm.go:273","msg":"initialized proposervm","state":"before fork"}
{"level":"info","timestamp":"2023-10-06T22:19:34.136Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"snowman/transitive.go:96","msg":"initializing consensus engine"}
{"level":"info","timestamp":"2023-10-06T22:19:34.137Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"syncer/state_syncer.go:407","msg":"starting state sync"}
[10-06|22:19:34.137] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:485 state sync started
{"level":"warn","timestamp":"2023-10-06T22:19:34.137Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"syncer/state_syncer.go:552","msg":"received an unexpected message from the VM","msg":"Pending Transactions"}
{"level":"info","timestamp":"2023-10-06T22:19:34.138Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"syncer/state_syncer.go:433","msg":"starting state sync"}
[10-06|22:19:34.138] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:19 Serving syncable block at latest height {"summary": "0:2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5 root=A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:34.138] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:49 parsed state summary {"summary": "0:2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5 root=A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:34.144] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:19 Serving syncable block at latest height {"summary": "0:2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5 root=A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:34.146] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:19 Serving syncable block at latest height {"summary": "0:2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5 root=A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:34.146] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:36 Serving syncable block at requested height {"height": 0, "summary": "0:2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5 root=A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:34.147] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:36 Serving syncable block at requested height {"height": 0, "summary": "0:2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5 root=A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:36.139] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:36 Serving syncable block at requested height {"height": 0, "summary": "0:2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5 root=A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:36.140] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:49 parsed state summary {"summary": "0:2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5 root=A5MtH4TL8131tK4tEs4ESqAYap1ridoqK8gkF3vU9uPMVcpcs"}
[10-06|22:19:36.140] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_client.go:74 accepted syncable block {"height": 0, "blockID": "2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5"}
[10-06|22:19:36.140] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_client.go:86 bypassing state sync {"lastAccepted": 0, "syncableHeight": 0}
[10-06|22:19:36.140] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:437 state sync client ready
{"level":"info","timestamp":"2023-10-06T22:19:36.140Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"syncer/state_syncer.go:334","msg":"accepted state summary","summaryID":"2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5","syncMode":"Dynamic","numTotalSummaries":1}
{"level":"info","timestamp":"2023-10-06T22:19:36.140Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"bootstrap/bootstrapper.go:115","msg":"starting bootstrapper"}
[10-06|22:19:36.141] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:1033 no seen transactions to backfill
[10-06|22:19:36.141] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:521 bootstrapping started {"state sync started": true}
[10-06|22:19:36.141] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:446 validity window ready
[10-06|22:19:36.141] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:453 node is now ready {"synced": true}
[10-06|22:19:36.141] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> gossiper/proposer.go:154 no transactions to gossip
{"level":"warn","timestamp":"2023-10-06T22:19:36.141Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"bootstrap/bootstrapper.go:303","msg":"received an unexpected message from the VM","msg":"Pending Transactions"}
{"level":"info","timestamp":"2023-10-06T22:19:36.143Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"common/bootstrapper.go:249","msg":"bootstrapping started syncing","numVerticesInFrontier":1}
{"level":"info","timestamp":"2023-10-06T22:19:36.143Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"bootstrap/bootstrapper.go:554","msg":"executing blocks","numPendingJobs":0}
{"level":"info","timestamp":"2023-10-06T22:19:36.143Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"queue/jobs.go:224","msg":"executed operations","numExecuted":0}
{"level":"info","timestamp":"2023-10-06T22:19:36.143Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"snowman/transitive.go:409","msg":"consensus starting","lastAcceptedBlock":"2fDLtnZZNZ7qCDbuEUfxRu3KkpFkqcoe4skZdNupmJv7nXQTs5"}
[10-06|22:19:36.143] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:525 normal operation started {"state sync started": true}
[10-06|22:19:36.167] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:513 built block {"context": false, "hght": 1, "attempted": 0, "added": 0, "state changes": 3, "state operations": 3, "parent (t)": 1672531200000, "block (t)": 1696630776167}
[10-06|22:19:36.167] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:505 merkle root generated {"height": 1, "blkID": "fLVxtE8SpWWyzqzgoX1b16ocErvveuNrVXq9eCUkwTzYEeWHn", "root": "tbmT7RVYcvFeNrLmReEVvDN17X2g5wWcRbNAUMdLeU5SDzv1z"}
{"level":"info","timestamp":"2023-10-06T22:19:36.168Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"proposervm/pre_fork_block.go:242","msg":"built block","blkID":"2J1DyPUpaK4fx2eH2rXCfZ4NxBCRNbZgUL6keV5ecAj8ZUHNJT","innerBlkID":"fLVxtE8SpWWyzqzgoX1b16ocErvveuNrVXq9eCUkwTzYEeWHn","height":1,"parentTimestamp":"2023-01-01T00:00:00.000Z","blockTimestamp":"2023-10-06T22:19:36.000Z"}
[10-06|22:19:36.168] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:368 skipping verification, already processed {"height": 1, "blkID": "fLVxtE8SpWWyzqzgoX1b16ocErvveuNrVXq9eCUkwTzYEeWHn"}
[10-06|22:19:36.168] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:114 verified block {"blkID": "fLVxtE8SpWWyzqzgoX1b16ocErvveuNrVXq9eCUkwTzYEeWHn", "height": 1, "txs": 0, "parent root": "peSb41ossvpDGGJqgrF12SprP849Da4evydhav9zi8pkY9158", "state ready": true, "unit prices": [100,100,100,100,100], "units consumed": [0,0,0,0,0]}
[10-06|22:19:36.170] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:709 parsed block {"id": "2TNQDbszbrf88noQDGk4C27Ggq7zAYW2XXot5gVacdc6VyWDZF", "height": 1}
[10-06|22:19:36.171] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:114 verified block {"blkID": "2TNQDbszbrf88noQDGk4C27Ggq7zAYW2XXot5gVacdc6VyWDZF", "height": 1, "txs": 0, "parent root": "peSb41ossvpDGGJqgrF12SprP849Da4evydhav9zi8pkY9158", "state ready": true, "unit prices": [100,100,100,100,100], "units consumed": [0,0,0,0,0]}
[10-06|22:19:36.171] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:679 merkle root generated {"height": 1, "blkID": "2TNQDbszbrf88noQDGk4C27Ggq7zAYW2XXot5gVacdc6VyWDZF", "root": "SjoWHwJXmaCQFbBBTN3sHrHB8NoLp1on1wiX4bFHMF5Eiu4VG"}
[10-06|22:19:36.171] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:709 parsed block {"id": "xfoTH5Zw8o9oCtgSycgHJVQ6Bj8dbZgepHxMFbJ1HPPTfheXs", "height": 1}
[10-06|22:19:36.171] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:114 verified block {"blkID": "xfoTH5Zw8o9oCtgSycgHJVQ6Bj8dbZgepHxMFbJ1HPPTfheXs", "height": 1, "txs": 0, "parent root": "peSb41ossvpDGGJqgrF12SprP849Da4evydhav9zi8pkY9158", "state ready": true, "unit prices": [100,100,100,100,100], "units consumed": [0,0,0,0,0]}
[10-06|22:19:36.171] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:679 merkle root generated {"height": 1, "blkID": "xfoTH5Zw8o9oCtgSycgHJVQ6Bj8dbZgepHxMFbJ1HPPTfheXs", "root": "isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}
[10-06|22:19:36.187] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:309 accepted block {"blkID": "xfoTH5Zw8o9oCtgSycgHJVQ6Bj8dbZgepHxMFbJ1HPPTfheXs", "height": 1, "txs": 0, "parent root": "peSb41ossvpDGGJqgrF12SprP849Da4evydhav9zi8pkY9158", "size": 92, "dropped mempool txs": 0, "state ready": true}
[10-06|22:19:36.188] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:241 block processed {"blkID": "xfoTH5Zw8o9oCtgSycgHJVQ6Bj8dbZgepHxMFbJ1HPPTfheXs", "height": 1}

[10-06|22:19:36.188] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:153 rejected block {"id": "2TNQDbszbrf88noQDGk4C27Ggq7zAYW2XXot5gVacdc6VyWDZF"}
[10-06|22:19:36.188] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:153 rejected block {"id": "fLVxtE8SpWWyzqzgoX1b16ocErvveuNrVXq9eCUkwTzYEeWHn"}
[10-06|22:19:36.193] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/proposer_monitor.go:82 refreshed proposer monitor {"previous": 0, "new": 10, "t": "650.546µs"}
[10-06|22:19:36.691] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:709 parsed block {"id": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "height": 2}
[10-06|22:19:36.692] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:114 verified block {"blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "height": 2, "txs": 0, "parent root": "isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR", "state ready": true, "unit prices": [100,100,100,100,100], "units consumed": [0,0,0,0,0]}
[10-06|22:19:36.692] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:679 merkle root generated {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "root": "2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB"}
[10-06|22:19:36.705] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:309 accepted block {"blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "height": 2, "txs": 0, "parent root": "isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR", "size": 92, "dropped mempool txs": 0, "state ready": true}
[10-06|22:19:36.705] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:241 block processed {"blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "height": 2}
runtime engine: ignoring signal: terminated
{"level":"info","timestamp":"2023-10-06T22:19:37.926Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"snowman/transitive.go:339","msg":"shutting down consensus engine"}
[10-06|22:19:37.927] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/resolutions.go:232 acceptor queue shutdown
[10-06|22:19:37.927] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> gossiper/proposer.go:329 stopping gossip loop
[10-06|22:19:37.927] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/warp_manager.go:101 stopping warp manager
runtime engine: received shutdown signal: terminated
vm server: graceful termination success
{"level":"info","timestamp":"2023-10-06T22:19:37.937Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"router/chain_router.go:383","msg":"chain shutdown","shutdownDuration":10952394}
{"level":"info","timestamp":"2023-10-06T22:19:37.937Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"subprocess/runtime.go:124","msg":"stderr collector shutdown"}
{"level":"info","timestamp":"2023-10-06T22:19:37.937Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"subprocess/runtime.go:111","msg":"stdout collector shutdown"}
{"level":"info","timestamp":"2023-10-06T22:19:43.440Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"subprocess/runtime.go:143","msg":"plugin handshake succeeded","addr":"127.0.0.1:45627"}
{"level":"info","timestamp":"2023-10-06T22:19:43.442Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"rpcchainvm/vm_client.go:169","msg":"grpc: serving database","version":"v1.4.5","address":"127.0.0.1:44059"}
{"level":"info","timestamp":"2023-10-06T22:19:43.443Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"rpcchainvm/vm_client.go:195","msg":"grpc: serving vm services","address":"127.0.0.1:34639"}
[10-06|22:19:43.444] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/warp_manager.go:70 starting warp manager
[10-06|22:19:43.444] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> controller/controller.go:91 initialized config {"loaded": true, "contents": {"gossipMaxSize":2044723,"gossipProposerDiff":4,"gossipProposerDepth":1,"noGossipBuilderDiff":4,"verifyTimeout":30000,"traceEnabled":false,"traceSampleRate":0,"continuousProfilerDir":"","streamingBacklogSize":10000000,"mempoolSize":10000000,"mempoolPayerSize":10000000,"mempoolExemptPayers":["token1rvzhmceq997zntgvravfagsks6w0ryud3rylh4cdvayry0dl97nsjzf3yp"],"maxOrdersPerPair":1024,"trackedPairs":["*"],"verifySignatures":true,"storeTransactions":false,"testMode":false,"logLevel":"INFO","parallelism":12,"stateSyncServerDelay":0}}
[10-06|22:19:43.444] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> controller/controller.go:100 loaded genesis {"genesis": {"hrp":"token","minBlockGap":250,"minEmptyBlockGap":2500,"minUnitPrice":[100,100,100,100,100],"unitPriceChangeDenominator":[48,48,48,48,48],"windowTargetUnits":[1800000,18446744073709551615,18446744073709551615,18446744073709551615,18446744073709551615],"maxBlockUnits":[18446744073709551615,18446744073709551615,18446744073709551615,18446744073709551615,18446744073709551615],"validityWindow":60000,"baseUnits":1,"baseWarpUnits":1024,"warpUnitsPerSigner":128,"outgoingWarpComputeUnits":1024,"coldStorageKeyReadUnits":5,"coldStorageValueReadUnits":2,"warmStorageKeyReadUnits":1,"warmStorageValueReadUnits":1,"storageKeyCreateUnits":20,"storageKeyValueUnits":5,"coldStorageKeyModificationUnits":10,"coldStorageValueModificationUnits":3,"warmStorageKeyModificationUnits":5,"warmStorageValueModificationUnits":3,"customAllocation":[{"address":"token1rvzhmceq997zntgvravfagsks6w0ryud3rylh4cdvayry0dl97nsjzf3yp","balance":1000000000000000}]}}
[10-06|22:19:43.502] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> orderbook/orderbook.go:51 tracking all order books
[10-06|22:19:43.504] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:1110 loaded blocks from disk {"start": 0, "finish": 2}
[10-06|22:19:43.505] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:295 initialized vm from last accepted {"block": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX"}
{"level":"info","timestamp":"2023-10-06T22:19:43.506Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"proposervm/vm.go:415","msg":"block height index was successfully verified"}
{"level":"info","timestamp":"2023-10-06T22:19:43.506Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"proposervm/vm.go:267","msg":"initialized proposervm","state":"after fork","forkHeight":1,"lastAcceptedHeight":2}
{"level":"info","timestamp":"2023-10-06T22:19:43.507Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"snowman/transitive.go:96","msg":"initializing consensus engine"}
{"level":"info","timestamp":"2023-10-06T22:19:43.508Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"syncer/state_syncer.go:407","msg":"starting state sync"}
[10-06|22:19:43.508] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:485 state sync started
{"level":"warn","timestamp":"2023-10-06T22:19:43.508Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"syncer/state_syncer.go:552","msg":"received an unexpected message from the VM","msg":"Pending Transactions"}
{"level":"info","timestamp":"2023-10-06T22:19:43.509Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"syncer/state_syncer.go:433","msg":"starting state sync"}
[10-06|22:19:43.509] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:19 Serving syncable block at latest height {"summary": "2:45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX root=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}
[10-06|22:19:43.510] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:49 parsed state summary {"summary": "2:45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX root=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}
[10-06|22:19:43.523] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:19 Serving syncable block at latest height {"summary": "2:45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX root=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}
[10-06|22:19:43.550] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:19 Serving syncable block at latest height {"summary": "2:45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX root=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}
[10-06|22:19:43.551] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:36 Serving syncable block at requested height {"height": 2, "summary": "2:45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX root=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}
[10-06|22:19:45.511] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:36 Serving syncable block at requested height {"height": 2, "summary": "2:45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX root=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}
{"level":"info","timestamp":"2023-10-06T22:19:45.513Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"syncer/state_syncer.go:334","msg":"accepted state summary","summaryID":"2n7vp3BmS5LsAquPhFJp4qhnq5rhvyDArtLa38R18crtU5aqkM","syncMode":"Skipped","numTotalSummaries":1}
{"level":"info","timestamp":"2023-10-06T22:19:45.513Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"bootstrap/bootstrapper.go:115","msg":"starting bootstrapper"}
[10-06|22:19:45.513] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:1084 backfilled seen txs {"start": 1, "finish": 2}
[10-06|22:19:45.513] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:521 bootstrapping started {"state sync started": false}
[10-06|22:19:45.513] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:437 state sync client ready
[10-06|22:19:45.513] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:446 validity window ready
[10-06|22:19:45.513] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:453 node is now ready {"synced": false}
[10-06|22:19:45.513] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> gossiper/proposer.go:154 no transactions to gossip
{"level":"warn","timestamp":"2023-10-06T22:19:45.513Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"bootstrap/bootstrapper.go:303","msg":"received an unexpected message from the VM","msg":"Pending Transactions"}
{"level":"info","timestamp":"2023-10-06T22:19:45.515Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"common/bootstrapper.go:249","msg":"bootstrapping started syncing","numVerticesInFrontier":1}
{"level":"info","timestamp":"2023-10-06T22:19:45.515Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"bootstrap/bootstrapper.go:554","msg":"executing blocks","numPendingJobs":0}
{"level":"info","timestamp":"2023-10-06T22:19:45.515Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"queue/jobs.go:224","msg":"executed operations","numExecuted":0}
{"level":"info","timestamp":"2023-10-06T22:19:45.516Z","logger":"26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain","caller":"snowman/transitive.go:409","msg":"consensus starting","lastAcceptedBlock":"24zAVUJmJ1o2J1aGBuZneD9ezEV8Y3UXbTyHHQ6PYKqEhHVrtF"}
[10-06|22:19:45.516] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:525 normal operation started {"state sync started": false}
[10-06|22:19:45.525] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:36 Serving syncable block at requested height {"height": 2, "summary": "2:45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX root=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}

[10-06|22:19:45.525] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/syncervm_server.go:36 Serving syncable block at requested height {"height": 2, "summary": "2:45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX root=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR"}
[10-06|22:19:45.539] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:826 block not processed {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "attemptVerify": false}
[10-06|22:19:45.539] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:878 verifying block when view requested {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "accepted": true}
[10-06|22:19:45.539] ^[[0;33mERROR^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.539] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.539] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:749 BuildBlock failed {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.563] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> gossiper/proposer.go:154 no transactions to gossip
[10-06|22:19:45.565] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:826 block not processed {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "attemptVerify": false}
[10-06|22:19:45.565] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:878 verifying block when view requested {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "accepted": true}
[10-06|22:19:45.565] ^[[0;33mERROR^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.565] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.565] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:749 BuildBlock failed {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.589] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:826 block not processed {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "attemptVerify": false}
[10-06|22:19:45.589] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:878 verifying block when view requested {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "accepted": true}
[10-06|22:19:45.589] ^[[0;33mERROR^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.589] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.589] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:749 BuildBlock failed {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.613] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> gossiper/proposer.go:154 no transactions to gossip
[10-06|22:19:45.615] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:826 block not processed {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "attemptVerify": false}
[10-06|22:19:45.615] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:878 verifying block when view requested {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "accepted": true}
[10-06|22:19:45.615] ^[[0;33mERROR^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.615] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.615] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:749 BuildBlock failed {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.639] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:826 block not processed {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "attemptVerify": false}
[10-06|22:19:45.639] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:878 verifying block when view requested {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "accepted": true}
[10-06|22:19:45.639] ^[[0;33mERROR^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.639] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.639] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:749 BuildBlock failed {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.663] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> gossiper/proposer.go:154 no transactions to gossip
[10-06|22:19:45.665] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:826 block not processed {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "attemptVerify": false}
[10-06|22:19:45.665] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:878 verifying block when view requested {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "accepted": true}
[10-06|22:19:45.665] ^[[0;33mERROR^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.665] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.665] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:749 BuildBlock failed {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.689] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:826 block not processed {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "attemptVerify": false}
[10-06|22:19:45.689] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:878 verifying block when view requested {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "accepted": true}
[10-06|22:19:45.689] ^[[0;33mERROR^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.689] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/builder.go:90 block building failed: couldn't get parent db {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.689] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> vm/vm.go:749 BuildBlock failed {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}
[10-06|22:19:45.713] ^[[1;33mWARN^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> gossiper/proposer.go:154 no transactions to gossip
[10-06|22:19:45.715] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:826 block not processed {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "attemptVerify": false}
[10-06|22:19:45.715] ^[[0;0mINFO^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:878 verifying block when view requested {"height": 2, "blkID": "45QXaV2oMiGqHrp9n833XE47e8JBNAd21wpjF9uZEKxgCaPTX", "accepted": true}
[10-06|22:19:45.715] ^[[0;33mERROR^[[0;0m <26LD69J7AoDajcqMr8kRZakwLqoMUWAXBr39bkDSA49n85Ys48 Chain> chain/block.go:889 unable to verify block {"error": "unexpected state root: state=2dBuFHfFqJA5ib8F2pDkgPqVLu1WwoXMD5ttgGpG17t6JkF9gB wanted=isVPoVtc3KcNs4if5jgpzQJL7Sa1cudvvK7MsnDz9zQyKsNRR: unable to load parent view"}

chain/block.go

patrick-ogrady · 2023-10-06T23:42:51Z

vm/verify_context.go

@@ -24,6 +24,16 @@ func (vm *VM) GetVerifyContext(ctx context.Context, blockHeight uint64, parent i
 		return nil, errors.New("cannot get context of genesis block")
 	}

+	// If the parent block is not yet accepted, we should return the block's processing parent (it may


Not having this first may have been a bug (likely not)

patrick-ogrady · 2023-10-06T23:43:01Z

vm/vm.go

@@ -328,7 +330,7 @@ func (vm *VM) Initialize(
 		if err := sps.Insert(ctx, chain.HeightKey(vm.StateManager().HeightKey()), binary.BigEndian.AppendUint64(nil, 0)); err != nil {
 			return err
 		}
-		if err := sps.Insert(ctx, chain.HeightKey(vm.StateManager().TimestampKey()), binary.BigEndian.AppendUint64(nil, 0)); err != nil {
+		if err := sps.Insert(ctx, chain.TimestampKey(vm.StateManager().TimestampKey()), binary.BigEndian.AppendUint64(nil, 0)); err != nil {


This was a bug

patrick-ogrady added 22 commits September 5, 2023 17:16

progress on DEVNET config

ab7a1c1

Merge branch 'main' into update-devnets

38981b9

working on script

84eff58

working on scripts

11f5c6c

add TODOs

54c4894

experiment with yaml overwrites

4c64143

use cache for downloads

a836750

update yaml overwrite

9db9ecf

add apply

3afee2c

cache profile name

93a770a

add note about SSO session name

1126ee9

fix metric issue

4049d82

use deploy folder

9a3d01d

use right date order

c9770e1

add custom spec name

e9d3890

update spec name

439cd2a

change date format

7eca68b

use single folder

e763997

fix json

6bb00d9

working on dev machine setup

19ba59b

load chainID from ops file

7bbcbd4

generate prometheus dashboard on remote node

2f32db1

patrick-ogrady added 3 commits October 6, 2023 12:01

remove unused var

2bb32a8

add more logging

a374f2d

don't archive build artifacts

8759555

patrick-ogrady added 2 commits October 6, 2023 15:47

found bug

64f7127

handle last accepted read after restart

a7463c4

patrick-ogrady commented Oct 6, 2023

View reviewed changes

chain/block.go Show resolved Hide resolved

patrick-ogrady added run unit labels Oct 6, 2023

patrick-ogrady added this to the v0.1.0 (Devnet Mark I - TokenVM) milestone Oct 6, 2023

patrick-ogrady commented Oct 6, 2023

View reviewed changes

chain/block.go Outdated Show resolved Hide resolved

simplify View function

e8af649

patrick-ogrady changed the title ~~[DEVNET] Update README + Add Command~~ [DEVNET] Add Deploy Script + Fix Restart Issue Oct 6, 2023

cleanup comments

10e3253

patrick-ogrady commented Oct 6, 2023

View reviewed changes

patrick-ogrady added 9 commits October 6, 2023 16:51

add test for unprocessed block

9347041

static analysis

f03c4ae

don't set upload as latest

c7cc310

script working

a697f0e

use pre-built token-cli file

911f22d

exit if insufficent funds

b744db3

fix fee params

36dde1f

add multi-region as default

f3a7790

add more comments

056c80a

patrick-ogrady removed run unit labels Oct 9, 2023

patrick-ogrady added 5 commits October 9, 2023 09:18

enable profiles on devnet

307ca2b

working on READMEs

09f9e3a

update README

de5f483

nits

525c884

clarify OS type

5c6c28c

patrick-ogrady added the run sync label Oct 11, 2023

Merge branch 'main' into update-devnets

818a1c0

patrick-ogrady merged commit 0138d7d into main Oct 11, 2023
18 checks passed

patrick-ogrady deleted the update-devnets branch October 11, 2023 19:49

patrick-ogrady linked an issue Oct 12, 2023 that may be closed by this pull request

[avalanche-ops] Automate entire walkthrough of creating a cluster, spinning up prometheus, running spam script (i.e. examples/tokenvm/DEVNETS.md) #205

Closed

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

[DEVNET] Add Deploy Script + Fix Restart Issue #514

[DEVNET] Add Deploy Script + Fix Restart Issue #514

patrick-ogrady commented Oct 4, 2023 •

edited

patrick-ogrady commented Oct 6, 2023

patrick-ogrady commented Oct 6, 2023 •

edited

patrick-ogrady Oct 6, 2023 •

edited

patrick-ogrady Oct 6, 2023

[DEVNET] Add Deploy Script + Fix Restart Issue #514

[DEVNET] Add Deploy Script + Fix Restart Issue #514

Conversation

patrick-ogrady commented Oct 4, 2023 • edited

TODO

patrick-ogrady commented Oct 6, 2023

patrick-ogrady commented Oct 6, 2023 • edited

patrick-ogrady Oct 6, 2023 • edited

Choose a reason for hiding this comment

patrick-ogrady Oct 6, 2023

Choose a reason for hiding this comment

patrick-ogrady commented Oct 4, 2023 •

edited

patrick-ogrady commented Oct 6, 2023 •

edited

patrick-ogrady Oct 6, 2023 •

edited