Skip to content

Instantly share code, notes, and snippets.

@fanquake
Last active Oct 16, 2020
Embed
What would you like to do?

master @ 9ad7cd2887abf5f91495337a2458560de10b4f69 + bitcoin/bitcoin#20080

feature_asmap.py                      | ✖ Failed  | 11 s
feature_filelock.py                   | ✖ Failed  | 2 s
interface_rpc.py                      | ✖ Failed  | 3 s
rpc_createmultisig.py                 | ✖ Failed  | 23 s
rpc_createmultisig.py --descriptors   | ✖ Failed  | 105 s
rpc_dumptxoutset.py                   | ✖ Failed  | 5 s
tool_wallet.py                        | ✖ Failed  | 4 s
wallet_keypool.py --descriptors       | ✖ Failed  | 124 s
wallet_multiwallet.py                 | ✖ Failed  | 151 s
wallet_multiwallet.py --descriptors   | ✖ Failed  | 126 s
wallet_multiwallet.py --usecli        | ✖ Failed  | 156 s

ALL                                   | ✖ Failed  | 9867 s (accumulated) 

Logs:

Temporary test directory at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314
Running Unit Tests for Test Framework Modules
..........
----------------------------------------------------------------------
Ran 10 tests in 0.922s

OK
1/172 - wallet_hd.py passed, Duration: 58 s                                                                        
2/172 - wallet_hd.py --descriptors passed, Duration: 67 s
3/172 - mining_getblocktemplate_longpoll.py passed, Duration: 75 s
4/172 - p2p_compactblocks.py passed, Duration: 82 s
5/172 - rpc_fundrawtransaction.py passed, Duration: 88 s
6/172 - feature_segwit.py passed, Duration: 97 s
7/172 - p2p_timeouts.py passed, Duration: 9 s
8/172 - wallet_labels.py passed, Duration: 39 s
9/172 - feature_maxuploadtarget.py passed, Duration: 127 s
10/172 - wallet_backup.py passed, Duration: 157 s        
11/172 - wallet_labels.py --descriptors passed, Duration: 76 s
12/172 - wallet_backup.py --descriptors passed, Duration: 159 s
13/172 - wallet_dump.py passed, Duration: 35 s
14/172 - p2p_tx_download.py passed, Duration: 71 s
15/172 - p2p_sendheaders.py passed, Duration: 23 s
16/172 - rpc_txoutproof.py passed, Duration: 7 s
17/172 - wallet_basic.py passed, Duration: 133 s
18/172 - wallet_listtransactions.py passed, Duration: 36 s
19/172 - mempool_limit.py passed, Duration: 15 s
20/172 - wallet_importmulti.py passed, Duration: 45 s
21/172 - wallet_abandonconflict.py passed, Duration: 23 s
22/172 - wallet_listreceivedby.py passed, Duration: 29 s
23/172 - p2p_feefilter.py passed, Duration: 24 s
24/172 - feature_csv_activation.py passed, Duration: 55 s
25/172 - feature_reindex.py passed, Duration: 12 s
26/172 - rpc_rawtransaction.py passed, Duration: 72 s
27/172 - wallet_basic.py --descriptors passed, Duration: 217 s
28/172 - feature_abortnode.py passed, Duration: 67 s           
29/172 - wallet_address_types.py passed, Duration: 110 s
30/172 - wallet_keypool_topup.py passed, Duration: 81 s
31/172 - mempool_resurrect.py passed, Duration: 4 s
32/172 - interface_bitcoin_cli.py passed, Duration: 22 s
33/172 - p2p_segwit.py passed, Duration: 256 s
34/172 - tool_wallet.py failed, Duration: 4 s

stdout:
2020-10-16T01:08:59.542000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/tool_wallet_130
2020-10-16T01:09:01.574000Z TestFramework (INFO): Testing that various invalid commands raise with specific error messages
2020-10-16T01:09:02.270000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/tool_wallet.py", line 217, in run_test
    self.test_invalid_tool_commands_and_args()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/tool_wallet.py", line 77, in test_invalid_tool_commands_and_args
    'info',
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/tool_wallet.py", line 39, in assert_raises_tool_error
    assert_equal(stderr.strip(), error)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 50, in assert_equal
    raise AssertionError("not(%s)" % " == ".join(str(arg) for arg in (thing1, thing2) + args))
AssertionError: not(Error initializing wallet database environment "/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/tool_wallet_130/node0/regtest/wallets"! == Error initializing wallet database environment "/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/tool_wallet_130/node0/regtest/wallets"!)
2020-10-16T01:09:02.340000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:09:03.003000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/tool_wallet_130
2020-10-16T01:09:03.003000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/tool_wallet_130/test_framework.log
2020-10-16T01:09:03.003000Z TestFramework (ERROR): 
2020-10-16T01:09:03.004000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/tool_wallet_130' to consolidate all logs
2020-10-16T01:09:03.004000Z TestFramework (ERROR): 
2020-10-16T01:09:03.004000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:09:03.009000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:09:03.021000Z TestFramework (ERROR): 


stderr:


35/172 - wallet_txn_doublespend.py --mineblock passed, Duration: 22 s
36/172 - wallet_txn_clone.py passed, Duration: 21 s
37/172 - wallet_txn_clone.py --segwit passed, Duration: 20 s
38/172 - feature_bip68_sequence.py passed, Duration: 160 s
39/172 - rpc_misc.py passed, Duration: 11 s
40/172 - mempool_spend_coinbase.py passed, Duration: 5 s
41/172 - rpc_getchaintips.py passed, Duration: 22 s
42/172 - mempool_reorg.py passed, Duration: 19 s
43/172 - interface_zmq.py passed, Duration: 97 s
44/172 - interface_rest.py passed, Duration: 48 s
45/172 - wallet_keypool_topup.py --descriptors passed, Duration: 152 s
46/172 - mempool_persist.py passed, Duration: 48 s                       
47/172 - wallet_avoidreuse.py passed, Duration: 89 s
48/172 - wallet_createwallet.py passed, Duration: 14 s
49/172 - wallet_watchonly.py passed, Duration: 9 s
50/172 - wallet_createwallet.py --usecli passed, Duration: 17 s
51/172 - wallet_watchonly.py --usecli passed, Duration: 12 s
52/172 - interface_http.py passed, Duration: 7 s
53/172 - wallet_reorgsrestore.py passed, Duration: 18 s
54/172 - interface_rpc.py failed, Duration: 3 s

stdout:
2020-10-16T01:11:36.426000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/interface_rpc_110
2020-10-16T01:11:38.242000Z TestFramework (INFO): Testing getrpcinfo...
2020-10-16T01:11:38.251000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/interface_rpc.py", line 71, in run_test
    self.test_getrpcinfo()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/interface_rpc.py", line 36, in test_getrpcinfo
    assert_equal(info['logpath'], os.path.join(self.nodes[0].datadir, self.chain, 'debug.log'))
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 50, in assert_equal
    raise AssertionError("not(%s)" % " == ".join(str(arg) for arg in (thing1, thing2) + args))
AssertionError: not(/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/interface_rpc_110/node0/regtest/debug.log == /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/interface_rpc_110/node0/regtest/debug.log)
2020-10-16T01:11:38.321000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:11:39.229000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/interface_rpc_110
2020-10-16T01:11:39.244000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/interface_rpc_110/test_framework.log
2020-10-16T01:11:39.244000Z TestFramework (ERROR): 
2020-10-16T01:11:39.245000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/interface_rpc_110' to consolidate all logs
2020-10-16T01:11:39.245000Z TestFramework (ERROR): 
2020-10-16T01:11:39.245000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:11:39.245000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:11:39.245000Z TestFramework (ERROR): 


stderr:


55/172 - wallet_avoidreuse.py --descriptors passed, Duration: 129 s
56/172 - rpc_users.py passed, Duration: 16 s
57/172 - rpc_whitelist.py passed, Duration: 6 s
58/172 - feature_proxy.py passed, Duration: 5 s
59/172 - wallet_multiwallet.py --descriptors failed, Duration: 126 s

stdout:
2020-10-16T01:10:12.851000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_118
2020-10-16T01:12:08.780000Z TestFramework (INFO): Check for per-wallet settxfee call
2020-10-16T01:12:08.902000Z TestFramework (INFO): Test dynamic wallet loading
2020-10-16T01:12:12.562000Z TestFramework (INFO): Load first wallet
2020-10-16T01:12:12.783000Z TestFramework (INFO): Load second wallet
2020-10-16T01:12:13.108000Z TestFramework (INFO): Concurrent wallet loading
2020-10-16T01:12:13.609000Z TestFramework (INFO): Load remaining wallets
2020-10-16T01:12:15.222000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 133, in try_rpc
    fun(*args, **kwds)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 146, in __call__
    raise JSONRPCException(response['error'], status)
test_framework.authproxy.JSONRPCException: Wallet file verification failed. Failed to load database path '/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_118/node0/regtest/wallets/wallets'. Path does not exist. (-18)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/wallet_multiwallet.py", line 253, in run_test
    assert_raises_rpc_error(-18, "Wallet file verification failed. Failed to load database path '{}'. Path does not exist.".format(path), self.nodes[0].loadwallet, 'wallets')
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 124, in assert_raises_rpc_error
    assert try_rpc(code, message, fun, *args, **kwds), "No exception raised"
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 141, in try_rpc
    message, e.error['message']))
AssertionError: Expected substring not found in error message:
substring: 'Wallet file verification failed. Failed to load database path '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_118/node0/regtest/wallets/wallets'. Path does not exist.'
error message: 'Wallet file verification failed. Failed to load database path '/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_118/node0/regtest/wallets/wallets'. Path does not exist.'.
2020-10-16T01:12:15.291000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:12:18.068000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_118
2020-10-16T01:12:18.099000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_118/test_framework.log
2020-10-16T01:12:18.120000Z TestFramework (ERROR): 
2020-10-16T01:12:18.132000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_118' to consolidate all logs
2020-10-16T01:12:18.149000Z TestFramework (ERROR): 
2020-10-16T01:12:18.167000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:12:18.194000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:12:18.217000Z TestFramework (ERROR): 


stderr:


60/172 - rpc_signrawtransaction.py passed, Duration: 18 s
61/172 - rpc_psbt.py passed, Duration: 64 s
62/172 - wallet_multiwallet.py failed, Duration: 151 s

stdout:
2020-10-16T01:10:11.821000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_119
2020-10-16T01:12:33.167000Z TestFramework (INFO): Check for per-wallet settxfee call
2020-10-16T01:12:33.309000Z TestFramework (INFO): Test dynamic wallet loading
2020-10-16T01:12:37.273000Z TestFramework (INFO): Load first wallet
2020-10-16T01:12:37.516000Z TestFramework (INFO): Load second wallet
2020-10-16T01:12:37.874000Z TestFramework (INFO): Concurrent wallet loading
2020-10-16T01:12:38.250000Z TestFramework (INFO): Load remaining wallets
2020-10-16T01:12:39.848000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 133, in try_rpc
    fun(*args, **kwds)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 146, in __call__
    raise JSONRPCException(response['error'], status)
test_framework.authproxy.JSONRPCException: Wallet file verification failed. Failed to load database path '/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_119/node0/regtest/wallets/wallets'. Path does not exist. (-18)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/wallet_multiwallet.py", line 253, in run_test
    assert_raises_rpc_error(-18, "Wallet file verification failed. Failed to load database path '{}'. Path does not exist.".format(path), self.nodes[0].loadwallet, 'wallets')
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 124, in assert_raises_rpc_error
    assert try_rpc(code, message, fun, *args, **kwds), "No exception raised"
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 141, in try_rpc
    message, e.error['message']))
AssertionError: Expected substring not found in error message:
substring: 'Wallet file verification failed. Failed to load database path '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_119/node0/regtest/wallets/wallets'. Path does not exist.'
error message: 'Wallet file verification failed. Failed to load database path '/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_119/node0/regtest/wallets/wallets'. Path does not exist.'.
2020-10-16T01:12:39.928000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:12:43.010000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_119
2020-10-16T01:12:43.024000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_119/test_framework.log
2020-10-16T01:12:43.045000Z TestFramework (ERROR): 
2020-10-16T01:12:43.061000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_119' to consolidate all logs
2020-10-16T01:12:43.061000Z TestFramework (ERROR): 
2020-10-16T01:12:43.061000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:12:43.062000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:12:43.062000Z TestFramework (ERROR): 


stderr:


63/172 - p2p_addrv2_relay.py passed, Duration: 10 s
64/172 - rpc_decodescript.py passed, Duration: 4 s
65/172 - wallet_multiwallet.py --usecli failed, Duration: 156 s

stdout:
2020-10-16T01:10:15.395000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_117
2020-10-16T01:12:40.969000Z TestFramework (INFO): Check for per-wallet settxfee call
2020-10-16T01:12:41.241000Z TestFramework (INFO): Test dynamic wallet loading
2020-10-16T01:12:45.047000Z TestFramework (INFO): Load first wallet
2020-10-16T01:12:45.448000Z TestFramework (INFO): Load second wallet
2020-10-16T01:12:45.749000Z TestFramework (INFO): Concurrent wallet loading
2020-10-16T01:12:46.190000Z TestFramework (INFO): Load remaining wallets
2020-10-16T01:12:47.874000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 133, in try_rpc
    fun(*args, **kwds)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 563, in __call__
    return self.cli.send_cli(self.command, *args, **kwargs)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 627, in send_cli
    raise JSONRPCException(dict(code=int(code), message=message))
test_framework.authproxy.JSONRPCException: Wallet file verification failed. Failed to load database path '/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_117/node0/regtest/wallets/wallets'. Path does not exist. (-18)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/wallet_multiwallet.py", line 253, in run_test
    assert_raises_rpc_error(-18, "Wallet file verification failed. Failed to load database path '{}'. Path does not exist.".format(path), self.nodes[0].loadwallet, 'wallets')
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 124, in assert_raises_rpc_error
    assert try_rpc(code, message, fun, *args, **kwds), "No exception raised"
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 141, in try_rpc
    message, e.error['message']))
AssertionError: Expected substring not found in error message:
substring: 'Wallet file verification failed. Failed to load database path '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_117/node0/regtest/wallets/wallets'. Path does not exist.'
error message: 'Wallet file verification failed. Failed to load database path '/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_117/node0/regtest/wallets/wallets'. Path does not exist.'.
2020-10-16T01:12:47.947000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:12:50.981000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_117
2020-10-16T01:12:50.990000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_117/test_framework.log
2020-10-16T01:12:51.015000Z TestFramework (ERROR): 
2020-10-16T01:12:51.021000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_multiwallet_117' to consolidate all logs
2020-10-16T01:12:51.021000Z TestFramework (ERROR): 
2020-10-16T01:12:51.021000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:12:51.021000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:12:51.021000Z TestFramework (ERROR): 


stderr:


66/172 - wallet_disable.py passed, Duration: 2 s
67/172 - p2p_disconnect_ban.py passed, Duration: 13 s
68/172 - p2p_addr_relay.py passed, Duration: 11 s
69/172 - rpc_deprecated.py passed, Duration: 20 s
70/172 - p2p_getdata.py passed, Duration: 5 s
71/172 - rpc_blockchain.py passed, Duration: 35 s
72/172 - wallet_keypool.py passed, Duration: 11 s
73/172 - feature_fee_estimation.py passed, Duration: 330 s
74/172 - p2p_nobloomfilter_messages.py passed, Duration: 6 s
75/172 - rpc_psbt.py --descriptors passed, Duration: 123 s
76/172 - rpc_setban.py passed, Duration: 14 s
77/172 - p2p_filter.py passed, Duration: 22 s
78/172 - wallet_groups.py passed, Duration: 109 s
79/172 - p2p_invalid_locator.py passed, Duration: 16 s
80/172 - p2p_invalid_block.py passed, Duration: 8 s
81/172 - p2p_blocksonly.py passed, Duration: 35 s
82/172 - mining_prioritisetransaction.py passed, Duration: 39 s
83/172 - p2p_invalid_tx.py passed, Duration: 13 s
84/172 - p2p_invalid_messages.py passed, Duration: 18 s
85/172 - example_test.py passed, Duration: 8 s
86/172 - feature_backwards_compatibility.py skipped
87/172 - feature_block.py passed, Duration: 700 s
88/172 - wallet_txn_doublespend.py passed, Duration: 17 s
89/172 - rpc_getblockfilter.py passed, Duration: 6 s
90/172 - wallet_txn_clone.py --mineblock passed, Duration: 20 s
91/172 - rpc_invalidateblock.py passed, Duration: 8 s
92/172 - wallet_keypool.py --descriptors failed, Duration: 124 s

stdout:
2020-10-16T01:13:20.189000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/wallet_keypool_91
2020-10-16T01:14:23.598000Z TestFramework (ERROR): JSONRPC error
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 168, in _get_response
    http_response = self.__conn.getresponse()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1198, in getresponse
    response.begin()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 297, in begin
    version, status, reason = self._read_status()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 258, in _read_status
    line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/socket.py", line 576, in readinto
    return self._sock.recv_into(b)
socket.timeout: timed out

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/wallet_keypool.py", line 132, in run_test
    nodes[0].keypoolrefill(100)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 108, in _request
    return self._get_response()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 175, in _get_response
    self.__conn.timeout)})
test_framework.authproxy.JSONRPCException: 'keypoolrefill' RPC took longer than 30.000000 seconds. Consider using larger timeout for calls that take longer to return. (-344)
2020-10-16T01:14:23.685000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:14:23.720000Z TestFramework.node0 (ERROR): Unable to stop node.
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 319, in stop_node
    self.stop(wait=wait)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 107, in _request
    self.__conn.request(method, path, postdata, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1107, in request
    self._send_request(method, url, body, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1142, in _send_request
    self.putrequest(method, url, **skips)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 975, in putrequest
    raise CannotSendRequest(self.__state)
http.client.CannotSendRequest: Request-sent
2020-10-16T01:15:23.804000Z TestFramework.utils (ERROR): wait_until() failed. Predicate: ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
'''
[node 0] Cleaning up leftover process


stderr:
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/wallet_keypool.py", line 192, in <module>
    KeyPoolTest().main()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 149, in main
    exit_code = self.shutdown()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 273, in shutdown
    self.stop_nodes()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 521, in stop_nodes
    node.wait_until_stopped()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 362, in wait_until_stopped
    wait_until_helper(self.is_node_stopped, timeout=timeout, timeout_factor=self.timeout_factor)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 261, in wait_until_helper
    raise AssertionError("Predicate {} not true after {} seconds".format(predicate_source, timeout))
AssertionError: Predicate ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
''' not true after 60.0 seconds


93/172 - feature_notifications.py passed, Duration: 33 s
94/172 - rpc_createmultisig.py failed, Duration: 23 s

stdout:
2020-10-16T01:15:30.546000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_createmultisig_69
2020-10-16T01:15:35.236000Z TestFramework (INFO): Check that addmultisigaddress fails when the private keys are missing
2020-10-16T01:15:35.912000Z TestFramework (INFO): Generating blocks ...
2020-10-16T01:15:51.350000Z TestFramework (ERROR): JSONRPC error
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/rpc_createmultisig.py", line 56, in run_test
    self.do_multisig()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/rpc_createmultisig.py", line 130, in do_multisig
    node1.loadwallet('wmulti')
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 146, in __call__
    raise JSONRPCException(response['error'], status)
test_framework.authproxy.JSONRPCException: Wallet file verification failed. Failed to load database path '/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_createmultisig_69/node1/regtest/wallets/wmulti'. Path does not exist. (-18)
2020-10-16T01:15:51.423000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:15:53.126000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_createmultisig_69
2020-10-16T01:15:53.149000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_createmultisig_69/test_framework.log
2020-10-16T01:15:53.160000Z TestFramework (ERROR): 
2020-10-16T01:15:53.172000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_createmultisig_69' to consolidate all logs
2020-10-16T01:15:53.183000Z TestFramework (ERROR): 
2020-10-16T01:15:53.183000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:15:53.184000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:15:53.184000Z TestFramework (ERROR): 


stderr:


95/172 - feature_rbf.py passed, Duration: 51 s
96/172 - p2p_getaddr_caching.py passed, Duration: 198 s
97/172 - rpc_preciousblock.py passed, Duration: 19 s
98/172 - mempool_package_onemore.py passed, Duration: 88 s
99/172 - mempool_packages.py passed, Duration: 90 s
100/172 - wallet_importprunedfunds.py passed, Duration: 25 s
101/172 - p2p_leak_tx.py passed, Duration: 9 s
102/172 - rpc_signmessage.py passed, Duration: 3 s
103/172 - rpc_net.py passed, Duration: 238 s
104/172 - p2p_eviction.py passed, Duration: 13 s
105/172 - rpc_generate.py passed, Duration: 5 s
106/172 - feature_taproot.py passed, Duration: 673 s
107/172 - wallet_descriptor.py passed, Duration: 231 s
108/172 - feature_versionbits_warning.py passed, Duration: 67 s
109/172 - rpc_generateblock.py passed, Duration: 12 s
110/172 - mempool_accept.py passed, Duration: 10 s
111/172 - wallet_upgradewallet.py skipped
112/172 - mempool_expiry.py passed, Duration: 10 s
113/172 - rpc_bind.py --ipv4 skipped
114/172 - wallet_import_with_label.py passed, Duration: 6 s
115/172 - rpc_bind.py --ipv6 skipped
116/172 - rpc_bind.py --nonloopback skipped
117/172 - feature_nulldummy.py passed, Duration: 19 s
118/172 - feature_signet.py passed, Duration: 13 s
119/172 - rpc_createmultisig.py --descriptors failed, Duration: 105 s

stdout:
2020-10-16T01:15:53.540000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_createmultisig_68
2020-10-16T01:16:08.486000Z TestFramework (INFO): Generating blocks ...
2020-10-16T01:16:38.500000Z TestFramework (ERROR): JSONRPC error
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 168, in _get_response
    http_response = self.__conn.getresponse()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1198, in getresponse
    response.begin()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 297, in begin
    version, status, reason = self._read_status()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 258, in _read_status
    line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/socket.py", line 576, in readinto
    return self._sock.recv_into(b)
socket.timeout: timed out

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/rpc_createmultisig.py", line 48, in run_test
    node0.generate(149)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 298, in generate
    return self.generatetoaddress(nblocks=nblocks, address=self.get_deterministic_priv_key().address, maxtries=maxtries)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 108, in _request
    return self._get_response()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 175, in _get_response
    self.__conn.timeout)})
test_framework.authproxy.JSONRPCException: 'generatetoaddress' RPC took longer than 30.000000 seconds. Consider using larger timeout for calls that take longer to return. (-344)
2020-10-16T01:16:38.602000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:16:38.633000Z TestFramework.node0 (ERROR): Unable to stop node.
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 319, in stop_node
    self.stop(wait=wait)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/authproxy.py", line 107, in _request
    self.__conn.request(method, path, postdata, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1107, in request
    self._send_request(method, url, body, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1142, in _send_request
    self.putrequest(method, url, **skips)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 975, in putrequest
    raise CannotSendRequest(self.__state)
http.client.CannotSendRequest: Request-sent
2020-10-16T01:17:38.807000Z TestFramework.utils (ERROR): wait_until() failed. Predicate: ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
'''


stderr:
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/rpc_createmultisig.py", line 221, in <module>
    RpcCreateMultiSigTest().main()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 149, in main
    exit_code = self.shutdown()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 273, in shutdown
    self.stop_nodes()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 521, in stop_nodes
    node.wait_until_stopped()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 362, in wait_until_stopped
    wait_until_helper(self.is_node_stopped, timeout=timeout, timeout_factor=self.timeout_factor)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 261, in wait_until_helper
    raise AssertionError("Predicate {} not true after {} seconds".format(predicate_source, timeout))
AssertionError: Predicate ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
''' not true after 60.0 seconds


120/172 - rpc_named_arguments.py passed, Duration: 5 s
121/172 - mining_basic.py passed, Duration: 23 s
122/172 - wallet_implicitsegwit.py passed, Duration: 18 s
123/172 - p2p_leak.py passed, Duration: 11 s
124/172 - wallet_encryption.py passed, Duration: 9 s
125/172 - wallet_balance.py passed, Duration: 47 s
126/172 - wallet_importdescriptors.py passed, Duration: 42 s
127/172 - rpc_uptime.py passed, Duration: 4 s
128/172 - wallet_encryption.py --descriptors passed, Duration: 14 s
129/172 - wallet_listsinceblock.py passed, Duration: 25 s
130/172 - wallet_fallbackfee.py passed, Duration: 6 s
131/172 - rpc_dumptxoutset.py failed, Duration: 5 s

stdout:
2020-10-16T01:18:00.526000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_dumptxoutset_34
2020-10-16T01:18:04.313000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/rpc_dumptxoutset.py", line 34, in run_test
    assert_equal(out['path'], str(expected_path))
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/util.py", line 50, in assert_equal
    raise AssertionError("not(%s)" % " == ".join(str(arg) for arg in (thing1, thing2) + args))
AssertionError: not(/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_dumptxoutset_34/node0/regtest/txoutset.dat == /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_dumptxoutset_34/node0/regtest/txoutset.dat)
2020-10-16T01:18:04.377000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:18:04.790000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_dumptxoutset_34
2020-10-16T01:18:04.806000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_dumptxoutset_34/test_framework.log
2020-10-16T01:18:04.807000Z TestFramework (ERROR): 
2020-10-16T01:18:04.807000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/rpc_dumptxoutset_34' to consolidate all logs
2020-10-16T01:18:04.807000Z TestFramework (ERROR): 
2020-10-16T01:18:04.807000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:18:04.812000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:18:04.812000Z TestFramework (ERROR): 


stderr:


132/172 - rpc_estimatefee.py passed, Duration: 3 s
133/172 - rpc_getblockstats.py passed, Duration: 5 s
134/172 - wallet_resendwallettransactions.py passed, Duration: 16 s
135/172 - feature_minchainwork.py passed, Duration: 13 s
136/172 - feature_assumevalid.py passed, Duration: 218 s
137/172 - p2p_fingerprint.py passed, Duration: 12 s
138/172 - feature_filelock.py failed, Duration: 2 s

stdout:
2020-10-16T01:18:26.378000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_filelock_25
2020-10-16T01:18:27.260000Z TestFramework (INFO): Using datadir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_filelock_25/node0/regtest
2020-10-16T01:18:27.271000Z TestFramework (INFO): Check that we can't start a second bitcoind instance using the same datadir
2020-10-16T01:18:27.549000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 485, in assert_start_raises_init_error
    self.wait_for_rpc_connection()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 221, in wait_for_rpc_connection
    'bitcoind exited with status {} during initialization'.format(self.process.returncode)))
test_framework.test_node.FailedToStartError: [node 1] bitcoind exited with status 1 during initialization

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/feature_filelock.py", line 27, in run_test
    self.nodes[1].assert_start_raises_init_error(extra_args=['-datadir={}'.format(self.nodes[0].datadir), '-noserver'], expected_msg=expected_msg)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 507, in assert_start_raises_init_error
    'Expected message "{}" does not fully match stderr:\n"{}"'.format(expected_msg, stderr))
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 162, in _raise_assertion_error
    raise AssertionError(self._node_msg(msg))
AssertionError: [node 1] Expected message "Error: Cannot obtain a lock on data directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_filelock_25/node0/regtest. Bitcoin Core is probably already running." does not fully match stderr:
"Error: Cannot obtain a lock on data directory /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_filelock_25/node0/regtest. Bitcoin Core is probably already running."
2020-10-16T01:18:27.627000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:18:28.119000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_filelock_25
2020-10-16T01:18:28.120000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_filelock_25/test_framework.log
2020-10-16T01:18:28.120000Z TestFramework (ERROR): 
2020-10-16T01:18:28.121000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_filelock_25' to consolidate all logs
2020-10-16T01:18:28.123000Z TestFramework (ERROR): 
2020-10-16T01:18:28.135000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:18:28.153000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:18:28.154000Z TestFramework (ERROR): 


stderr:


139/172 - feature_uacomment.py passed, Duration: 11 s
140/172 - wallet_coinbase_category.py passed, Duration: 11 s
141/172 - p2p_dos_header_tree.py passed, Duration: 8 s
142/172 - wallet_send.py passed, Duration: 29 s
143/172 - feature_loadblock.py passed, Duration: 16 s
144/172 - feature_includeconf.py passed, Duration: 9 s
145/172 - feature_asmap.py failed, Duration: 11 s

stdout:
2020-10-16T01:18:44.644000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19
2020-10-16T01:18:47.875000Z TestFramework (INFO): Test bitcoind with no -asmap arg passed
2020-10-16T01:18:49.794000Z TestFramework (INFO): Test bitcoind -asmap=<absolute path>
2020-10-16T01:18:51.600000Z TestFramework (INFO): Test bitcoind -asmap=<relative path>
2020-10-16T01:18:54.477000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/feature_asmap.py", line 99, in run_test
    self.test_asmap_with_relative_path()
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/feature_asmap.py", line 64, in test_asmap_with_relative_path
    self.start_node(0, ['-asmap={}'.format(name)])
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/contextlib.py", line 66, in __exit__
    next(self.gen)
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 393, in assert_debug_log
    self._raise_assertion_error('Expected messages "{}" does not partially match log:\n\n{}\n\n'.format(str(expected_msgs), print_log))
  File "/Users/michael/github/bitcoin-merge-tree/test/functional/test_framework/test_node.py", line 162, in _raise_assertion_error
    raise AssertionError(self._node_msg(msg))
AssertionError: [node 0] Expected messages "['Opened asmap file "/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_\u20bf_🏃_20201016_090314/feature_asmap_19/node0/regtest/ASN_map" (59 bytes) from disk', 'Using asmap version fec61fa21a9f46f3b17bdcd660d7f4cd90b966aad3aec593c99b35f0aca15853 for IP bucketing']" does not partially match log:

 - 
 - 
 - 
 - 
 - 
 - 2020-10-16T01:18:52.609365Z [init] Bitcoin Core version v0.20.99.0-7642ae91a (release build)
 - 2020-10-16T01:18:52.609388Z [init] InitParameterInteraction: parameter interaction: -bind set -> setting -listen=1
 - 2020-10-16T01:18:52.609670Z [init] Validating signatures for all blocks.
 - 2020-10-16T01:18:52.609687Z [init] Setting nMinimumChainWork=0000000000000000000000000000000000000000000000000000000000000000
 - 2020-10-16T01:18:52.609829Z [init] Using the 'sse4(1way),sse41(4way),avx2(8way)' SHA256 implementation
 - 2020-10-16T01:18:52.609844Z [init] Using RdSeed as additional entropy source
 - 2020-10-16T01:18:52.609852Z [init] Using RdRand as an additional entropy source
 - 2020-10-16T01:18:52.747119Z [init] Default data directory /Users/michael/Library/Application Support/Bitcoin
 - 2020-10-16T01:18:52.754360Z [init] Using data directory /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest
 - 2020-10-16T01:18:52.761441Z [init] Config file: /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/bitcoin.conf
 - 2020-10-16T01:18:52.767634Z [init] Config file arg: regtest="1"
 - 2020-10-16T01:18:52.778696Z [init] Config file arg: [regtest] bind="127.0.0.1"
 - 2020-10-16T01:18:52.781924Z [init] Config file arg: [regtest] discover="0"
 - 2020-10-16T01:18:52.781965Z [init] Config file arg: [regtest] dnsseed="0"
 - 2020-10-16T01:18:52.781994Z [init] Config file arg: [regtest] fallbackfee="0.0002"
 - 2020-10-16T01:18:52.782020Z [init] Config file arg: [regtest] keypool="1"
 - 2020-10-16T01:18:52.782046Z [init] Config file arg: [regtest] listenonion="0"
 - 2020-10-16T01:18:52.787974Z [init] Config file arg: [regtest] port="11228"
 - 2020-10-16T01:18:52.794611Z [init] Config file arg: [regtest] printtoconsole="0"
 - 2020-10-16T01:18:52.806203Z [init] Config file arg: [regtest] rpcport="16228"
 - 2020-10-16T01:18:52.806238Z [init] Config file arg: [regtest] server="1"
 - 2020-10-16T01:18:52.806260Z [init] Config file arg: [regtest] shrinkdebugfile="0"
 - 2020-10-16T01:18:52.806279Z [init] Config file arg: [regtest] upnp="0"
 - 2020-10-16T01:18:52.806299Z [init] Setting file arg: wallet = [""]
 - 2020-10-16T01:18:52.806319Z [init] Command-line arg: asmap="ASN_map"
 - 2020-10-16T01:18:52.806343Z [init] Command-line arg: datadir="/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0"
 - 2020-10-16T01:18:52.806361Z [init] Command-line arg: debug=""
 - 2020-10-16T01:18:52.806377Z [init] Command-line arg: debugexclude="libevent"
 - 2020-10-16T01:18:52.806395Z [init] Command-line arg: debugexclude="leveldb"
 - 2020-10-16T01:18:52.806412Z [init] Command-line arg: logthreadnames=""
 - 2020-10-16T01:18:52.806430Z [init] Command-line arg: logtimemicros=""
 - 2020-10-16T01:18:52.806447Z [init] Command-line arg: uacomment="testnode0"
 - 2020-10-16T01:18:52.806472Z [init] Using at most 125 automatic connections (2560 file descriptors available)
 - 2020-10-16T01:18:52.819991Z [init] Using 16 MiB out of 32/2 requested for signature cache, able to store 524288 elements
 - 2020-10-16T01:18:52.836193Z [init] Using 16 MiB out of 32/2 requested for script execution cache, able to store 524288 elements
 - 2020-10-16T01:18:52.841920Z [init] Script verification uses 7 additional threads
 - 2020-10-16T01:18:52.848209Z [scheduler] scheduler thread start
 - 2020-10-16T01:18:52.874971Z [init] Allowing HTTP connections from: 127.0.0.0/8 ::1/128 
 - 2020-10-16T01:18:52.875828Z [init] Binding RPC on address ::1 port 16228
 - 2020-10-16T01:18:52.883418Z [init] Binding RPC on address 127.0.0.1 port 16228
 - 2020-10-16T01:18:52.883538Z [init] Initialized HTTP server
 - 2020-10-16T01:18:52.883574Z [init] HTTP: creating work queue of depth 16
 - 2020-10-16T01:18:52.883591Z [init] Starting RPC
 - 2020-10-16T01:18:52.883632Z [init] Starting HTTP RPC server
 - 2020-10-16T01:18:52.883652Z [init] Using random cookie authentication.
 - 2020-10-16T01:18:52.884185Z [init] Generated RPC authentication cookie /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/.cookie
 - 2020-10-16T01:18:52.884220Z [init] Registering HTTP handler for / (exactmatch 1)
 - 2020-10-16T01:18:52.884242Z [init] Registering HTTP handler for /wallet/ (exactmatch 0)
 - 2020-10-16T01:18:52.884264Z [init] Starting HTTP server
 - 2020-10-16T01:18:52.884281Z [init] HTTP: starting 4 worker threads
 - 2020-10-16T01:18:52.884913Z [http] Entering http event loop
 - 2020-10-16T01:18:52.885067Z [init] Using wallet directory /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest
 - 2020-10-16T01:18:52.885109Z [init] init message: Verifying wallet(s)...
 - 2020-10-16T01:18:52.886992Z [init] Using BerkeleyDB version Berkeley DB 4.8.30: (April  9, 2010)
 - 2020-10-16T01:18:52.887022Z [init] Using wallet /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/wallet.dat
 - 2020-10-16T01:18:52.887368Z [init] BerkeleyEnvironment::Open: LogDir=/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/database ErrorFile=/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/db.log
 - 2020-10-16T01:18:52.941089Z [init] init message: Loading banlist...
 - 2020-10-16T01:18:52.947174Z [init] Loaded 0 banned node ips/subnets from banlist.dat  2ms
 - 2020-10-16T01:18:52.947496Z [init] net: setting try another outbound peer=false
 - 2020-10-16T01:18:52.947528Z [init] SetNetworkActive: true
 - 2020-10-16T01:18:52.948891Z [init] Opened asmap file "/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/ASN_map" (59 bytes) from disk
 - 2020-10-16T01:18:52.948958Z [init] Using asmap version fec61fa21a9f46f3b17bdcd660d7f4cd90b966aad3aec593c99b35f0aca15853 for IP bucketing
 - 2020-10-16T01:18:52.948988Z [init] Cache configuration:
 - 2020-10-16T01:18:52.949012Z [init] * Using 2.0 MiB for block index database
 - 2020-10-16T01:18:52.949035Z [init] * Using 8.0 MiB for chain state database
 - 2020-10-16T01:18:52.949055Z [init] * Using 440.0 MiB for in-memory UTXO set (plus up to 286.1 MiB of unused mempool space)
 - 2020-10-16T01:18:52.949072Z [init] init message: Loading block index...
 - 2020-10-16T01:18:52.949102Z [init] Switching active chainstate to Chainstate [ibd] @ height -1 (null)
 - 2020-10-16T01:18:52.949305Z [init] Opening LevelDB in /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/blocks/index
 - 2020-10-16T01:18:52.958751Z [http] Received a POST request for / from 127.0.0.1:61091
 - 2020-10-16T01:18:52.970928Z [httpworker.0] ThreadRPCServer method=getblockcount user=__cookie__
 - 2020-10-16T01:18:53.077147Z [init] Opened LevelDB successfully
 - 2020-10-16T01:18:53.077514Z [init] Using obfuscation key for /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/blocks/index: 0000000000000000
 - 2020-10-16T01:18:53.078754Z [init] LoadBlockIndexDB: last block file = 0
 - 2020-10-16T01:18:53.078801Z [init] LoadBlockIndexDB: last block file info: CBlockFileInfo(blocks=201, size=52550, heights=0...200, time=2011-02-02...2020-10-16)
 - 2020-10-16T01:18:53.078818Z [init] Checking all blk files are present...
 - 2020-10-16T01:18:53.078936Z [init] Opening LevelDB in /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/chainstate
 - 2020-10-16T01:18:53.198428Z [init] Opened LevelDB successfully
 - 2020-10-16T01:18:53.208892Z [init] Using obfuscation key for /private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/chainstate: 7e6856e539052b1a
 - 2020-10-16T01:18:53.217410Z [init] Loaded best chain: hashBestChain=6692cc8f184501f6d7688a7fd321a70b59683732c3dd954bbc25836a333fb735 height=200 date=2020-10-16T01:18:47Z progress=1.000000
 - 2020-10-16T01:18:53.223342Z [init] init message: Rewinding blocks...
 - 2020-10-16T01:18:53.224000Z [init] FlushStateToDisk: write block and undo data to disk started
 - 2020-10-16T01:18:53.238282Z [http] Received a POST request for / from 127.0.0.1:61093
 - 2020-10-16T01:18:53.244221Z [httpworker.1] ThreadRPCServer method=getblockcount user=__cookie__
 - 2020-10-16T01:18:53.246545Z [init] FlushStateToDisk: write block and undo data to disk completed (16.52ms)
 - 2020-10-16T01:18:53.258045Z [init] FlushStateToDisk: write block index to disk started
 - 2020-10-16T01:18:53.269001Z [init] FlushStateToDisk: write block index to disk completed (10.89ms)
 - 2020-10-16T01:18:53.274003Z [init] FlushStateToDisk: write coins cache to disk (0 coins, 0kB) started
 - 2020-10-16T01:18:53.281607Z [init] Writing final batch of 0.00 MiB
 - 2020-10-16T01:18:53.288931Z [init] Committed 0 changed transaction outputs (out of 0) to coin database...
 - 2020-10-16T01:18:53.288994Z [init] FlushStateToDisk: write coins cache to disk (0 coins, 0kB) completed (0.01s)
 - 2020-10-16T01:18:53.289045Z [init] Enqueuing ChainStateFlushed: block hash=6692cc8f184501f6d7688a7fd321a70b59683732c3dd954bbc25836a333fb735
 - 2020-10-16T01:18:53.289097Z [init] init message: Verifying blocks...
 - 2020-10-16T01:18:53.289173Z [init] Verifying last 6 blocks at level 3
 - 2020-10-16T01:18:53.289236Z [init] [0%]...ChainStateFlushed: block hash=6692cc8f184501f6d7688a7fd321a70b59683732c3dd954bbc25836a333fb735
 - 2020-10-16T01:18:53.291241Z [init] [16%]...[33%]...[50%]...[66%]...[83%]...[99%]...[DONE].
 - 2020-10-16T01:18:53.298280Z [init] No coin database inconsistencies in last 6 blocks (6 transactions)
 - 2020-10-16T01:18:53.298309Z [init]  block index             349ms
 - 2020-10-16T01:18:53.300374Z [init] Reading estimates: 190 buckets counting confirms up to 48 blocks
 - 2020-10-16T01:18:53.307254Z [init] Reading estimates: 190 buckets counting confirms up to 12 blocks
 - 2020-10-16T01:18:53.309806Z [init] Reading estimates: 190 buckets counting confirms up to 1008 blocks
 - 2020-10-16T01:18:53.322702Z [init] init message: Loading wallet...
 - 2020-10-16T01:18:53.329838Z [init] BerkeleyEnvironment::Open: LogDir=/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/database ErrorFile=/private/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/node0/regtest/db.log
 - 2020-10-16T01:18:53.368846Z [init] [default wallet] Wallet File Version = 169900
 - 2020-10-16T01:18:53.369483Z [init] [default wallet] Keys: 4 plaintext, 0 encrypted, 4 w/ metadata, 4 total. Unknown wallet records: 0
 - 2020-10-16T01:18:53.407404Z [init] [default wallet] Wallet completed loading in              78ms
 - 2020-10-16T01:18:53.411633Z [init] [default wallet] setKeyPool.size() = 2
 - 2020-10-16T01:18:53.411675Z [init] [default wallet] mapWallet.size() = 51
 - 2020-10-16T01:18:53.411702Z [init] [default wallet] m_address_book.size() = 3
 - 2020-10-16T01:18:53.411933Z [init] block tree size = 201
 - 2020-10-16T01:18:53.421389Z [init] nBestHeight = 200
 - 2020-10-16T01:18:53.429584Z [loadblk] loadblk thread start
 - 2020-10-16T01:18:53.439126Z [loadblk] Imported mempool transactions from disk: 0 succeeded, 0 failed, 0 expired, 0 already there, 0 waiting for initial broadcast
 - 2020-10-16T01:18:53.439235Z [loadblk] loadblk thread exit
 - 2020-10-16T01:18:53.441315Z [init] Bound to 127.0.0.1:11228
 - 2020-10-16T01:18:53.441558Z [init] Unable to bind to 127.0.0.1:18445 on this computer. Bitcoin Core is probably already running.
 - 2020-10-16T01:18:53.441672Z [init] init message: Loading P2P addresses...
 - 2020-10-16T01:18:53.442498Z [init] Loaded 0 addresses from peers.dat  1ms
 - 2020-10-16T01:18:53.442651Z [init] Loaded 0 addresses from "anchors.dat"
 - 2020-10-16T01:18:53.443350Z [init] 0 block-relay-only anchors will be tried for connections.
 - 2020-10-16T01:18:53.443430Z [init] init message: Starting network threads...
 - 2020-10-16T01:18:53.443649Z [init] DNS seeding disabled
 - 2020-10-16T01:18:53.444214Z [init] init message: Done loading
 - 2020-10-16T01:18:53.457593Z [net] net thread start
 - 2020-10-16T01:18:53.464906Z [opencon] opencon thread start
 - 2020-10-16T01:18:53.466833Z [msghand] msghand thread start
 - 2020-10-16T01:18:53.471071Z [addcon] addcon thread start
 - 2020-10-16T01:18:53.501983Z [http] Received a POST request for / from 127.0.0.1:61095
 - 2020-10-16T01:18:53.506844Z [httpworker.2] ThreadRPCServer method=getblockcount user=__cookie__
 - 2020-10-16T01:18:53.508546Z [http] Received a POST request for / from 127.0.0.1:61095
 - 2020-10-16T01:18:53.508708Z [httpworker.3] ThreadRPCServer method=getmempoolinfo user=__cookie__
 - 2020-10-16T01:18:54.458221Z [scheduler] Leaving InitialBlockDownload (latching to false)


2020-10-16T01:18:54.555000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:18:55.686000Z TestFramework (WARNING): Not cleaning up dir /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19
2020-10-16T01:18:55.693000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19/test_framework.log
2020-10-16T01:18:55.694000Z TestFramework (ERROR): 
2020-10-16T01:18:55.695000Z TestFramework (ERROR): Hint: Call /Users/michael/github/bitcoin-merge-tree/test/functional/combine_logs.py '/var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_090314/feature_asmap_19' to consolidate all logs
2020-10-16T01:18:55.695000Z TestFramework (ERROR): 
2020-10-16T01:18:55.695000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2020-10-16T01:18:55.696000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2020-10-16T01:18:55.696000Z TestFramework (ERROR): 


stderr:


146/172 - mempool_compatibility.py skipped

master @ 9ad7cd2887abf5f91495337a2458560de10b4f69

feature_versionbits_warning.py        | ✖ Failed  | 94 s
rpc_createmultisig.py --descriptors   | ✖ Failed  | 107 s
wallet_basic.py --descriptors         | ✖ Failed  | 141 s
wallet_keypool.py --descriptors       | ✖ Failed  | 126 s

ALL                                   | ✖ Failed  | 10037 s (accumulated)

Logs:

➜  fanquake-bitcoin git:(master) test/functional/test_runner.py --jobs=10
Temporary test directory at /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_092350
Running Unit Tests for Test Framework Modules
..........
----------------------------------------------------------------------
Ran 10 tests in 0.690s

OK
.......................................................................................................................                                                                                                                       1/172 - wallet_hd.py passed, Duration: 65 s
2/172 - wallet_hd.py --descriptors passed, Duration: 76 s
3/172 - mining_getblocktemplate_longpoll.py passed, Duration: 77 s
4/172 - p2p_compactblocks.py passed, Duration: 91 s
5/172 - rpc_fundrawtransaction.py passed, Duration: 96 s
6/172 - feature_segwit.py passed, Duration: 100 s
7/172 - p2p_timeouts.py passed, Duration: 9 s
8/172 - wallet_labels.py passed, Duration: 38 s
9/172 - feature_maxuploadtarget.py passed, Duration: 138 s
10/172 - wallet_backup.py passed, Duration: 163 s
11/172 - wallet_labels.py --descriptors passed, Duration: 81 s
12/172 - wallet_dump.py passed, Duration: 36 s
13/172 - wallet_backup.py --descriptors passed, Duration: 180 s
14/172 - p2p_tx_download.py passed, Duration: 81 s
15/172 - p2p_sendheaders.py passed, Duration: 22 s
16/172 - wallet_basic.py passed, Duration: 133 s
17/172 - wallet_listtransactions.py passed, Duration: 37 s
18/172 - mempool_limit.py passed, Duration: 12 s
19/172 - rpc_txoutproof.py passed, Duration: 7 s
20/172 - wallet_importmulti.py passed, Duration: 31 s
21/172 - wallet_basic.py --descriptors failed, Duration: 141 s

stdout:
2020-10-16T01:25:09.724000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_092350/wallet_basic_160
2020-10-16T01:25:24.413000Z TestFramework (INFO): Mining blocks...
2020-10-16T01:25:52.496000Z TestFramework (INFO): test gettxout
2020-10-16T01:25:54.662000Z TestFramework (INFO): test gettxout (second part)
2020-10-16T01:26:29.187000Z TestFramework (ERROR): JSONRPC error
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 168, in _get_response
    http_response = self.__conn.getresponse()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1198, in getresponse
    response.begin()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 297, in begin
    version, status, reason = self._read_status()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 258, in _read_status
    line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/socket.py", line 576, in readinto
    return self._sock.recv_into(b)
socket.timeout: timed out

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/wallet_basic.py", line 156, in run_test
    self.nodes[1].generate(100)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 298, in generate
    return self.generatetoaddress(nblocks=nblocks, address=self.get_deterministic_priv_key().address, maxtries=maxtries)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 108, in _request
    return self._get_response()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 175, in _get_response
    self.__conn.timeout)})
test_framework.authproxy.JSONRPCException: 'generatetoaddress' RPC took longer than 30.000000 seconds. Consider using larger timeout for calls that take longer to return. (-344)
2020-10-16T01:26:29.274000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:26:29.331000Z TestFramework.node1 (ERROR): Unable to stop node.
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 319, in stop_node
    self.stop(wait=wait)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 107, in _request
    self.__conn.request(method, path, postdata, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1107, in request
    self._send_request(method, url, body, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1142, in _send_request
    self.putrequest(method, url, **skips)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 975, in putrequest
    raise CannotSendRequest(self.__state)
http.client.CannotSendRequest: Request-sent
2020-10-16T01:27:30.651000Z TestFramework.utils (ERROR): wait_until() failed. Predicate: ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
'''


stderr:
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/wallet_basic.py", line 678, in <module>
    WalletTest().main()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 149, in main
    exit_code = self.shutdown()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 273, in shutdown
    self.stop_nodes()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 521, in stop_nodes
    node.wait_until_stopped()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 362, in wait_until_stopped
    wait_until_helper(self.is_node_stopped, timeout=timeout, timeout_factor=self.timeout_factor)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/util.py", line 261, in wait_until_helper
    raise AssertionError("Predicate {} not true after {} seconds".format(predicate_source, timeout))
AssertionError: Predicate ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
''' not true after 60.0 seconds


22/172 - wallet_abandonconflict.py passed, Duration: 27 s
23/172 - feature_csv_activation.py passed, Duration: 29 s
24/172 - feature_reindex.py passed, Duration: 7 s
25/172 - wallet_listreceivedby.py passed, Duration: 51 s
26/172 - p2p_feefilter.py passed, Duration: 23 s
27/172 - rpc_rawtransaction.py passed, Duration: 55 s
28/172 - wallet_address_types.py passed, Duration: 78 s  
29/172 - feature_abortnode.py passed, Duration: 65 s
30/172 - wallet_keypool_topup.py passed, Duration: 84 s
31/172 - interface_bitcoin_cli.py passed, Duration: 27 s
32/172 - mempool_resurrect.py passed, Duration: 6 s
33/172 - wallet_txn_doublespend.py --mineblock passed, Duration: 19 s
34/172 - p2p_segwit.py passed, Duration: 262 s
35/172 - tool_wallet.py passed, Duration: 18 s
36/172 - wallet_txn_clone.py --segwit passed, Duration: 15 s
37/172 - wallet_txn_clone.py passed, Duration: 19 s
38/172 - interface_zmq.py passed, Duration: 89 s
39/172 - rpc_getchaintips.py passed, Duration: 20 s
40/172 - rpc_misc.py passed, Duration: 9 s
41/172 - mempool_spend_coinbase.py passed, Duration: 5 s
42/172 - mempool_reorg.py passed, Duration: 14 s
43/172 - wallet_keypool_topup.py --descriptors passed, Duration: 152 s
44/172 - interface_rest.py passed, Duration: 35 s
45/172 - feature_bip68_sequence.py passed, Duration: 207 s
46/172 - mempool_persist.py passed, Duration: 46 s
47/172 - wallet_createwallet.py passed, Duration: 16 s
48/172 - wallet_avoidreuse.py passed, Duration: 89 s
49/172 - wallet_createwallet.py --usecli passed, Duration: 17 s
50/172 - wallet_watchonly.py passed, Duration: 13 s
51/172 - wallet_watchonly.py --usecli passed, Duration: 15 s
52/172 - wallet_reorgsrestore.py passed, Duration: 23 s
53/172 - interface_http.py passed, Duration: 11 s
54/172 - interface_rpc.py passed, Duration: 3 s
55/172 - wallet_avoidreuse.py --descriptors passed, Duration: 138 s
56/172 - rpc_users.py passed, Duration: 15 s
57/172 - rpc_whitelist.py passed, Duration: 6 s
58/172 - feature_proxy.py passed, Duration: 7 s
59/172 - wallet_multiwallet.py --descriptors passed, Duration: 143 s
60/172 - wallet_multiwallet.py passed, Duration: 167 s
61/172 - wallet_multiwallet.py --usecli passed, Duration: 147 s
62/172 - rpc_signrawtransaction.py passed, Duration: 22 s
63/172 - feature_fee_estimation.py passed, Duration: 313 s
64/172 - rpc_psbt.py passed, Duration: 68 s
65/172 - rpc_decodescript.py passed, Duration: 4 s
66/172 - p2p_addrv2_relay.py passed, Duration: 9 s
67/172 - wallet_disable.py passed, Duration: 3 s
68/172 - p2p_addr_relay.py passed, Duration: 4 s
69/172 - p2p_disconnect_ban.py passed, Duration: 12 s
70/172 - rpc_deprecated.py passed, Duration: 10 s
71/172 - p2p_getdata.py passed, Duration: 4 s
72/172 - wallet_keypool.py passed, Duration: 12 s
73/172 - rpc_blockchain.py passed, Duration: 47 s
74/172 - p2p_nobloomfilter_messages.py passed, Duration: 4 s
75/172 - p2p_filter.py passed, Duration: 25 s    
76/172 - rpc_psbt.py --descriptors passed, Duration: 142 s
77/172 - wallet_groups.py passed, Duration: 108 s
78/172 - rpc_setban.py passed, Duration: 15 s
79/172 - p2p_blocksonly.py passed, Duration: 19 s
80/172 - p2p_invalid_locator.py passed, Duration: 9 s
81/172 - p2p_invalid_block.py passed, Duration: 8 s
82/172 - mining_prioritisetransaction.py passed, Duration: 24 s
83/172 - p2p_invalid_messages.py passed, Duration: 15 s
84/172 - p2p_invalid_tx.py passed, Duration: 13 s
85/172 - example_test.py passed, Duration: 8 s
86/172 - feature_backwards_compatibility.py skipped
87/172 - wallet_txn_doublespend.py passed, Duration: 17 s
88/172 - wallet_keypool.py --descriptors failed, Duration: 126 s

stdout:
2020-10-16T01:33:43.006000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_092350/wallet_keypool_91
2020-10-16T01:34:48.217000Z TestFramework (ERROR): JSONRPC error
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 168, in _get_response
    http_response = self.__conn.getresponse()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1198, in getresponse
    response.begin()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 297, in begin
    version, status, reason = self._read_status()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 258, in _read_status
    line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/socket.py", line 576, in readinto
    return self._sock.recv_into(b)
socket.timeout: timed out

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/wallet_keypool.py", line 132, in run_test
    nodes[0].keypoolrefill(100)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 108, in _request
    return self._get_response()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 175, in _get_response
    self.__conn.timeout)})
test_framework.authproxy.JSONRPCException: 'keypoolrefill' RPC took longer than 30.000000 seconds. Consider using larger timeout for calls that take longer to return. (-344)
2020-10-16T01:34:48.333000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:34:48.371000Z TestFramework.node0 (ERROR): Unable to stop node.
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 319, in stop_node
    self.stop(wait=wait)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 107, in _request
    self.__conn.request(method, path, postdata, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1107, in request
    self._send_request(method, url, body, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1142, in _send_request
    self.putrequest(method, url, **skips)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 975, in putrequest
    raise CannotSendRequest(self.__state)
http.client.CannotSendRequest: Request-sent
2020-10-16T01:35:48.401000Z TestFramework.utils (ERROR): wait_until() failed. Predicate: ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
'''
[node 0] Cleaning up leftover process


stderr:
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/wallet_keypool.py", line 192, in <module>
    KeyPoolTest().main()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 149, in main
    exit_code = self.shutdown()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 273, in shutdown
    self.stop_nodes()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 521, in stop_nodes
    node.wait_until_stopped()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 362, in wait_until_stopped
    wait_until_helper(self.is_node_stopped, timeout=timeout, timeout_factor=self.timeout_factor)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/util.py", line 261, in wait_until_helper
    raise AssertionError("Predicate {} not true after {} seconds".format(predicate_source, timeout))
AssertionError: Predicate ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
''' not true after 60.0 seconds


89/172 - wallet_txn_clone.py --mineblock passed, Duration: 19 s
90/172 - rpc_getblockfilter.py passed, Duration: 7 s
91/172 - feature_block.py passed, Duration: 728 s
92/172 - rpc_invalidateblock.py passed, Duration: 10 s
93/172 - feature_notifications.py passed, Duration: 32 s
94/172 - feature_rbf.py passed, Duration: 51 s                
95/172 - p2p_getaddr_caching.py passed, Duration: 194 s
96/172 - rpc_net.py passed, Duration: 223 s                       
97/172 - mempool_package_onemore.py passed, Duration: 88 s
98/172 - mempool_packages.py passed, Duration: 93 s
99/172 - rpc_preciousblock.py passed, Duration: 15 s
100/172 - rpc_createmultisig.py passed, Duration: 87 s
101/172 - feature_taproot.py passed, Duration: 658 s
102/172 - rpc_signmessage.py passed, Duration: 4 s
103/172 - p2p_leak_tx.py passed, Duration: 15 s
104/172 - wallet_importprunedfunds.py passed, Duration: 18 s
105/172 - rpc_generate.py passed, Duration: 4 s
106/172 - wallet_descriptor.py passed, Duration: 242 s
107/172 - p2p_eviction.py passed, Duration: 18 s
108/172 - rpc_generateblock.py passed, Duration: 13 s
109/172 - mempool_accept.py passed, Duration: 8 s
110/172 - mempool_expiry.py passed, Duration: 8 s
111/172 - wallet_import_with_label.py passed, Duration: 5 s
112/172 - wallet_upgradewallet.py skipped
113/172 - rpc_bind.py --ipv4 skipped
114/172 - rpc_bind.py --ipv6 skipped
115/172 - rpc_bind.py --nonloopback skipped
116/172 - feature_nulldummy.py passed, Duration: 15 s
117/172 - feature_signet.py passed, Duration: 13 s
118/172 - feature_versionbits_warning.py failed, Duration: 94 s

stdout:
2020-10-16T01:36:48.714000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_092350/feature_versionbits_warning_67
2020-10-16T01:37:22.301000Z TestFramework (ERROR): JSONRPC error
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 168, in _get_response
    http_response = self.__conn.getresponse()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1198, in getresponse
    response.begin()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 297, in begin
    version, status, reason = self._read_status()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 258, in _read_status
    line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/socket.py", line 576, in readinto
    return self._sock.recv_into(b)
socket.timeout: timed out

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/feature_versionbits_warning.py", line 68, in run_test
    node.generatetoaddress(VB_PERIOD, node_deterministic_address)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 108, in _request
    return self._get_response()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 175, in _get_response
    self.__conn.timeout)})
test_framework.authproxy.JSONRPCException: 'generatetoaddress' RPC took longer than 30.000000 seconds. Consider using larger timeout for calls that take longer to return. (-344)
2020-10-16T01:37:22.375000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:37:22.412000Z TestFramework.node0 (ERROR): Unable to stop node.
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 319, in stop_node
    self.stop(wait=wait)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 107, in _request
    self.__conn.request(method, path, postdata, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1107, in request
    self._send_request(method, url, body, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1142, in _send_request
    self.putrequest(method, url, **skips)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 975, in putrequest
    raise CannotSendRequest(self.__state)
http.client.CannotSendRequest: Request-sent
2020-10-16T01:38:22.462000Z TestFramework.utils (ERROR): wait_until() failed. Predicate: ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
'''


stderr:
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/feature_versionbits_warning.py", line 103, in <module>
    VersionBitsWarningTest().main()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 149, in main
    exit_code = self.shutdown()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 273, in shutdown
    self.stop_nodes()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 521, in stop_nodes
    node.wait_until_stopped()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 362, in wait_until_stopped
    wait_until_helper(self.is_node_stopped, timeout=timeout, timeout_factor=self.timeout_factor)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/util.py", line 261, in wait_until_helper
    raise AssertionError("Predicate {} not true after {} seconds".format(predicate_source, timeout))
AssertionError: Predicate ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
''' not true after 60.0 seconds


119/172 - wallet_balance.py passed, Duration: 32 s
120/172 - mining_basic.py passed, Duration: 22 s
121/172 - rpc_named_arguments.py passed, Duration: 5 s
122/172 - wallet_implicitsegwit.py passed, Duration: 15 s
123/172 - rpc_createmultisig.py --descriptors failed, Duration: 107 s

stdout:
2020-10-16T01:36:48.073000Z TestFramework (INFO): Initializing test directory /var/folders/z2/cn877pxd3czdfh47mfkmbwgm0000gn/T/test_runner_₿_🏃_20201016_092350/rpc_createmultisig_68
2020-10-16T01:37:04.238000Z TestFramework (INFO): Generating blocks ...
2020-10-16T01:37:34.263000Z TestFramework (ERROR): JSONRPC error
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 168, in _get_response
    http_response = self.__conn.getresponse()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1198, in getresponse
    response.begin()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 297, in begin
    version, status, reason = self._read_status()
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 258, in _read_status
    line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/socket.py", line 576, in readinto
    return self._sock.recv_into(b)
socket.timeout: timed out

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 126, in main
    self.run_test()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/rpc_createmultisig.py", line 48, in run_test
    node0.generate(149)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 298, in generate
    return self.generatetoaddress(nblocks=nblocks, address=self.get_deterministic_priv_key().address, maxtries=maxtries)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 108, in _request
    return self._get_response()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 175, in _get_response
    self.__conn.timeout)})
test_framework.authproxy.JSONRPCException: 'generatetoaddress' RPC took longer than 30.000000 seconds. Consider using larger timeout for calls that take longer to return. (-344)
2020-10-16T01:37:34.341000Z TestFramework (INFO): Stopping nodes
2020-10-16T01:37:34.355000Z TestFramework.node0 (ERROR): Unable to stop node.
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 319, in stop_node
    self.stop(wait=wait)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/coverage.py", line 47, in __call__
    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 144, in __call__
    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/authproxy.py", line 107, in _request
    self.__conn.request(method, path, postdata, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1107, in request
    self._send_request(method, url, body, headers)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 1142, in _send_request
    self.putrequest(method, url, **skips)
  File "/Users/michael/.pyenv/versions/3.5.6/lib/python3.5/http/client.py", line 975, in putrequest
    raise CannotSendRequest(self.__state)
http.client.CannotSendRequest: Request-sent
2020-10-16T01:38:34.463000Z TestFramework.utils (ERROR): wait_until() failed. Predicate: ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
'''


stderr:
Traceback (most recent call last):
  File "/Users/michael/github/fanquake-bitcoin/test/functional/rpc_createmultisig.py", line 221, in <module>
    RpcCreateMultiSigTest().main()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 149, in main
    exit_code = self.shutdown()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 273, in shutdown
    self.stop_nodes()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_framework.py", line 521, in stop_nodes
    node.wait_until_stopped()
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/test_node.py", line 362, in wait_until_stopped
    wait_until_helper(self.is_node_stopped, timeout=timeout, timeout_factor=self.timeout_factor)
  File "/Users/michael/github/fanquake-bitcoin/test/functional/test_framework/util.py", line 261, in wait_until_helper
    raise AssertionError("Predicate {} not true after {} seconds".format(predicate_source, timeout))
AssertionError: Predicate ''''
    def is_node_stopped(self):
        """Checks whether the node has stopped.

        Returns True if the node has stopped. False otherwise.
        This method is responsible for freeing resources (self.process)."""
        if not self.running:
            return True
        return_code = self.process.poll()
        if return_code is None:
            return False

        # process has stopped. Assert that it didn't return an error code.
        assert return_code == 0, self._node_msg(
            "Node returned non-zero exit code (%d) when stopping" % return_code)
        self.running = False
        self.process = None
        self.rpc_connected = False
        self.rpc = None
        self.log.debug("Node stopped")
        return True
''' not true after 60.0 seconds


124/172 - wallet_encryption.py passed, Duration: 8 s
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment