eth_estimateGas returns Transaction execution error

[bettiah]

I'm running:

• Which Parity version?: v1.8.3-beta-b49c44a-20171114
• Which operating system?: MacOS
• How installed?: homebrew
• Are you fully synchronized?: no
• Did you try to restart the node?: yes

I am on a test network with InstantSeal engine.

While testing, "eth_estimateGas" rpc method starts to return code -32015. It seems to get stuck here, until the contract state is changed (via another Transaction).
Note that the same transaction, on the same deployed contract succeeded a few times.
There are assertions in the code path, but I will be very surprised if they are being hit.

2017-12-08 01:07:03   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_estimateGas", "params": [{"from": "0xd372eab3c4e20442431652123bbaa8c4d9700313", "to": "0x9627beb47876aee1ae1041351452fa42ab32d8da", "data": "0xbe45fd62000000000000000000000000289cfe49497ddd2103434abb3c16a4e1f39786d8000000000000000000000000000000000000000000000000000000000000006400000000000000000000000000000000000000000000000000000000000000600000000000000000000000000000000000000000000000000000000000000014a77082957d1345f19d0a05050f49f5a76e31a055000000000000000000000000"}], "id": 274}.
2017-12-08 01:07:03   DEBUG rpc  Response: {"jsonrpc":"2.0","error":{"code":-32015,"message":"Transaction execution error.","data":"Internal(\"Requires higher than upper limit of 1000000000000\")"},"id":274}.

It is possibly a duplicate of another issue :eth_estimateGas issues "Transaction execution error" #6685 .

[tomusdrw]

@bettiah Please make sure that all transactions (especially the transaction deploying the contract) has been mined correctly. There used to be some issues with InstantSeal engine, where some transactions were not properly mined if you are not using --force-sealing
You can also try to pass a pending argument to eth_estimateGas to make sure it's using the pending block, not latest one.

Could you also check if there is a contract under that address(eth_getCode("0x9627beb47876aee1ae1041351452fa42ab32d8da"))? That would help us understand the actual issue. Logs from a run with -lminer=debug would be useful as well.

[bettiah]

@tomusdrw Thank you for the detailed list of suggestions. Below are logs from 2 consecutive transactions, 1 passes & second one fails. I have also tried your suggestions about --force-sealing & adding "pending" and it does not change things. Also eth_getCode shows a contract. Furthermore, I can send another, different, transaction to the same contract that 'releases' the failure, and this transaction starts to work again. ( Only to stop working again after a few txns)

2017-12-09 00:37:00  IO Worker #3 INFO import     0/ 0 peers   54 KiB chain 13 KiB db 0 bytes queue 448 bytes sync  RPC:  0 conn,  0 req/s, 403 µs
2017-12-09 00:37:01   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_estimateGas", "params": [{"from": "0xd372eab3c4e20442431652123bbaa8c4d9700313", "to": "0xc26788385a0757975422d38005b01eef2d30d010", "data": "0xbe45fd6200000000000000000000000039358f9b1810f8d4f2449dd50308047905f522c0000000000000000000000000000000000000000000000000000000000000006400000000000000000000000000000000000000000000000000000000000000600000000000000000000000000000000000000000000000000000000000000014a77082957d1345f19d0a05050f49f5a76e31a055000000000000000000000000"}], "id": 37}.
2017-12-09 00:37:01   DEBUG rpc  Response: {"jsonrpc":"2.0","result":"0x11a58","id":37}.
2017-12-09 00:37:01   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_blockNumber", "params": [], "id": 38}.
2017-12-09 00:37:01   DEBUG rpc  Response: {"jsonrpc":"2.0","result":"0x1a","id":38}.
2017-12-09 00:37:01   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_getBlockByNumber", "params": ["0x1a", false], "id": 39}.
2017-12-09 00:37:01   DEBUG rpc  Response: {"jsonrpc":"2.0","result":{"author":"0x0000000000000000000000000000000000000000","difficulty":"0x20000","extraData":"0xd5830108038650617269747986312e32312e30826d61","gasLimit":"0x5941c3","gasUsed":"0x94e9","hash":"0x47c3d05292955e17b18dcb62ab49cb30be655391c090fc76a7146ba3c1faa351","logsBloom":"0x00000800000000000000000000000000000000000000000000000000020000000000000000000000000000000000000000000000000000000000000000004000000000000000000000000000000000000000000000000000000020000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000020000000000000000000000000000000000000000100000000800000000020000000000000001000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001200000001000000000000000000000000000000000000000","miner":"0x0000000000000000000000000000000000000000","number":"0x1a","parentHash":"0x8e4f24fdfa0c2975a554809d086d025617bd047d74f24b4014b64a5b9597a603","receiptsRoot":"0x2f4c39f0bf944bf672975497807eed22311c1638a4a8759b285d498a18b01e2f","sealFields":[],"sha3Uncles":"0x1dcc4de8dec75d7aab85b567b6ccd41ad312451b948a7413f0a142fd40d49347","size":"0x295","stateRoot":"0xfe712299698f4d66fcc2665f71af54547d4ed82e3a3677b989669422b64fbd67","timestamp":"0x5a2abf8b","totalDifficulty":"0x360000","transactions":["0x639f104b7fbfe47821df58bdb86da9b757538060c4dfe06b1762590b33877ec5"],"transactionsRoot":"0x1a7c47fc861b8dd30239abbdd05302603401b65d1c938c272a5884dbd68b5ab7","uncles":[]},"id":39}.
2017-12-09 00:37:01   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_sendTransaction", "params": [{"from": "0xd372eab3c4e20442431652123bbaa8c4d9700313", "to": "0xc26788385a0757975422d38005b01eef2d30d010", "data": "0xbe45fd6200000000000000000000000039358f9b1810f8d4f2449dd50308047905f522c0000000000000000000000000000000000000000000000000000000000000006400000000000000000000000000000000000000000000000000000000000000600000000000000000000000000000000000000000000000000000000000000014a77082957d1345f19d0a05050f49f5a76e31a055000000000000000000000000", "gas": "0x2a0f8"}], "id": 40}.
2017-12-09 00:37:01   TRACE own_tx  Importing transaction: PendingTransaction { transaction: SignedTransaction { transaction: UnverifiedTransaction { unsigned: Transaction { nonce: 11, gas_price: 0, gas: 172280, action: Call(c26788385a0757975422d38005b01eef2d30d010), value: 0, data: [190, 69, 253, 98, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 57, 53, 143, 155, 24, 16, 248, 212, 242, 68, 157, 213, 3, 8, 4, 121, 5, 245, 34, 192, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 100, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 96, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 20, 167, 112, 130, 149, 125, 19, 69, 241, 157, 10, 5, 5, 15, 73, 245, 167, 110, 49, 160, 85, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }, v: 69, r: 73056664550119176218088131569829602557097490149828893925401941846878070771579, s: 57085507445846833650472248647775998101168263535980462464181596297346622033770, hash: 2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71 }, sender: d372eab3c4e20442431652123bbaa8c4d9700313, public: Some(cf0a76c46dc1c182e319d742caecdfda3a4f3820a3875d38770f81d10934237995f0ee1bf64ccdbad5dc59514d504c7ed4215127f9b333f51923dc9cd098be05) }, condition: None }
2017-12-09 00:37:01   DEBUG own_tx  Imported to Current (hash 2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71)
2017-12-09 00:37:01   TRACE own_tx  Status: TransactionQueueStatus { pending: 1, future: 0 }
2017-12-09 00:37:01   TRACE miner  update_sealing
2017-12-09 00:37:01   TRACE miner  requires_reseal: sealing enabled
2017-12-09 00:37:01   TRACE miner  requires_reseal: should_disable_sealing=false; best_block=26, last_request=0
2017-12-09 00:37:01   TRACE miner  update_sealing: preparing a block
2017-12-09 00:37:01   TRACE miner  prepare_block: No existing work - making new block
2017-12-09 00:37:01   TRACE miner  Adding tx 2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71 took Duration { secs: 0, nanos: 292125 }
2017-12-09 00:37:01   TRACE miner  Pushed 1/1 transactions
2017-12-09 00:37:01   TRACE miner  update_sealing: engine indicates internal sealing
2017-12-09 00:37:01   TRACE miner  seal_block_internally: attempting internal seal.
2017-12-09 00:37:01   TRACE miner  chain_new_blocks
2017-12-09 00:37:01   DEBUG miner  minimal_gas_price: recalibrating...
2017-12-09 00:37:01   DEBUG miner  minimal_gas_price: Got gas price! 0
2017-12-09 00:37:01   INFO own_tx  Transaction mined (hash 2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71)
2017-12-09 00:37:01   TRACE miner  update_sealing
2017-12-09 00:37:01   TRACE miner  requires_reseal: sealing enabled
2017-12-09 00:37:01   TRACE miner  requires_reseal: should_disable_sealing=false; best_block=27, last_request=0
2017-12-09 00:37:01   TRACE miner  update_sealing: preparing a block
2017-12-09 00:37:01   TRACE miner  prepare_block: No existing work - making new block
2017-12-09 00:37:01   TRACE miner  Pushed 0/0 transactions
2017-12-09 00:37:01   TRACE miner  update_sealing: engine indicates internal sealing
2017-12-09 00:37:01   INFO import  Imported #27 1eb4…0306 (1 txs, 0.07 Mgas, 0.76 ms, 0.74 KiB)
2017-12-09 00:37:01   TRACE miner  update_sealing: imported internally sealed block
2017-12-09 00:37:01   DEBUG rpc  [Some(Num(40))] Took 18ms
2017-12-09 00:37:01   DEBUG rpc  Response: {"jsonrpc":"2.0","result":"0x2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71","id":40}.
2017-12-09 00:37:01   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_getTransactionReceipt", "params": ["0x2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71"], "id": 41}.
2017-12-09 00:37:01   DEBUG rpc  Response: {"jsonrpc":"2.0","result":{"blockHash":"0x1eb4f0f47d4f992a4c127f7d644d63fd1b9b591c1cfa185467f358aa06e40306","blockNumber":"0x1b","contractAddress":null,"cumulativeGasUsed":"0x11a58","gasUsed":"0x11a58","logs":[{"address":"0x39358f9b1810f8d4f2449dd50308047905f522c0","blockHash":"0x1eb4f0f47d4f992a4c127f7d644d63fd1b9b591c1cfa185467f358aa06e40306","blockNumber":"0x1b","data":"0x0000000000000000000000000000000000000000000000000000000000000064","logIndex":"0x0","topics":["0x986876e67d288f7b8bc5229976a1d5710be919feb66d2e1aec1bf3eadebba207","0x000000000000000000000000d372eab3c4e20442431652123bbaa8c4d9700313","0x000000000000000000000000a77082957d1345f19d0a05050f49f5a76e31a055"],"transactionHash":"0x2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71","transactionIndex":"0x0","transactionLogIndex":"0x0","type":"mined"},{"address":"0xc26788385a0757975422d38005b01eef2d30d010","blockHash":"0x1eb4f0f47d4f992a4c127f7d644d63fd1b9b591c1cfa185467f358aa06e40306","blockNumber":"0x1b","data":"0x000000000000000000000000000000000000000000000000000000000000006400000000000000000000000000000000000000000000000000000000000000400000000000000000000000000000000000000000000000000000000000000014a77082957d1345f19d0a05050f49f5a76e31a055000000000000000000000000","logIndex":"0x1","topics":["0xe19260aff97b920c7df27010903aeb9c8d2be5d310a2c67824cf3f15396e4c16","0x000000000000000000000000d372eab3c4e20442431652123bbaa8c4d9700313","0x00000000000000000000000039358f9b1810f8d4f2449dd50308047905f522c0"],"transactionHash":"0x2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71","transactionIndex":"0x0","transactionLogIndex":"0x1","type":"mined"}],"logsBloom":"0x00000800000000000000000000010000000000000c00000000000000020000000000000000000000000000000000000000000000000000004000000000004000000000000000000000000800000000000000000000000000000020000000000000000000000000000000000000000000000000000000000000000000000000000020000000000000000000000000000020000420000000000000000000000001000000000000000100000000800000000020000000000000000000000000000000000000000000000000000000000000000000000000010000000000000000000000010000000000200000000000000000200000000000000000000000000000","root":null,"status":"0x1","transactionHash":"0x2ab1739a6a91dbc3e82ebb6b264f419bfda4c52d42db66b680632d7039efed71","transactionIndex":"0x0"},"id":41}.
2017-12-09 00:37:01   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_call", "params": [{"to": "0xc26788385a0757975422d38005b01eef2d30d010", "data": "0x70a08231000000000000000000000000d372eab3c4e20442431652123bbaa8c4d9700313"}, "latest"], "id": 42}.
2017-12-09 00:37:01   DEBUG rpc  Response: {"jsonrpc":"2.0","result":"0x0000000000000000000000000000000000000000000000000000000000000321","id":42}.

2017-12-09 00:37:13   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_estimateGas", "params": [{"from": "0xd372eab3c4e20442431652123bbaa8c4d9700313", "to": "0xc26788385a0757975422d38005b01eef2d30d010", "data": "0xbe45fd6200000000000000000000000039358f9b1810f8d4f2449dd50308047905f522c0000000000000000000000000000000000000000000000000000000000000006400000000000000000000000000000000000000000000000000000000000000600000000000000000000000000000000000000000000000000000000000000014a77082957d1345f19d0a05050f49f5a76e31a055000000000000000000000000"}], "id": 43}.
2017-12-09 00:37:13   DEBUG rpc  Response: {"jsonrpc":"2.0","error":{"code":-32015,"message":"Transaction execution error.","data":"Internal(\"Requires higher than upper limit of 1000000000000\")"},"id":43}.
2017-12-09 00:37:13   TRACE rpc  Request: {"jsonrpc": "2.0", "method": "eth_call", "params": [{"to": "0xc26788385a0757975422d38005b01eef2d30d010", "data": "0x70a08231000000000000000000000000d372eab3c4e20442431652123bbaa8c4d9700313"}, "latest"], "id": 44}.
2017-12-09 00:37:13   DEBUG rpc  Response: {"jsonrpc":"2.0","result":"0x0000000000000000000000000000000000000000000000000000000000000321","id":44}.

[tomusdrw]

What kind of contract is it? Maybe there is just some assertion that causes it to fail (and consume all the gas)?

[comodoro]

I have encountered probably this, Transaction execution error on estimateGas(), with parity --mode offline --config dev --gasprice 0 --force-sealing --reseal-min-period 3000 -l DEBUG --unlock 0x00a329c0648769a73afac7f9381e08fb43dbea72 --password pwd there is quite a lot of logs, but relevant seems to be this:

2018-07-10 20:57:13   DEBUG trace  Traced failed call FlatTrace { action: Call(Call { from: 0000000000000000000000000000000000000000, to: bb0eaa2ac4753b686c399706e4752829a5124073, value: 0, gas: 5309445, input: [218, 155, 198, 236, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 64, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], call_type: Call }), result: FailedCall(Reverted), subtraces: 0, trace_address: [] }
2018-07-10 20:57:13   DEBUG trace  Traced failed call FlatTrace { action: Call(Call { from: 0000000000000000000000000000000000000000, to: bb0eaa2ac4753b686c399706e4752829a5124073, value: 0, gas: 5309445, input: [218, 155, 198, 236, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 64, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], call_type: Call }), result: FailedCall(Reverted), subtraces: 0, trace_address: [] }
2018-07-10 20:57:13   DEBUG rpc  Response: {"jsonrpc":"2.0","result":true,"id":39}.
2018-07-10 20:57:13   DEBUG pubsub  Adding subscription id=Id(04c801ee57537930)
2018-07-10 20:57:13   DEBUG trace  Traced failed call FlatTrace { action: Call(Call { from: 0000000000000000000000000000000000000000, to: bb0eaa2ac4753b686c399706e4752829a5124073, value: 0, gas: 53289930, input: [218, 155, 198, 236, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 64, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], call_type: Call }), result: FailedCall(Reverted), subtraces: 0, trace_address: [] }
2018-07-10 20:57:13   DEBUG trace  Traced failed call FlatTrace { action: Call(Call { from: 0000000000000000000000000000000000000000, to: bb0eaa2ac4753b686c399706e4752829a5124073, value: 0, gas: 53289930, input: [218, 155, 198, 236, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 64, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], call_type: Call }), result: FailedCall(Reverted), subtraces: 0, trace_address: [] }
2018-07-10 20:57:13   DEBUG tokio_core::reactor  loop process - 4 events, Duration { secs: 0, nanos: 20505530 }
2018-07-10 20:57:13   DEBUG pubsub  Adding subscription id=Id(37a26954726e357f)
2018-07-10 20:57:13   DEBUG rpc  [Some(Num(40))] Took 16ms
2018-07-10 20:57:13   DEBUG rpc  [Some(Num(41))] Took 15ms
2018-07-10 20:57:13   DEBUG tokio_core::reactor  loop poll - Duration { secs: 0, nanos: 5470 }
2018-07-10 20:57:13   DEBUG tokio_core::reactor  loop time - Instant { t: 1532749707594 }
2018-07-10 20:57:13   DEBUG tokio_core::reactor  consuming notification queue
2018-07-10 20:57:13   DEBUG tokio_core::reactor  loop process - 1 events, Duration { secs: 0, nanos: 2977915 }
2018-07-10 20:57:13   DEBUG tokio_core::reactor  loop poll - Duration { secs: 0, nanos: 9846 }
2018-07-10 20:57:13   DEBUG tokio_core::reactor  loop time - Instant { t: 1532749735867 }
2018-07-10 20:57:13   DEBUG rpc  Response: {"jsonrpc":"2.0","error":{"code":-32015,"message":"Transaction execution error.","data":"Internal(\"Requires higher than upper limit of 53311650\")"},"id":40}.
2018-07-10 20:57:13   DEBUG rpc  Response: {"jsonrpc":"2.0","error":{"code":-32015,"message":"Transaction execution error.","data":"Internal(\"Requires higher than upper limit of 53311650\")"},"id":41}.
2018-07-10 20:57:13   DEBUG rpc  Response: {"jsonrpc":"2.0","result":[],"id":42}.
2018-07-10 20:57:13   DEBUG rpc  Response: {"jsonrpc":"2.0","result":"0x04c801ee57537930","id":43}.
2018-07-10 20:57:13   DEBUG rpc  Response: {"jsonrpc":"2.0","result":[],"id":44}.
2018-07-10 20:57:13   DEBUG rpc  Response: {"jsonrpc":"2.0","result":"0x37a26954726e357f","id":45}.

From that this

{"code":-32015,"message":"Transaction execution error.","data":"Internal(\"Requires higher than upper limit of 53311650\")"},"id":40}.

tries to suggest something, unfortunately I have no idea what requires higher limit of what

@5chdn Does it qualify for reopening or should I create a new issue?

[comodoro]

Just FYI, the code in question passes with geth --dev --nodiscover --maxpeers 0 --ws --wsorigins="*"

[Tbaut]

@comodoro There are 1 or 2 issues related already that you could contribute to: #8675 and #8893

[comodoro]

Thanks, I have added to the first one