Bitcoin-PR-15419-error.log

Temporary test directory at /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905
WARNING! There is already a bitcoind process running on this system. Tests may fail unexpectedly due to resource contention!
1/117 - wallet_hd.py passed, Duration: 16 s
2/117 - mining_getblocktemplate_longpoll.py passed, Duration: 70 s
3/117 - feature_maxuploadtarget.py passed, Duration: 64 s
4/117 - rpc_fundrawtransaction.py passed, Duration: 65 s
5/117 - wallet_backup.py passed, Duration: 158 s
6/117 - feature_segwit.py passed, Duration: 30 s
7/117 - p2p_compactblocks.py passed, Duration: 52 s
8/117 - wallet_labels.py passed, Duration: 13 s
9/117 - feature_block.py passed, Duration: 145 s
10/117 - p2p_timeouts.py passed, Duration: 6 s
11/117 - wallet_dump.py passed, Duration: 21 s
12/117 - wallet_basic.py passed, Duration: 60 s
13/117 - p2p_sendheaders.py passed, Duration: 16 s
14/117 - wallet_zapwallettxes.py passed, Duration: 8 s
15/117 - wallet_listtransactions.py passed, Duration: 33 s
16/117 - mempool_limit.py passed, Duration: 8 s
17/117 - rpc_txoutproof.py passed, Duration: 7 s
18/117 - wallet_importmulti.py passed, Duration: 23 s
19/117 - p2p_segwit.py passed, Duration: 84 s
20/117 - wallet_abandonconflict.py passed, Duration: 11 s
21/117 - wallet_listreceivedby.py passed, Duration: 18 s
22/117 - feature_csv_activation.py passed, Duration: 19 s
23/117 - rpc_rawtransaction.py passed, Duration: 33 s
24/117 - feature_bip68_sequence.py passed, Duration: 28 s
25/117 - feature_reindex.py passed, Duration: 6 s
26/117 - p2p_feefilter.py passed, Duration: 53 s
27/117 - interface_zmq.py skipped
28/117 - interface_bitcoin_cli.py passed, Duration: 4 s
29/117 - mempool_resurrect.py passed, Duration: 4 s
30/117 - feature_fee_estimation.py passed, Duration: 410 s
31/117 - tool_wallet.py failed, Duration: 2 s

stdout:
2019-02-19T16:45:58.940000Z TestFramework (INFO): Initializing test directory /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/tool_wallet_83
2019-02-19T16:46:00.459000Z TestFramework (ERROR): Unexpected exception caught during testing
Traceback (most recent call last):
  File "/Volumes/SSD/src/bitcoin/test/functional/test_framework/test_framework.py", line 175, in main
    self.run_test()
  File "/Users/bitcoin/dev/bitcoin/test/functional/tool_wallet.py", line 41, in run_test
    self.assert_raises_tool_error('Invalid command: foo', 'foo')
  File "/Users/bitcoin/dev/bitcoin/test/functional/tool_wallet.py", line 26, in assert_raises_tool_error
    p = self.bitcoin_wallet_process(*args)
  File "/Users/bitcoin/dev/bitcoin/test/functional/tool_wallet.py", line 23, in bitcoin_wallet_process
    return subprocess.Popen([binary] + args, stdin=subprocess.PIPE, stdout=subprocess.PIPE, stderr=subprocess.PIPE, universal_newlines=True)
  File "/Users/bitcoin/.pyenv/versions/3.4.9/lib/python3.4/subprocess.py", line 856, in __init__
    restore_signals, start_new_session)
  File "/Users/bitcoin/.pyenv/versions/3.4.9/lib/python3.4/subprocess.py", line 1464, in _execute_child
    raise child_exception_type(errno_num, err_msg)
FileNotFoundError: [Errno 2] No such file or directory: '/Users/bitcoin/dev/bitcoin/src/bitcoin-wallet'
2019-02-19T16:46:00.522000Z TestFramework (INFO): Stopping nodes
2019-02-19T16:46:01.148000Z TestFramework (WARNING): Not cleaning up dir /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/tool_wallet_83
2019-02-19T16:46:01.151000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/tool_wallet_83/test_framework.log
2019-02-19T16:46:01.151000Z TestFramework (ERROR): Hint: Call /Volumes/SSD/src/bitcoin/test/functional/combine_logs.py '/var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/tool_wallet_83' to consolidate all logs


stderr:


32/117 - wallet_txn_doublespend.py --mineblock passed, Duration: 15 s
33/117 - wallet_address_types.py passed, Duration: 112 s
34/117 - wallet_keypool_topup.py passed, Duration: 72 s
35/117 - wallet_txn_clone.py failed, Duration: 11 s

stdout:
2019-02-19T16:46:01.474000Z TestFramework (INFO): Initializing test directory /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_82
2019-02-19T16:46:10.943000Z TestFramework (ERROR): Unexpected exception caught during testing
Traceback (most recent call last):
  File "/Volumes/SSD/src/bitcoin/test/functional/test_framework/test_framework.py", line 175, in main
    self.run_test()
  File "/Users/bitcoin/dev/bitcoin/test/functional/wallet_txn_clone.py", line 84, in run_test
    tx1_clone = self.nodes[0].signrawtransactionwithwallet(clone_tx.serialize().hex(), None, "ALL|ANYONECANPAY")
AttributeError: 'bytes' object has no attribute 'hex'
2019-02-19T16:46:11.009000Z TestFramework (INFO): Stopping nodes
2019-02-19T16:46:12.128000Z TestFramework (WARNING): Not cleaning up dir /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_82
2019-02-19T16:46:12.133000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_82/test_framework.log
2019-02-19T16:46:12.140000Z TestFramework (ERROR): Hint: Call /Volumes/SSD/src/bitcoin/test/functional/combine_logs.py '/var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_82' to consolidate all logs


stderr:


36/117 - mempool_spend_coinbase.py passed, Duration: 4 s
37/117 - wallet_txn_clone.py --segwit failed, Duration: 11 s

stdout:
2019-02-19T16:46:07.501000Z TestFramework (INFO): Initializing test directory /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_81
2019-02-19T16:46:17.783000Z TestFramework (ERROR): Unexpected exception caught during testing
Traceback (most recent call last):
  File "/Volumes/SSD/src/bitcoin/test/functional/test_framework/test_framework.py", line 175, in main
    self.run_test()
  File "/Users/bitcoin/dev/bitcoin/test/functional/wallet_txn_clone.py", line 84, in run_test
    tx1_clone = self.nodes[0].signrawtransactionwithwallet(clone_tx.serialize().hex(), None, "ALL|ANYONECANPAY")
AttributeError: 'bytes' object has no attribute 'hex'
2019-02-19T16:46:17.836000Z TestFramework (INFO): Stopping nodes
2019-02-19T16:46:18.690000Z TestFramework (WARNING): Not cleaning up dir /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_81
2019-02-19T16:46:18.690000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_81/test_framework.log
2019-02-19T16:46:18.691000Z TestFramework (ERROR): Hint: Call /Volumes/SSD/src/bitcoin/test/functional/combine_logs.py '/var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_81' to consolidate all logs


stderr:


38/117 - rpc_getchaintips.py passed, Duration: 11 s
39/117 - mempool_reorg.py passed, Duration: 9 s
40/117 - interface_rest.py passed, Duration: 23 s
41/117 - mempool_persist.py passed, Duration: 17 s
42/117 - wallet_createwallet.py passed, Duration: 8 s
43/117 - wallet_createwallet.py --usecli passed, Duration: 10 s
44/117 - interface_http.py passed, Duration: 3 s
45/117 - interface_rpc.py passed, Duration: 2 s
46/117 - rpc_users.py passed, Duration: 5 s
47/117 - feature_proxy.py passed, Duration: 2 s
48/117 - rpc_signrawtransaction.py passed, Duration: 3 s
49/117 - rpc_psbt.py passed, Duration: 34 s
50/117 - wallet_groups.py passed, Duration: 22 s
51/117 - rpc_decodescript.py passed, Duration: 2 s
52/117 - p2p_disconnect_ban.py passed, Duration: 6 s
53/117 - rpc_deprecated.py passed, Duration: 3 s
54/117 - wallet_disable.py passed, Duration: 1 s
55/117 - wallet_multiwallet.py passed, Duration: 73 s
56/117 - rpc_net.py passed, Duration: 4 s
57/117 - p2p_mempool.py passed, Duration: 2 s
58/117 - wallet_multiwallet.py --usecli passed, Duration: 77 s
59/117 - rpc_blockchain.py passed, Duration: 19 s
60/117 - p2p_invalid_block.py passed, Duration: 5 s
61/117 - p2p_invalid_locator.py passed, Duration: 6 s
62/117 - wallet_keypool.py passed, Duration: 14 s
63/117 - p2p_invalid_tx.py passed, Duration: 3 s
64/117 - mining_prioritisetransaction.py passed, Duration: 16 s
65/117 - p2p_invalid_messages.py failed, Duration: 8 s

stdout:
2019-02-19T16:47:49.790000Z TestFramework (INFO): Initializing test directory /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/p2p_invalid_messages_53
2019-02-19T16:47:51.549000Z TestFramework (INFO): Sending a bunch of large, junk messages to test memory exhaustion. May take a bit...
2019-02-19T16:47:56.031000Z TestFramework (INFO): Waiting for node to drop junk messages.
2019-02-19T16:47:56.125000Z TestFramework.mininode (WARNING): Connection lost to 127.0.0.1:11424 due to [Errno 41] Protocol wrong type for socket
2019-02-19T16:47:56.224000Z TestFramework (INFO): Sending a message with incorrect size of 2
2019-02-19T16:47:56.380000Z TestFramework (INFO): Sending a message with incorrect size of 77
2019-02-19T16:47:56.532000Z TestFramework (INFO): Sending a message with incorrect size of 78
2019-02-19T16:47:56.687000Z TestFramework (INFO): Sending a message with incorrect size of 79
2019-02-19T16:47:57.024000Z TestFramework (INFO): Stopping nodes
2019-02-19T16:47:57.881000Z TestFramework (INFO): Cleaning up /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/p2p_invalid_messages_53 on exit
2019-02-19T16:47:57.887000Z TestFramework (INFO): Tests successful


stderr:
Fatal write error on socket transport
protocol: <test_framework.mininode.P2PDataStore object at 0x1026b60f0>
transport: <_SelectorSocketTransport fd=10 read=polling write=<idle, bufsize=0>>
Traceback (most recent call last):
  File "/Users/bitcoin/.pyenv/versions/3.4.9/lib/python3.4/asyncio/selector_events.py", line 701, in write
    n = self._sock.send(data)
OSError: [Errno 41] Protocol wrong type for socket


66/117 - example_test.py passed, Duration: 5 s
67/117 - wallet_txn_doublespend.py passed, Duration: 9 s
68/117 - wallet_txn_clone.py --mineblock failed, Duration: 9 s

stdout:
2019-02-19T16:47:58.384000Z TestFramework (INFO): Initializing test directory /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_48
2019-02-19T16:48:06.401000Z TestFramework (ERROR): Unexpected exception caught during testing
Traceback (most recent call last):
  File "/Volumes/SSD/src/bitcoin/test/functional/test_framework/test_framework.py", line 175, in main
    self.run_test()
  File "/Users/bitcoin/dev/bitcoin/test/functional/wallet_txn_clone.py", line 84, in run_test
    tx1_clone = self.nodes[0].signrawtransactionwithwallet(clone_tx.serialize().hex(), None, "ALL|ANYONECANPAY")
AttributeError: 'bytes' object has no attribute 'hex'
2019-02-19T16:48:06.463000Z TestFramework (INFO): Stopping nodes
2019-02-19T16:48:07.339000Z TestFramework (WARNING): Not cleaning up dir /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_48
2019-02-19T16:48:07.345000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_48/test_framework.log
2019-02-19T16:48:07.369000Z TestFramework (ERROR): Hint: Call /Volumes/SSD/src/bitcoin/test/functional/combine_logs.py '/var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/wallet_txn_clone_48' to consolidate all logs


stderr:


69/117 - feature_notifications.py passed, Duration: 9 s
70/117 - rpc_invalidateblock.py passed, Duration: 4 s
71/117 - feature_rbf.py passed, Duration: 21 s
72/117 - rpc_createmultisig.py passed, Duration: 31 s
73/117 - feature_versionbits_warning.py passed, Duration: 14 s
74/117 - rpc_preciousblock.py passed, Duration: 6 s
75/117 - p2p_leak_tx.py passed, Duration: 4 s
76/117 - wallet_importprunedfunds.py passed, Duration: 9 s
77/117 - rpc_signmessage.py passed, Duration: 3 s
78/117 - wallet_balance.py passed, Duration: 10 s
79/117 - feature_nulldummy.py passed, Duration: 10 s
80/117 - mempool_accept.py passed, Duration: 8 s
81/117 - feature_assumevalid.py passed, Duration: 83 s
82/117 - rpc_bind.py --ipv4 skipped
83/117 - rpc_bind.py --ipv6 skipped
84/117 - rpc_bind.py --nonloopback skipped
85/117 - wallet_import_with_label.py passed, Duration: 8 s
86/117 - mempool_packages.py passed, Duration: 76 s
87/117 - rpc_named_arguments.py passed, Duration: 3 s
88/117 - mining_basic.py failed, Duration: 15 s

stdout:
2019-02-19T16:49:16.101000Z TestFramework (INFO): Initializing test directory /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/mining_basic_29
2019-02-19T16:49:19.184000Z TestFramework (INFO): Create some old blocks
2019-02-19T16:49:28.564000Z TestFramework (INFO): getmininginfo
2019-02-19T16:49:28.587000Z TestFramework (INFO): getblocktemplate: Test capability advertised
2019-02-19T16:49:28.588000Z TestFramework (INFO): getblocktemplate: segwit rule must be set
2019-02-19T16:49:28.603000Z TestFramework (INFO): getblocktemplate: Test valid block
2019-02-19T16:49:28.625000Z TestFramework (INFO): submitblock: Test block decode failure
2019-02-19T16:49:28.637000Z TestFramework (INFO): getblocktemplate: Test bad input hash for coinbase transaction
2019-02-19T16:49:28.645000Z TestFramework (INFO): submitblock: Test invalid coinbase transaction
2019-02-19T16:49:28.649000Z TestFramework (INFO): getblocktemplate: Test truncated final transaction
2019-02-19T16:49:28.649000Z TestFramework (INFO): getblocktemplate: Test duplicate transaction
2019-02-19T16:49:28.706000Z TestFramework (INFO): getblocktemplate: Test invalid transaction
2019-02-19T16:49:28.751000Z TestFramework (INFO): getblocktemplate: Test nonfinal transaction
2019-02-19T16:49:28.801000Z TestFramework (INFO): getblocktemplate: Test bad tx count
2019-02-19T16:49:28.816000Z TestFramework (INFO): getblocktemplate: Test bad bits
2019-02-19T16:49:28.843000Z TestFramework (INFO): getblocktemplate: Test bad merkle root
2019-02-19T16:49:28.884000Z TestFramework (INFO): getblocktemplate: Test bad timestamps
2019-02-19T16:49:29.021000Z TestFramework (INFO): getblocktemplate: Test not best block
2019-02-19T16:49:29.110000Z TestFramework (INFO): submitheader tests
2019-02-19T16:49:29.138000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
  File "/Volumes/SSD/src/bitcoin/test/functional/test_framework/util.py", line 113, in try_rpc
    fun(*args, **kwds)
  File "/Users/bitcoin/dev/bitcoin/test/functional/mining_basic.py", line 187, in <lambda>
    assert_raises_rpc_error(-25, 'Must submit previous header', lambda: node.submitheader(hexdata=super(CBlock, bad_block).serialize().hex()))
AttributeError: 'bytes' object has no attribute 'hex'

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/Volumes/SSD/src/bitcoin/test/functional/test_framework/test_framework.py", line 175, in main
    self.run_test()
  File "/Users/bitcoin/dev/bitcoin/test/functional/mining_basic.py", line 187, in run_test
    assert_raises_rpc_error(-25, 'Must submit previous header', lambda: node.submitheader(hexdata=super(CBlock, bad_block).serialize().hex()))
  File "/Volumes/SSD/src/bitcoin/test/functional/test_framework/util.py", line 105, in assert_raises_rpc_error
    assert try_rpc(code, message, fun, *args, **kwds), "No exception raised"
  File "/Volumes/SSD/src/bitcoin/test/functional/test_framework/util.py", line 122, in try_rpc
    raise AssertionError("Unexpected exception raised: " + type(e).__name__)
AssertionError: Unexpected exception raised: AttributeError
2019-02-19T16:49:29.194000Z TestFramework (INFO): Stopping nodes
2019-02-19T16:49:30.449000Z TestFramework (WARNING): Not cleaning up dir /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/mining_basic_29
2019-02-19T16:49:30.449000Z TestFramework (ERROR): Test failed. Test logging available at /var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/mining_basic_29/test_framework.log
2019-02-19T16:49:30.464000Z TestFramework (ERROR): Hint: Call /Volumes/SSD/src/bitcoin/test/functional/combine_logs.py '/var/folders/lv/01bvgc7x1fz03w1t8v7g7mrw0000gp/T/test_runner_₿_🏃_20190219_173905/mining_basic_29' to consolidate all logs


stderr:


89/117 - p2p_leak.py passed, Duration: 8 s
90/117 - wallet_listsinceblock.py passed, Duration: 16 s
91/117 - wallet_encryption.py passed, Duration: 7 s
92/117 - wallet_bumpfee.py passed, Duration: 30 s
93/117 - rpc_uptime.py passed, Duration: 2 s
94/117 - wallet_import_rescan.py passed, Duration: 47 s
95/117 - wallet_resendwallettransactions.py passed, Duration: 3 s
96/117 - wallet_fallbackfee.py passed, Duration: 4 s
97/117 - rpc_getblockstats.py passed, Duration: 3 s
98/117 - wallet_create_tx.py passed, Duration: 2 s
99/117 - feature_dersig.py passed, Duration: 19 s
100/117 - feature_minchainwork.py passed, Duration: 8 s
101/117 - feature_cltv.py passed, Duration: 20 s
102/117 - wallet_coinbase_category.py passed, Duration: 3 s
103/117 - feature_uacomment.py passed, Duration: 4 s
104/117 - feature_filelock.py passed, Duration: 2 s
105/117 - rpc_deriveaddresses.py passed, Duration: 2 s
106/117 - p2p_fingerprint.py passed, Duration: 8 s
107/117 - feature_includeconf.py passed, Duration: 4 s
108/117 - rpc_deriveaddresses.py --usecli passed, Duration: 3 s
109/117 - p2p_unrequested_blocks.py passed, Duration: 10 s
110/117 - feature_blocksdir.py passed, Duration: 3 s
111/117 - feature_logging.py passed, Duration: 9 s
112/117 - rpc_help.py passed, Duration: 2 s
113/117 - feature_help.py passed, Duration: 0 s
114/117 - feature_config_args.py passed, Duration: 4 s
115/117 - rpc_scantxoutset.py passed, Duration: 15 s
116/117 - feature_shutdown.py passed, Duration: 3 s
117/117 - p2p_node_network_limited.py passed, Duration: 15 s