search

filecoin-project / lotus

Reference implementation of the Filecoin protocol, written in Go
https://lotus.filecoin.io/
Other
2.83k stars 1.25k forks source link

testnet/3: TestAPIDealFlow fails for two deals #1455

Closed jsign closed 4 years ago

jsign commented 4 years ago

Switching to testnet/3 seems that started happening something that works on master.

To reproduce the problem:

git clone https://github.com/jsign/lotus.git
cd lotus
git checkout jsign/testnet3/DoubleTestAPIDealFlowFAIL 
...get ffi ...
go test ./node -run TestAPIDealFlow$ -v -count 1

Which fails.

That branch is pristine testnet/3, only changing TestAPIDealFlow to do a second deal is after the (original) first one. The second one is exactly the same with the same logic (copy pasted), with some other randomness to make the Cid different.

jsign commented 4 years ago

Extra logs for the failing part:

2020-03-25T19:27:44.538-0300    INFO    provideradapter storageadapter/provider.go:57   publishing deal
2020-03-25T19:27:44.572-0300    ERROR   vm      vm/runtime.go:85        spec actors failure: assertion failed
github.com/filecoin-project/lotus/chain/vm.(*Runtime).shimCall.func1
        /Users/ignaciohagopian/code/lotus/chain/vm/runtime.go:85
runtime.gopanic
        /usr/local/go/src/runtime/panic.go:967
github.com/filecoin-project/specs-actors/actors/util.AssertMsg
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/util/assert.go:9
github.com/filecoin-project/specs-actors/actors/util.Assert
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/util/assert.go:14
github.com/filecoin-project/specs-actors/actors/builtin/market.(*State).updatePendingDealState
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_state.go:110
github.com/filecoin-project/specs-actors/actors/builtin/market.(*State).updatePendingDealStates
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_state.go:80
github.com/filecoin-project/specs-actors/actors/builtin/market.(*State).updatePendingDealStatesForParty
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_state.go:72
github.com/filecoin-project/specs-actors/actors/builtin/market.Actor.PublishStorageDeals.func1
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_actor.go:190
github.com/filecoin-project/lotus/chain/vm.(*shimStateHandle).Transaction
        /Users/ignaciohagopian/code/lotus/chain/vm/runtime.go:380
github.com/filecoin-project/specs-actors/actors/builtin/market.Actor.PublishStorageDeals
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_actor.go:166
reflect.Value.call
        /usr/local/go/src/reflect/value.go:460
reflect.Value.Call
        /usr/local/go/src/reflect/value.go:321
github.com/filecoin-project/lotus/chain/vm.(*invoker).transform.func2.1
        /Users/ignaciohagopian/code/lotus/chain/vm/invoker.go:154
github.com/filecoin-project/lotus/chain/vm.(*Runtime).shimCall
        /Users/ignaciohagopian/code/lotus/chain/vm/runtime.go:90
github.com/filecoin-project/lotus/chain/vm.(*invoker).transform.func2
        /Users/ignaciohagopian/code/lotus/chain/vm/invoker.go:153
reflect.callReflect
        /usr/local/go/src/reflect/value.go:549
reflect.makeFuncStub
        /usr/local/go/src/reflect/asm_amd64.s:20
github.com/filecoin-project/lotus/chain/vm.(*invoker).Invoke
        /Users/ignaciohagopian/code/lotus/chain/vm/invoker.go:74
github.com/filecoin-project/lotus/chain/vm.(*VM).Invoke
        /Users/ignaciohagopian/code/lotus/chain/vm/vm.go:592
github.com/filecoin-project/lotus/chain/vm.(*VM).send
        /Users/ignaciohagopian/code/lotus/chain/vm/vm.go:238
github.com/filecoin-project/lotus/chain/vm.(*VM).ApplyMessage
        /Users/ignaciohagopian/code/lotus/chain/vm/vm.go:370
github.com/filecoin-project/lotus/chain/stmgr.(*StateManager).ApplyBlocks
        /Users/ignaciohagopian/code/lotus/chain/stmgr/stmgr.go:169
github.com/filecoin-project/lotus/chain/stmgr.(*StateManager).computeTipSetState
        /Users/ignaciohagopian/code/lotus/chain/stmgr/stmgr.go:330
github.com/filecoin-project/lotus/chain/stmgr.(*StateManager).TipSetState
        /Users/ignaciohagopian/code/lotus/chain/stmgr/stmgr.go:112
github.com/filecoin-project/lotus/chain/gen.MinerCreateBlock
        /Users/ignaciohagopian/code/lotus/chain/gen/mining.go:24
github.com/filecoin-project/lotus/node/impl/full.(*StateAPI).MinerCreateBlock
        /Users/ignaciohagopian/code/lotus/node/impl/full/state.go:267
github.com/filecoin-project/lotus/miner.(*Miner).createBlock
        /Users/ignaciohagopian/code/lotus/miner/miner.go:388
github.com/filecoin-project/lotus/miner.(*Miner).mineOne
        /Users/ignaciohagopian/code/lotus/miner/miner.go:332
github.com/filecoin-project/lotus/miner.(*Miner).mine
        /Users/ignaciohagopian/code/lotus/miner/miner.go:187
2020-03-25T19:27:44.572-0300    WARN    vm      vm/vm.go:387    Send actor error        {"from": "t3rzrk5ucuk22zglhl7tl2yrssfmo25yign32vok7kotlg2pklrze5hr4zbztitv3pjps5goluzybnokwwvzba", "to": "t05", "nonce": 9, "method": "5", "height": "60", "error": "spec actors failure: assertion failed (RetCode=1):\n    github.com/filecoin-project/lotus/chain/vm.(*Runtime).shimCall.func1\n        /Users/ignaciohagopian/code/lotus/chain/vm/runtime.go:86"}
2020-03-25T19:27:44.588-0300    WARN    providerstates  providerstates/provider_states.go:286   deal bafyreidfieeexr4i3n4pzhyi24szsuetjri2w26etwxmbjwwiduxenqj7a failed: error calling node: publishing deal: publishing deal failed: exit 1
2020-03-25T19:27:44.602-0300    ERROR   storagemarket_impl      clientstates/client_states.go:120       deal bafyreidfieeexr4i3n4pzhyi24szsuetjri2w26etwxmbjwwiduxenqj7a failed: deal wasn't accepted (State=7)
github.com/filecoin-project/go-fil-markets/storagemarket/impl/clientstates.FailDeal
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/go-fil-markets@v0.0.0-20200318012938-6403a5bda668/storagemarket/impl/clientstates/client_states.go:120
reflect.Value.call
        /usr/local/go/src/reflect/value.go:460
reflect.Value.Call
        /usr/local/go/src/reflect/value.go:321
github.com/filecoin-project/go-statemachine/fsm.fsmHandler.handler.func1
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/go-statemachine@v0.0.0-20200226041606-2074af6d51d9/fsm/fsm.go:98
reflect.callReflect
        /usr/local/go/src/reflect/value.go:549
reflect.makeFuncStub
        /usr/local/go/src/reflect/asm_amd64.s:20
reflect.Value.call
        /usr/local/go/src/reflect/value.go:460
reflect.Value.Call
        /usr/local/go/src/reflect/value.go:321
github.com/filecoin-project/go-statemachine.(*StateMachine).run.func3
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/go-statemachine@v0.0.0-20200226041606-2074af6d51d9/machine.go:91
    TestAPIDealFlow: deals.go:210: deal errored
jsign commented 4 years ago

And just in case, full logs of the test:

=== RUN   TestAPIDealFlow
2020-03-25T19:27:40.570-0300    INFO    p2pnode lp2p/addrs.go:114       Swarm listening at: [/ip6/::/tcp/0 /ip4/0.0.0.0/tcp/0]
2020-03-25T19:27:40.570-0300    WARN    genesis testing/genesis.go:36   Generating new random genesis block, note that this SHOULD NOT happen unless you are setting up new network
init set t3rzrk5ucuk22zglhl7tl2yrssfmo25yign32vok7kotlg2pklrze5hr4zbztitv3pjps5goluzybnokwwvzba t0100
calling publish storage deals on miner t01000 with worker t3rzrk5ucuk22zglhl7tl2yrssfmo25yign32vok7kotlg2pklrze5hr4zbztitv3pjps5goluzybnokwwvzba
calling publish storage deals on miner t01000 with worker t3rzrk5ucuk22zglhl7tl2yrssfmo25yign32vok7kotlg2pklrze5hr4zbztitv3pjps5goluzybnokwwvzba
Added weight to claim:  2049
Added weight to claim:  4097
2020-03-25T19:27:40.572-0300    INFO    genesis genesis/genesis.go:248  Empty Genesis root: bafy2bzaceaa43et73tgxsoh2xizd4mxhbrcfig4kqp25zfa5scdgkzppllyuu
2020-03-25T19:27:40.573-0300    WARN    hello   hello/hello.go:50       running without peer manager
2020-03-25T19:27:40.574-0300    WARN    modules modules/core.go:45      Generating new API secret
2020-03-25T19:27:40.582-0300    INFO    p2pnode lp2p/addrs.go:114       Swarm listening at: [/ip6/::/tcp/0 /ip4/0.0.0.0/tcp/0]
2020-03-25T19:27:40.584-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-stacked-proof-of-replication-PoseidonHasher-Sha256Hasher-fe437922fe766f61b112750506d6be0e4ad5daa85ff9ce96549d99253ba61cbe.vk is ok
2020-03-25T19:27:40.584-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-stacked-proof-of-replication-PoseidonHasher-Sha256Hasher-49442c8ce7545579cbd689d578301d0cc1e46e94e2499a0ec36de7ff4f4694a2.vk is ok
2020-03-25T19:27:40.584-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-proof-of-spacetime-election-PoseidonHasher-27a7fc680a47e4821f40cf1676fb80b9888820ef6867a71a175b4c9ae068ad3f.vk is ok
2020-03-25T19:27:40.584-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-proof-of-spacetime-election-PoseidonHasher-0b0b9781bcb153efbb3cab4be3a792c4f555d4ab6f8dd62b27e1dcad08a34f22.vk is ok
2020-03-25T19:27:40.584-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-stacked-proof-of-replication-PoseidonHasher-Sha256Hasher-d84aa4581c74190f845596893ebe5b71da32ecf16e1d151b9fff74ee8f94d77c.vk is ok
2020-03-25T19:27:40.584-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.vk is ok
2020-03-25T19:27:40.585-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-stacked-proof-of-replication-PoseidonHasher-Sha256Hasher-fc32be6028c2398175466f36fa36810842ae8948fae15c84454af5b61ca99e15.vk is ok
2020-03-25T19:27:40.585-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-proof-of-spacetime-election-PoseidonHasher-0b499a953f1a9dcab420b3ba1e6b1f3952dc7f17cf67ed10406ae9a43e2b8ec5.vk is ok
2020-03-25T19:27:40.688-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params is ok
2020-03-25T19:27:42.287-0300    INFO    build   go-paramfetch@v0.0.2-0.20200218225740-47c639bab663/paramfetch.go:127    Parameter file /var/tmp/filecoin-proof-parameters/v24-stacked-proof-of-replication-PoseidonHasher-Sha256Hasher-fe437922fe766f61b112750506d6be0e4ad5daa85ff9ce96549d99253ba61cbe.params is ok
2020-03-25T19:27:42.291-0300    WARN    storedask       storedask/storedask.go:117      no previous ask found, miner will not accept deals until a price is set
2020-03-25T19:27:42.297-0300    WARN    modules modules/core.go:45      Generating new API secret
2020-03-25T19:27:42.298-0300    WARN    pubsub  go-log@v1.0.2/log.go:175        opening new stream to peer: protocol not supported12D3KooWFEfGdResiEwUWUgoXR5Ln4DBtUbfmmXnr2ZpHppQpjAE
2020-03-25T19:27:42.298-0300    WARN    modules modules/services.go:41  failed to say hello     {"error": "protocol not supported"}
FILE CID:  bafkreie6nksbq65i2vqazxhf6k7cyqgnyzbcrfqgofzkdxsojf4k6egzfu
2020-03-25T19:27:43.342-0300    WARN    ret-discovery   discovery/local.go:28   Tracking multiple retrieval peers not implemented
2020-03-25T19:27:43.423-0300    INFO    storagemarket_impl      impl/provider.go:104    Handling storage deal proposal!
2020-03-25T19:27:43.435-0300    INFO    providerstates  providerstates/provider_states.go:115   fetching data for a deal bafyreifyt2nz3jj42a6v4ed7bmqojelencf6lqyem7f72kzjjrszazjgdy
2020-03-25T19:27:43.435-0300    WARN    data_transfer_network   network/libp2p_impl.go:65       error setting deadline: set pipe: deadline not supported
2020-03-25T19:27:43.436-0300    WARN    data_transfer_network   network/libp2p_impl.go:79       error resetting deadline: set pipe: deadline not supported
2020-03-25T19:27:43.436-0300    WARN    data_transfer_network   network/libp2p_impl.go:65       error setting deadline: set pipe: deadline not supported
2020-03-25T19:27:43.436-0300    WARN    data_transfer_network   network/libp2p_impl.go:79       error resetting deadline: set pipe: deadline not supported
2020-03-25T19:27:43.436-0300    WARN    graphsync_network       go-log@v1.0.2/log.go:180        error setting deadline: set pipe: deadline not supported
2020-03-25T19:27:43.436-0300    WARN    graphsync_network       go-log@v1.0.2/log.go:180        error resetting deadline: set pipe: deadline not supported
2020-03-25T19:27:43.436-0300    WARN    graphsync_network       go-log@v1.0.2/log.go:180        error setting deadline: set pipe: deadline not supported
2020-03-25T19:27:43.436-0300    WARN    graphsync_network       go-log@v1.0.2/log.go:180        error resetting deadline: set pipe: deadline not supported
2020-03-25T19:27:43.500-0300    INFO    provideradapter storageadapter/provider.go:57   publishing deal
2020-03-25T19:27:43.546-0300    INFO    sectors sealing/sealing.go:128  Seal piece for deal 2
2020-03-25T19:27:43.546-0300    WARN    sbmock  mock/mock.go:83 Add piece: {1000 3} 2032 4
2020-03-25T19:27:43.553-0300    WARN    sbmock  mock/mock.go:101        Generated Piece CID: bafk4chzaul66aiuxppbfgefqwzrlmnm3fvmzjgyyt7jnbxpjqdvx3ct33ayq
2020-03-25T19:27:43.553-0300    INFO    sectors sealing/sealing.go:151  Start sealing 3
2020-03-25T19:27:43.553-0300    INFO    sectors sealing/states.go:22    performing filling up rest of the sector...     {"sector": "3"}
2020-03-25T19:27:43.553-0300    INFO    provideradapter storageadapter/client.go:162    DEAL ACCEPTED!
2020-03-25T19:27:43.553-0300    WARN    provideradapter storageadapter/provider.go:109  New Deal: deal 2
2020-03-25T19:27:43.554-0300    INFO    sectors sealing/states.go:67    performing sector replication...        {"sector": "3"}
2020-03-25T19:27:43.554-0300    INFO    sectors sealing/states.go:129   submitting precommit for sector: 3
2020-03-25T19:27:43.558-0300    INFO    sectors sealing/states.go:140   Sector precommitted: 3
2020-03-25T19:27:43.604-0300    INFO    sectors sealing/states.go:151   precommit message landed on chain: 3
2020-03-25T19:27:43.604-0300    INFO    sectors sealing/states.go:154   precommit for sector 3 made it on chain, will start proof computation at height 20
2020-03-25T19:27:43.897-0300    INFO    sectors sealing/states.go:184   scheduling seal proof computation...
2020-03-25T19:27:43.897-0300    INFO    sectors sealing/states.go:186   KOMIT 3 c3b18af9c09928072447a0049e20aec5f702db4197af5f4a9f31f8ba917f8c86(-492); fac62f8567728ccd012f53511e34c57c92b8637abfb46ba71dbfd9d4c11451a1(20); [{2048 bafk4chzaul66aiuxppbfgefqwzrlmnm3fvmzjgyyt7jnbxpjqdvx3ct33ayq}]; r:6261666b3463687a6167686d6878637435356f616f74786f71326b7072726734756c65777a776e6e776d6b336c61656374796a357a6f69786137777261; d:6261666b3463687a61756c3636616975787070626667656671777a726c6d6e6d3366766d7a6a67797974376a6e6278706a716476783363743333617971
2020-03-25T19:27:43.938-0300    INFO    vm      vm/syscalls.go:93       Verif r:6261666b3463687a6167686d6878637435356f616f74786f71326b7072726734756c65777a776e6e776d6b336c61656374796a357a6f69786137777261; d:6261666b3463687a61756c3636616975787070626667656671777a726c6d6e6d3366766d7a6a67797974376a6e6278706a716476783363743333617971; m:t01000; t:c3b18af9c09928072447a0049e20aec5f702db4197af5f4a9f31f8ba917f8c86; s:fac62f8567728ccd012f53511e34c57c92b8637abfb46ba71dbfd9d4c11451a1; N:3; p:0e31bed290367ffb3329c8d7632be2e95616618a04385cdf90e8cbe8abbe89a6
2020-03-25T19:27:43.955-0300    INFO    sectors sealing/fsm.go:170      Proving sector 3
2020-03-25T19:27:44.017-0300    INFO    provideradapter storageadapter/client.go:264    Storage deal 2 activated at epoch 28
2020-03-25T19:27:44.017-0300    INFO    provideradapter storageadapter/provider.go:261  Storage deal 2 activated at epoch 28
COMPLETE &{bafyreifyt2nz3jj42a6v4ed7bmqojelencf6lqyem7f72kzjjrszazjgdy 6 t01000 [1 85 193 31 32 162 253 224 34 151 123 194 83 16 176 182 98 182 53 155 45 89 148 155 24 159 210 208 221 233 128 235 125 138 123 216 49] 2032 1000000 100 2}
Requesting payment
2020-03-25T19:27:44.411-0300    WARN    paych   paychmgr/paych.go:305   AddVoucher: voucher re-added with matching proof
FILE CID:  bafkreiaslltkhsi4cbzh3hdbebuol2nvr33nkptmv4ofticgvx53525pim
2020-03-25T19:27:44.429-0300    WARN    ret-discovery   discovery/local.go:28   Tracking multiple retrieval peers not implemented
2020-03-25T19:27:44.477-0300    INFO    storagemarket_impl      impl/provider.go:104    Handling storage deal proposal!
2020-03-25T19:27:44.485-0300    INFO    providerstates  providerstates/provider_states.go:115   fetching data for a deal bafyreidfieeexr4i3n4pzhyi24szsuetjri2w26etwxmbjwwiduxenqj7a
2020-03-25T19:27:44.485-0300    WARN    data_transfer_network   network/libp2p_impl.go:65       error setting deadline: set pipe: deadline not supported
2020-03-25T19:27:44.486-0300    WARN    data_transfer_network   network/libp2p_impl.go:79       error resetting deadline: set pipe: deadline not supported
2020-03-25T19:27:44.486-0300    WARN    data_transfer_network   network/libp2p_impl.go:65       error setting deadline: set pipe: deadline not supported
2020-03-25T19:27:44.486-0300    WARN    data_transfer_network   network/libp2p_impl.go:79       error resetting deadline: set pipe: deadline not supported
2020-03-25T19:27:44.486-0300    WARN    graphsync_network       go-log@v1.0.2/log.go:180        error setting deadline: set pipe: deadline not supported
2020-03-25T19:27:44.486-0300    WARN    graphsync_network       go-log@v1.0.2/log.go:180        error resetting deadline: set pipe: deadline not supported
2020-03-25T19:27:44.486-0300    WARN    graphsync_network       go-log@v1.0.2/log.go:180        error setting deadline: set pipe: deadline not supported
2020-03-25T19:27:44.486-0300    WARN    graphsync_network       go-log@v1.0.2/log.go:180        error resetting deadline: set pipe: deadline not supported
2020-03-25T19:27:44.538-0300    INFO    provideradapter storageadapter/provider.go:57   publishing deal
2020-03-25T19:27:44.572-0300    ERROR   vm      vm/runtime.go:85        spec actors failure: assertion failed
github.com/filecoin-project/lotus/chain/vm.(*Runtime).shimCall.func1
        /Users/ignaciohagopian/code/lotus/chain/vm/runtime.go:85
runtime.gopanic
        /usr/local/go/src/runtime/panic.go:967
github.com/filecoin-project/specs-actors/actors/util.AssertMsg
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/util/assert.go:9
github.com/filecoin-project/specs-actors/actors/util.Assert
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/util/assert.go:14
github.com/filecoin-project/specs-actors/actors/builtin/market.(*State).updatePendingDealState
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_state.go:110
github.com/filecoin-project/specs-actors/actors/builtin/market.(*State).updatePendingDealStates
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_state.go:80
github.com/filecoin-project/specs-actors/actors/builtin/market.(*State).updatePendingDealStatesForParty
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_state.go:72
github.com/filecoin-project/specs-actors/actors/builtin/market.Actor.PublishStorageDeals.func1
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_actor.go:190
github.com/filecoin-project/lotus/chain/vm.(*shimStateHandle).Transaction
        /Users/ignaciohagopian/code/lotus/chain/vm/runtime.go:380
github.com/filecoin-project/specs-actors/actors/builtin/market.Actor.PublishStorageDeals
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/specs-actors@v0.0.0-20200321055844-54fa2e8da1c2/actors/builtin/market/market_actor.go:166
reflect.Value.call
        /usr/local/go/src/reflect/value.go:460
reflect.Value.Call
        /usr/local/go/src/reflect/value.go:321
github.com/filecoin-project/lotus/chain/vm.(*invoker).transform.func2.1
        /Users/ignaciohagopian/code/lotus/chain/vm/invoker.go:154
github.com/filecoin-project/lotus/chain/vm.(*Runtime).shimCall
        /Users/ignaciohagopian/code/lotus/chain/vm/runtime.go:90
github.com/filecoin-project/lotus/chain/vm.(*invoker).transform.func2
        /Users/ignaciohagopian/code/lotus/chain/vm/invoker.go:153
reflect.callReflect
        /usr/local/go/src/reflect/value.go:549
reflect.makeFuncStub
        /usr/local/go/src/reflect/asm_amd64.s:20
github.com/filecoin-project/lotus/chain/vm.(*invoker).Invoke
        /Users/ignaciohagopian/code/lotus/chain/vm/invoker.go:74
github.com/filecoin-project/lotus/chain/vm.(*VM).Invoke
        /Users/ignaciohagopian/code/lotus/chain/vm/vm.go:592
github.com/filecoin-project/lotus/chain/vm.(*VM).send
        /Users/ignaciohagopian/code/lotus/chain/vm/vm.go:238
github.com/filecoin-project/lotus/chain/vm.(*VM).ApplyMessage
        /Users/ignaciohagopian/code/lotus/chain/vm/vm.go:370
github.com/filecoin-project/lotus/chain/stmgr.(*StateManager).ApplyBlocks
        /Users/ignaciohagopian/code/lotus/chain/stmgr/stmgr.go:169
github.com/filecoin-project/lotus/chain/stmgr.(*StateManager).computeTipSetState
        /Users/ignaciohagopian/code/lotus/chain/stmgr/stmgr.go:330
github.com/filecoin-project/lotus/chain/stmgr.(*StateManager).TipSetState
        /Users/ignaciohagopian/code/lotus/chain/stmgr/stmgr.go:112
github.com/filecoin-project/lotus/chain/gen.MinerCreateBlock
        /Users/ignaciohagopian/code/lotus/chain/gen/mining.go:24
github.com/filecoin-project/lotus/node/impl/full.(*StateAPI).MinerCreateBlock
        /Users/ignaciohagopian/code/lotus/node/impl/full/state.go:267
github.com/filecoin-project/lotus/miner.(*Miner).createBlock
        /Users/ignaciohagopian/code/lotus/miner/miner.go:388
github.com/filecoin-project/lotus/miner.(*Miner).mineOne
        /Users/ignaciohagopian/code/lotus/miner/miner.go:332
github.com/filecoin-project/lotus/miner.(*Miner).mine
        /Users/ignaciohagopian/code/lotus/miner/miner.go:187
2020-03-25T19:27:44.572-0300    WARN    vm      vm/vm.go:387    Send actor error        {"from": "t3rzrk5ucuk22zglhl7tl2yrssfmo25yign32vok7kotlg2pklrze5hr4zbztitv3pjps5goluzybnokwwvzba", "to": "t05", "nonce": 9, "method": "5", "height": "60", "error": "spec actors failure: assertion failed (RetCode=1):\n    github.com/filecoin-project/lotus/chain/vm.(*Runtime).shimCall.func1\n        /Users/ignaciohagopian/code/lotus/chain/vm/runtime.go:86"}
2020-03-25T19:27:44.588-0300    WARN    providerstates  providerstates/provider_states.go:286   deal bafyreidfieeexr4i3n4pzhyi24szsuetjri2w26etwxmbjwwiduxenqj7a failed: error calling node: publishing deal: publishing deal failed: exit 1
2020-03-25T19:27:44.602-0300    ERROR   storagemarket_impl      clientstates/client_states.go:120       deal bafyreidfieeexr4i3n4pzhyi24szsuetjri2w26etwxmbjwwiduxenqj7a failed: deal wasn't accepted (State=7)
github.com/filecoin-project/go-fil-markets/storagemarket/impl/clientstates.FailDeal
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/go-fil-markets@v0.0.0-20200318012938-6403a5bda668/storagemarket/impl/clientstates/client_states.go:120
reflect.Value.call
        /usr/local/go/src/reflect/value.go:460
reflect.Value.Call
        /usr/local/go/src/reflect/value.go:321
github.com/filecoin-project/go-statemachine/fsm.fsmHandler.handler.func1
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/go-statemachine@v0.0.0-20200226041606-2074af6d51d9/fsm/fsm.go:98
reflect.callReflect
        /usr/local/go/src/reflect/value.go:549
reflect.makeFuncStub
        /usr/local/go/src/reflect/asm_amd64.s:20
reflect.Value.call
        /usr/local/go/src/reflect/value.go:460
reflect.Value.Call
        /usr/local/go/src/reflect/value.go:321
github.com/filecoin-project/go-statemachine.(*StateMachine).run.func3
        /Users/ignaciohagopian/go/pkg/mod/github.com/filecoin-project/go-statemachine@v0.0.0-20200226041606-2074af6d51d9/machine.go:91
    TestAPIDealFlow: deals.go:210: deal errored
jsign commented 4 years ago

In the meantime, I'll keep rebasing that branch regularly to see if other change magically solves this issue.