develop-e42cc61

name-pre-reg-up-sign-verify-data

[2017-10-26 16:52:30,596] [DEBUG] [spv:110] (1.140587694610176) Using testnet/regtest
/usr/local/lib/python2.7/dist-packages/urllib3/connectionpool.py:858: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[2017-10-26 16:52:30,795] [DEBUG] [blockstack-test-scenario:330] (1.140587694610176) Load scenario blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data
[2017-10-26 16:52:30,795] [DEBUG] [blockstack-test-scenario:337] (1.140587694610176) Identifying scenario path
[2017-10-26 16:52:32,006] [DEBUG] [blockstack-test-scenario:349] (1.140587694610176) Path of blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data is blockstack_integration_tests/scenarios/name_pre_reg_up_sign_verify_data.py
[2017-10-26 16:52:32,076] [WARNING] [config:91] (1.140587694610176) (1): in test environment
[2017-10-26 16:52:32,076] [WARNING] [config:112] (1.140587694610176) NUM_CONFIRMATIONS = 0
[2017-10-26 16:52:32,226] [DEBUG] [testlib:83] (1.140587694610176) Wallet 6e50431b955fe73f079469b24f06480aee44e4519282686433195b3c4b5336ef01 (mr6nrMvvh44sR5MiX929mMXP5hqgaTr6fx)
[2017-10-26 16:52:32,226] [DEBUG] [testlib:88] (1.140587694610176) BLOCKSTACK_SERIALIZATION_CHECK_WALLET: {"public_key": "03d6fd1ba0effaf1e8d94ea7b7a3d0ef26fea00a14ce5ffcc1495fe588a2c6d0f3", "type": "singlesig"}
[2017-10-26 16:52:32,361] [DEBUG] [testlib:83] (1.140587694610176) Wallet c244642ce0b4eb68da8e098facfcad889e3063c36a68b7951fb4c085de49df1b01 (msNXCSWz2pyDnpBpUb8As6EhXU5cR3UhPn)
[2017-10-26 16:52:32,361] [DEBUG] [testlib:88] (1.140587694610176) BLOCKSTACK_SERIALIZATION_CHECK_WALLET: {"public_key": "0297cb0fccbbcf48b2b9516920ca8d973cd81ce032ef1abc0e627c7fd0e4dcee58", "type": "singlesig"}
[2017-10-26 16:52:32,498] [DEBUG] [testlib:83] (1.140587694610176) Wallet f4c3907cb5769c28ff603c145db7fc39d7d26f69f726f8a7f995a40d3897bb5201 (mvF2KY1UbdopoomiB371epM99GTnzjSUfj)
[2017-10-26 16:52:32,498] [DEBUG] [testlib:88] (1.140587694610176) BLOCKSTACK_SERIALIZATION_CHECK_WALLET: {"public_key": "036a6582a6566aa4059b7361536e7e4ac3df4d77bf6e843c4c8207eaa12e0ca19e", "type": "singlesig"}
[2017-10-26 16:52:32,643] [DEBUG] [testlib:83] (1.140587694610176) Wallet 8f87d1ea26d03259371675ea3bd31231b67c5df0012c205c154764a124f5b8fe01 (myaPViveUWiiZQQTb51KXCDde4iLC3Rf3K)
[2017-10-26 16:52:32,643] [DEBUG] [testlib:88] (1.140587694610176) BLOCKSTACK_SERIALIZATION_CHECK_WALLET: {"public_key": "02bef7a01263d130b21117973503ae7bdeec123202c3dfaa3bf56728ff054abcd0", "type": "singlesig"}
[2017-10-26 16:52:32,789] [DEBUG] [testlib:83] (1.140587694610176) Wallet bb68eda988e768132bc6c7ca73a87fb9b0918e9a38d3618b74099be25f7cab7d01 (mr1XvuxPtCvv4GCR3W1SoVTQtMGhxZFr3t)
[2017-10-26 16:52:32,789] [DEBUG] [testlib:88] (1.140587694610176) BLOCKSTACK_SERIALIZATION_CHECK_WALLET: {"public_key": "03ea5d8c2a3ba84eb17625162320bb53440557c71f7977a57d61405e86be7bdcda", "type": "singlesig"}
[2017-10-26 16:52:32,928] [DEBUG] [testlib:83] (1.140587694610176) Wallet 2373c61a5414ce8a81cd12f6caaf1d56ffb5e1edb7f0b9f2e8699e26abbd09c301 (mxDynMQFypC5TaekMV5hRaPf1mfyEkcEHj)
[2017-10-26 16:52:32,928] [DEBUG] [testlib:88] (1.140587694610176) BLOCKSTACK_SERIALIZATION_CHECK_WALLET: {"public_key": "029b133f64fbabe7caf65575c5b19721dac8a49a809154b0f9a0fe2cbb8b196992", "type": "singlesig"}
[2017-10-26 16:52:32,963] [DEBUG] [blockstack-test-scenario:890] (1.140587694610176) Starting up bitcoind in regtest mode
[2017-10-26 16:52:32,982] [DEBUG] [blockstack-test-scenario:897] (1.140587694610176) Waiting for bitcoind to start up
[2017-10-26 16:52:33,984] [DEBUG] [session:104] (1.140587694610176) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:34,988] [DEBUG] [session:104] (1.140587694610176) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:35,993] [DEBUG] [session:104] (1.140587694610176) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:35,995] [DEBUG] [session:104] (1.140587694610176) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:36,920] [DEBUG] [blockstack-test-scenario:927] (1.140587694610176) bitcoind -regtest is ready
[2017-10-26 16:52:37,335] [DEBUG] [testlib:109] (1.140587694610176) Multisig wallet 2MxMQEYf7bXPT2s8fLbYCLKxfXv9kL5x9BL
[2017-10-26 16:52:37,335] [DEBUG] [session:104] (1.140587694610176) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:37,472] [DEBUG] [blockstack-test-scenario:944] (1.140587694610176) Fill mr6nrMvvh44sR5MiX929mMXP5hqgaTr6fx (cRH8tAZw6gSbewktG2Ajeq7KwFQ29Q7yADrfwBMxYHnMUvTxxNx8) with 50
[2017-10-26 16:52:38,114] [DEBUG] [blockstack-test-scenario:944] (1.140587694610176) Fill msNXCSWz2pyDnpBpUb8As6EhXU5cR3UhPn (cU6LBm2hSSpFbwE6tZBvDBEghgfjzdG2oHr5K2urLqpTaAy8hBgt) with 50
[2017-10-26 16:52:38,753] [DEBUG] [blockstack-test-scenario:944] (1.140587694610176) Fill mvF2KY1UbdopoomiB371epM99GTnzjSUfj (cVnVR3ehEmu4vxqG1va99YF48JFpcKCsXLKbjbmG7jvYcDyZk3EG) with 50
[2017-10-26 16:52:39,352] [DEBUG] [blockstack-test-scenario:944] (1.140587694610176) Fill myaPViveUWiiZQQTb51KXCDde4iLC3Rf3K (cSPhv8wqPLN7o69jJ2pJ3nYDxXTAo7WmzmUiz9ZTTc66VtUEcmuM) with 50
[2017-10-26 16:52:39,882] [DEBUG] [blockstack-test-scenario:944] (1.140587694610176) Fill mr1XvuxPtCvv4GCR3W1SoVTQtMGhxZFr3t (cTs14pEWitbXXQF7qN4jRvJGwgeEU4FCcJNTwXYdSngBYkmCkBpi) with 50
[2017-10-26 16:52:40,448] [DEBUG] [blockstack-test-scenario:944] (1.140587694610176) Fill mxDynMQFypC5TaekMV5hRaPf1mfyEkcEHj (cNmch2uK98tmW8NyV7wgvBZBmdQCqgSQ4UD5TnmdMQz9UuFFsBt2) with 50
[2017-10-26 16:52:42,522] [DEBUG] [blockstack-test-scenario:1002] (1.140587694610176) Fill 2MxMQEYf7bXPT2s8fLbYCLKxfXv9kL5x9BL with 250

Address mr6nrMvvh44sR5MiX929mMXP5hqgaTr6fx loaded with 5000000000 satoshis
Address msNXCSWz2pyDnpBpUb8As6EhXU5cR3UhPn loaded with 5000000000 satoshis
Address mvF2KY1UbdopoomiB371epM99GTnzjSUfj loaded with 5000000000 satoshis
Address myaPViveUWiiZQQTb51KXCDde4iLC3Rf3K loaded with 5000000000 satoshis
Address mr1XvuxPtCvv4GCR3W1SoVTQtMGhxZFr3t loaded with 5000000000 satoshis
Address mxDynMQFypC5TaekMV5hRaPf1mfyEkcEHj loaded with 5000000000 satoshis
Address 2MxMQEYf7bXPT2s8fLbYCLKxfXv9kL5x9BL loaded with 25000000000 satoshis

[2017-10-26 16:52:43,162] [DEBUG] [blockstack-test-scenario:516] (1.140587694610176) atexit_cleanup
[2017-10-26 16:52:43,163] [DEBUG] [config:1270] (1.140587694610176) Load config from '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.ini'
[2017-10-26 16:52:43,180] [DEBUG] [config:938] (1.140587694610176) Change blockstack-client.client_version to 0.17.0.10
[2017-10-26 16:52:43,180] [DEBUG] [config:909] (1.140587694610176) Add new field subdomain-resolution.subdomains_db
Saving configuration to /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/client/client.ini
Saving configuration to /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.ini
[2017-10-26 16:52:43,195] [DEBUG] [namedb:90] (1.140587694610176) Initialize database from '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.db'
[2017-10-26 16:52:43,991] [DEBUG] [indexer:191] (1.140587694610176) consensus snapshots at '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.snapshots'
[2017-10-26 16:52:43,992] [DEBUG] [indexer:221] (1.140587694610176) lastblock at '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock'
[2017-10-26 16:52:43,992] [DEBUG] [indexer:223] (1.140587694610176) Store lastblock 687 to /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock
[2017-10-26 16:52:43,997] [DEBUG] [atlas:1269] (1.140587694610176) Initializing Atlas DB at /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/atlas.db
[2017-10-26 16:52:44,203] [DEBUG] [atlas:1280] (1.140587694610176) Queuing all zonefiles
[2017-10-26 16:52:44,203] [DEBUG] [atlas:933] (1.140587694610176) Queued 0 zonefiles from 688-687
[2017-10-26 16:52:44,203] [DEBUG] [atlas:1283] (1.140587694610176) Adding seed peers
[2017-10-26 16:52:44,204] [DEBUG] [atlas:1290] (1.140587694610176) peer_table: []
[2017-10-26 16:52:44,204] [DEBUG] [session:104] (1.140587694610176) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:44,204] [DEBUG] [blockstackd:2126] (1.140587694610176) Optimistic GC thread start
[2017-10-26 16:52:44,214] [DEBUG] [atlas:1240] (1.140587694610176) Atlas DB exists at /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/atlas.db
[2017-10-26 16:52:44,215] [DEBUG] [atlas:1247] (1.140587694610176) Synchronize zonefiles from 688 to 687
[2017-10-26 16:52:44,215] [DEBUG] [atlas:933] (1.140587694610176) Queued 0 zonefiles from 688-687
[2017-10-26 16:52:44,215] [DEBUG] [atlas:1251] (1.140587694610176) Refreshing seed peers
[2017-10-26 16:52:44,215] [DEBUG] [atlas:1260] (1.140587694610176) Loading peer table
[2017-10-26 16:52:44,216] [DEBUG] [atlas:1290] (1.140587694610176) peer_table: []
[2017-10-26 16:52:44,216] [DEBUG] [atlas:3591] (1.140587694610176) Starting Atlas component 'health_checker'
[2017-10-26 16:52:44,216] [DEBUG] [atlas:3591] (1.140587694610176) Starting Atlas component 'peer_crawler'
[2017-10-26 16:52:44,217] [DEBUG] [atlas:2996] (1.140587582494464) localhost:16264: max neighbors is 80
[2017-10-26 16:52:44,217] [DEBUG] [atlas:3591] (1.140587694610176) Starting Atlas component 'zonefile_crawler'
[2017-10-26 16:52:44,218] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-26 16:52:44,219] [DEBUG] [blockstackd:2091] (1.140587694610176) Starting RPC
[2017-10-26 16:52:44,219] [INFO] [blockstackd:425] (1.140587357431552) Listening on 0.0.0.0:16264
[2017-10-26 16:52:44,220] [DEBUG] [blockstackd:2150] (1.140587694610176) Starting storage pusher
[2017-10-26 16:52:44,221] [DEBUG] [session:104] (1.140587340646144) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:44,225] [DEBUG] [testlib:160] (1.140587694610176) Connect to Blockstack node at localhost:16264
[2017-10-26 16:52:44,226] [DEBUG] [blockstackd:306] (1.140587357431552) Message is small enough to parse (101 bytes)
[2017-10-26 16:52:44,226] [DEBUG] [blockstackd:397] (1.140587357431552) Inbound RPC begin rpc_getinfo(())
[2017-10-26 16:52:44,227] [DEBUG] [session:104] (1.140587357431552) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=300.0
[2017-10-26 16:52:44,307] [DEBUG] [namedb:92] (1.140587357431552) Connect to database '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.db'
[2017-10-26 16:52:44,308] [DEBUG] [indexer:175] (1.140587357431552) consensus snapshots at '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.snapshots'
[2017-10-26 16:52:44,308] [DEBUG] [indexer:210] (1.140587357431552) lastblock at '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock'
[2017-10-26 16:52:44,308] [DEBUG] [indexer:213] (1.140587357431552) Lastblock: 687 (/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock)
[2017-10-26 16:52:44,312] [DEBUG] [blockstackd:407] (1.140587357431552) Inbound RPC end rpc_getinfo(())
127.0.0.1 - - [26/Oct/2017 16:52:44] "POST /RPC2 HTTP/1.0" 200 -
[2017-10-26 16:52:44,314] [DEBUG] [session:104] (1.140587694610176) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:44,457] [DEBUG] [blockstack-test-scenario:1064] (1.140587694610176) next block (now at 688)
[2017-10-26 16:52:44,457] [DEBUG] [session:104] (1.140587694610176) [1] Connect to bitcoind at http://blockstack@localhost:18332, timeout=60.0
[2017-10-26 16:52:44,459] [DEBUG] [namedb:92] (1.140587694610176) Connect to database '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.db'
[2017-10-26 16:52:44,538] [DEBUG] [indexer:175] (1.140587694610176) consensus snapshots at '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.snapshots'
[2017-10-26 16:52:44,538] [DEBUG] [indexer:210] (1.140587694610176) lastblock at '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock'
[2017-10-26 16:52:44,539] [DEBUG] [indexer:213] (1.140587694610176) Lastblock: 687 (/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock)
[2017-10-26 16:52:44,543] [DEBUG] [blockstack-test-scenario:545] (1.140587694610176) sync virtualchain up to 688
[2017-10-26 16:52:44,543] [DEBUG] [indexer:1125] (1.140587694610176) Sync virtualchain state from 688 to 689
[2017-10-26 16:52:44,543] [DEBUG] [transactions:75] (1.140587694610176) Will download SPV headers to /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/spv_headers.dat
[2017-10-26 16:52:44,543] [DEBUG] [spv:744] (1.140587694610176) Synchronize testnet 0 to 688
[2017-10-26 16:52:44,543] [DEBUG] [spv:767] (1.140587694610176) connect to localhost:18444
[2017-10-26 16:52:44,544] [DEBUG] [spv:368] (1.140587694610176) handshake (version 60002)
[2017-10-26 16:52:44,544] [DEBUG] [spv:371] (1.140587694610176) send Version
[2017-10-26 16:52:44,544] [DEBUG] [spv:384] (1.140587694610176) handle version
[2017-10-26 16:52:44,545] [DEBUG] [spv:386] (1.140587694610176) send VerAck
[2017-10-26 16:52:44,545] [DEBUG] [spv:358] (1.140587694610176) send getheaders
[2017-10-26 16:52:44,546] [DEBUG] [spv:403] (1.140587694610176) handle ping
[2017-10-26 16:52:44,546] [DEBUG] [spv:406] (1.140587694610176) send pong
[2017-10-26 16:52:44,559] [DEBUG] [spv:250] (1.140587694610176) handle headers (688)
[2017-10-26 16:52:44,559] [DEBUG] [spv:272] (1.140587694610176) Receive 688 testnet headers (0 to 687)
[2017-10-26 16:52:44,606] [DEBUG] [spv:785] (1.140587694610176) synced headers from -1 to 688 in /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/spv_headers.dat
[2017-10-26 16:52:44,606] [DEBUG] [blocks:125] (1.140587694610176) BlockDownloader: fetch blocks 688-688
[2017-10-26 16:52:44,606] [DEBUG] [blocks:173] (1.140587694610176) Segwit support: False
[2017-10-26 16:52:44,606] [DEBUG] [blocks:311] (1.140587694610176) handshake (version 60002)
[2017-10-26 16:52:44,607] [DEBUG] [blocks:314] (1.140587694610176) send Version
[2017-10-26 16:52:44,607] [DEBUG] [blocks:327] (1.140587694610176) handle version
[2017-10-26 16:52:44,607] [DEBUG] [blocks:329] (1.140587694610176) send VerAck
[2017-10-26 16:52:44,607] [DEBUG] [blocks:345] (1.140587694610176) send getdata for 688-688 (69856a0282b2bb9af52a1d69da52cff12051d40cb3adcf6620c3b439bfced084-69856a0282b2bb9af52a1d69da52cff12051d40cb3adcf6620c3b439bfced084)
[2017-10-26 16:52:44,808] [DEBUG] [blocks:370] (1.140587694610176) handle ping
[2017-10-26 16:52:44,808] [DEBUG] [blocks:373] (1.140587694610176) send pong
[2017-10-26 16:52:45,009] [DEBUG] [blocks:207] (1.140587694610176) num blocks received = 0, num requested = 1
[2017-10-26 16:52:45,009] [DEBUG] [blocks:577] (1.140587694610176) handle block 688 (69856a0282b2bb9af52a1d69da52cff12051d40cb3adcf6620c3b439bfced084)
[2017-10-26 16:52:45,010] [DEBUG] [blocks:673] (1.140587694610176) Request 1 nulldata sender TXs
[2017-10-26 16:52:45,010] [DEBUG] [blocks:238] (1.140587694610176) Fetch 1 TXs via JSON-RPC (0-1 of 1)
[2017-10-26 16:52:45,013] [DEBUG] [indexer:1065] (1.140587694610176) Process block 688 (0 virtual transactions)
[2017-10-26 16:52:45,013] [DEBUG] [indexer:794] (1.140587694610176) Snapshotting block 688
[2017-10-26 16:52:45,013] [DEBUG] [indexer:715] (1.140587694610176) Snapshot('5df6e0e2761359d30a8275058e299fcc0381534545f55cf43e41983f5d4c9456', [])
[2017-10-26 16:52:45,014] [DEBUG] [namedb:775] (1.140587694610176) Get all ops at 688 in /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.db
[2017-10-26 16:52:45,014] [DEBUG] [db:1861] (1.140587694610176) 0 name-preorder/import states at 688
[2017-10-26 16:52:45,014] [DEBUG] [db:1916] (1.140587694610176) 0 name-change states at 688
[2017-10-26 16:52:45,014] [DEBUG] [db:1962] (1.140587694610176) 0 preorders created at 688
[2017-10-26 16:52:45,015] [DEBUG] [db:2006] (1.140587694610176) 0 namespace-preorder states at 688
[2017-10-26 16:52:45,015] [DEBUG] [db:2054] (1.140587694610176) 0 namespace-change states at 688
[2017-10-26 16:52:45,081] [DEBUG] [namedb:1849] (1.140587694610176) ops hash at 688 is 5df6e0e2761359d30a8275058e299fcc0381534545f55cf43e41983f5d4c9456
[2017-10-26 16:52:45,081] [DEBUG] [virtualchain_hooks:457] (1.140587694610176) Synchronize Atlas DB for 687
[2017-10-26 16:52:45,096] [DEBUG] [namedb:775] (1.140587694610176) Get all ops at 687 in /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.db
[2017-10-26 16:52:45,096] [DEBUG] [db:1861] (1.140587694610176) 0 name-preorder/import states at 687
[2017-10-26 16:52:45,096] [DEBUG] [db:1916] (1.140587694610176) 0 name-change states at 687
[2017-10-26 16:52:45,096] [DEBUG] [db:1962] (1.140587694610176) 0 preorders created at 687
[2017-10-26 16:52:45,097] [DEBUG] [db:2006] (1.140587694610176) 0 namespace-preorder states at 687
[2017-10-26 16:52:45,097] [DEBUG] [db:2054] (1.140587694610176) 0 namespace-change states at 687
[2017-10-26 16:52:45,097] [DEBUG] [atlas:933] (1.140587694610176) Queued 0 zonefiles from 687-687
[2017-10-26 16:52:45,133] [DEBUG] [indexer:376] (1.140587694610176) Rename 'lastblock': /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock.tmp --> /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock
[2017-10-26 16:52:45,257] [DEBUG] [indexer:376] (1.140587694610176) Rename 'snapshots': /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.snapshots.tmp --> /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.snapshots
[2017-10-26 16:52:45,323] [DEBUG] [indexer:1154] (1.140587694610176) CONSENSUS(688): 2a9148d8b13939723d2aca16c75c6d68
[2017-10-26 16:52:45,323] [DEBUG] [indexer:1168] (1.140587694610176) Last block is 688
[2017-10-26 16:52:45,323] [INFO] [virtualchain:69] (1.140587694610176) 0 seconds
[2017-10-26 16:52:45,323] [DEBUG] [namedb:92] (1.140587694610176) Connect to database '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.db'
[2017-10-26 16:52:45,429] [DEBUG] [indexer:175] (1.140587694610176) consensus snapshots at '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.snapshots'
[2017-10-26 16:52:45,429] [DEBUG] [indexer:210] (1.140587694610176) lastblock at '/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock'
[2017-10-26 16:52:45,429] [DEBUG] [indexer:213] (1.140587694610176) Lastblock: 688 (/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.lastblock)
[2017-10-26 16:52:45,434] [DEBUG] [blockstack-test-scenario:551] (1.140587694610176) sync atlas node up to 688
[2017-10-26 16:52:45,434] [DEBUG] [blockstack-test-scenario:556] (1.140587694610176) Synchronize Atlas DB from 688 to 689
[2017-10-26 16:52:45,434] [DEBUG] [namedb:775] (1.140587694610176) Get all ops at 688 in /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.db
[2017-10-26 16:52:45,434] [DEBUG] [db:1861] (1.140587694610176) 0 name-preorder/import states at 688
[2017-10-26 16:52:45,435] [DEBUG] [db:1916] (1.140587694610176) 0 name-change states at 688
[2017-10-26 16:52:45,435] [DEBUG] [db:1962] (1.140587694610176) 0 preorders created at 688
[2017-10-26 16:52:45,435] [DEBUG] [db:2006] (1.140587694610176) 0 namespace-preorder states at 688
[2017-10-26 16:52:45,435] [DEBUG] [db:2054] (1.140587694610176) 0 namespace-change states at 688
[2017-10-26 16:52:45,435] [DEBUG] [atlas:933] (1.140587694610176) Queued 0 zonefiles from 688-688
[2017-10-26 16:52:45,436] [DEBUG] [db:230] (1.140587694610176) /usr/bin/sqlite3 /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/blockstack-server.db .backup "/tmp/blockstack-test-databases-VBhKwM/blockstack.db.688"
TEST ACTIVE: TX_MIN_CONFIRMATIONS = 0
TEST ACTIVE: FIRST_BLOCK_MAINNET = 688
TEST ACTIVE: CONFIG_PATH = /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/client/client.ini

blockstack opts
{
    "atlasdb_path": "/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/atlas.db", 
    "data_servers": "True", 
    "data_storage_drivers": "disk", 
    "serve_zonefiles": true, 
    "zonefile_storage_drivers_write": "disk", 
    "profile_storage_drivers": "disk", 
    "backup_max_age": 30, 
    "serve_profiles": true, 
    "atlas": true, 
    "analytics_key": "abcdef0123456789", 
    "email": "", 
    "atlas_hostname": "localhost", 
    "zonefiles": "/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/zonefiles", 
    "zonefile_storage_drivers": "disk", 
    "serve_data": true, 
    "announcers": "judecn.id,muneeb.id,shea256.id", 
    "server_version": "0.17.0.9", 
    "atlas_seeds": "", 
    "data_storage_drivers_write": "disk", 
    "redirect_data": false, 
    "rpc_port": 16264, 
    "backup_frequency": 1, 
    "profile_storage_drivers_write": "disk", 
    "atlas_blacklist": ""
}

blockchain opts
{
    "bitcoind_passwd": "blockstacksystem", 
    "bitcoind_user": "blockstack", 
    "bitcoind_server": "localhost", 
    "bitcoind_spv_path": "/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/spv_headers.dat", 
    "bitcoind_p2p_port": 18444, 
    "bitcoind_port": 18332, 
    "bitcoind_regtest": true
}

blockchain service opts
{
    "spv_headers_path": "/tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/spv_headers.dat", 
    "rpc_password": "blockstacksystem", 
    "utxo_provider": "bitcoind_utxo", 
    "server": "localhost", 
    "rpc_username": "blockstack", 
    "port": 18332, 
    "blockchain_server": "localhost", 
    "version_byte": 0, 
    "use_https": false, 
    "blockchain_port": 18332
}
blockchain headers: /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data/spv_headers.dat

atlas node initialization


bitcoind connection

working_dir: /tmp/blockstack-run-scenario.blockstack_integration_tests.scenarios.name_pre_reg_up_sign_verify_data

{
    "data_pubkey": "03ea5d8c2a3ba84eb17625162320bb53440557c71f7977a57d61405e86be7bdcda", 
    "data_pubkeys": [
        "03ea5d8c2a3ba84eb17625162320bb53440557c71f7977a57d61405e86be7bdcda"
    ], 
    "enc": "c2NyeXB0ABQAAAAIAAAAAeNMkPQ1M/hyDb3LIKLc0vx/49Dh0TnYSScWdFymuQ5IlCrpgYaLFPABD2sAg0Hgk3CNyOowEhhc/hkbVLwpY09F51brqfTcWT3LvcJOECwaORRhzXPtAdkP9bkRgK+PAlUgMCNel+p0wXWv6sxrrCbsb7hK2DEICuqmW/J1t10qvPI8RoS9hBAQsihYDseRKG+JOeckOhVaQoWoXirN8JVjEh5XghKQGyy76og4E3LoxURsOMtVXwFYSaozMURLLFTonmWMSxHwMr5ln2BP2ANFOv4r6BKLSRu/y/GsU3oj3d6Ugl8ONr5XJtZaeMy8FY/04a2b4qnQF5vBLF06CpjPEAtoceim6ItReFYyOs9dN+cQj9QPZGsx2vyIZSh5LxlH2kvyWWg/7TaF5eIWKMWkBSHDvQc3Oh/rm6PNpK7cOYzSGyiyEKD4Nd8mGwwzBMeKDDRzTSWaAV8cCtJHnZWJhbyqpzrl1svBc7hU+AR1KBrrD0b+", 
    "owner_addresses": [
        "myaPViveUWiiZQQTb51KXCDde4iLC3Rf3K"
    ], 
    "payment_addresses": [
        "mxDynMQFypC5TaekMV5hRaPf1mfyEkcEHj"
    ], 
    "version": "0.17.0"
}


{
    "data_privkey": "bb68eda988e768132bc6c7ca73a87fb9b0918e9a38d3618b74099be25f7cab7d01", 
    "data_pubkey": "04ea5d8c2a3ba84eb17625162320bb53440557c71f7977a57d61405e86be7bdcdab63a7f1eda1e6c1670c64a9f532b9f55458019d9b80fdf41748d06cd7f60d451", 
    "data_pubkeys": [
        "04ea5d8c2a3ba84eb17625162320bb53440557c71f7977a57d61405e86be7bdcdab63a7f1eda1e6c1670c64a9f532b9f55458019d9b80fdf41748d06cd7f60d451"
    ], 
    "owner_addresses": [
        "myaPViveUWiiZQQTb51KXCDde4iLC3Rf3K"
    ], 
    "owner_privkey": "8f87d1ea26d03259371675ea3bd31231b67c5df0012c205c154764a124f5b8fe01", 
    "payment_addresses": [
        "mxDynMQFypC5TaekMV5hRaPf1mfyEkcEHj"
    ], 
    "payment_privkey": "2373c61a5414ce8a81cd12f6caaf1d56ffb5e1edb7f0b9f2e8699e26abbd09c301", 
    "version": "0.17.0"
}


[2017-10-26 16:52:53,753] [INFO] [rpc:5299] (1.140587694610176) API server version 0.17.0 starting...
[2017-10-26 16:52:53,754] [INFO] [rpc:5300] (1.140587694610176) Machine:   x86_64
[2017-10-26 16:52:53,754] [INFO] [rpc:5301] (1.140587694610176) Version:   #120-Ubuntu SMP Tue Sep 19 17:28:18 UTC 2017
[2017-10-26 16:52:53,754] [INFO] [rpc:5302] (1.140587694610176) Platform:  Linux-4.4.0-97-generic-x86_64-with-Ubuntu-16.04-xenial
[2017-10-26 16:52:53,754] [INFO] [rpc:5303] (1.140587694610176) uname:     Linux name-pre-reg-up-sign-verify-data 4.4.0-97-generic #120-Ubuntu SMP Tue Sep 19 17:28:18 UTC 2017 x86_64 x86_64
[2017-10-26 16:52:53,754] [INFO] [rpc:5304] (1.140587694610176) System:    Linux
[2017-10-26 16:52:53,754] [INFO] [rpc:5305] (1.140587694610176) Processor: x86_64
[2017-10-26 16:52:53,754] [INFO] [rpc:5306] (1.140587694610176) pip:
asn1crypto==0.23.0
base58==0.2.5
basicrpc==0.0.2
bitmerchant==0.1.8
blockstack==0.17.0.10
blockstack-integration-tests==0.17.0.9
blockstack-profiles==0.14.1
blockstack-zones==0.14.3
boto==2.48.0
cachetools==2.0.1
certifi==2017.7.27.1
cffi==1.11.2
chardet==3.0.4
cryptography==2.1.2
defusedxml==0.5.0
dropbox==8.4.0
ecdsa==0.13
enum34==1.1.6
functools32==3.2.3.post2
google-api-python-client==1.6.4
httplib2==0.10.3
idna==2.6
influxdb==4.1.1
ipaddress==1.0.18
jsonpatch==1.16
jsonpointer==1.13
jsonschema==2.6.0
jsontokens==0.0.4
keychain==0.14.2.0
keylib==0.1.1
oauth2client==4.1.2
onedrivesdk==1.1.8
protocoin==0.2
pyasn1==0.3.7
pyasn1-modules==0.1.5
pycparser==2.18
pycurl==7.43.0
PyDrive==1.3.1
pyparsing==2.2.0
python-apt==1.1.0b1
python-dateutil==2.6.1
pytz==2017.2
PyYAML==3.12
requests==2.18.4
rsa==3.4.2
scrypt==0.8.0
simplejson==3.11.1
six==1.11.0
uritemplate==3.0.0
urllib3==1.22
utilitybelt==0.2.6
virtualchain==0.17.0.4
warlock==1.3.0
xmlrunner==1.7.7

[2017-10-26 16:52:53,758] [WARNING] [rpc:5341] (1.140587694610176) Not setting signal handlers since we are in test mode
[2017-10-26 16:52:57,424] [DEBUG] [rpc:5360] (1.140587694610176) Running in the background
[2017-10-26 17:52:45,197] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-26 18:52:46,014] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-26 19:52:46,952] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-26 20:52:47,862] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-26 21:52:48,729] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-26 22:52:49,564] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-26 23:52:49,578] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 00:52:50,445] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 01:52:51,343] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 02:52:52,321] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 03:52:53,181] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 04:52:44,766] [DEBUG] [atlas:3471] (1.140587365824256) localhost:16264: Re-trying storage on missing zonefiles
[2017-10-27 04:52:53,969] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 05:52:54,967] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 06:52:55,877] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 07:52:56,826] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 08:52:56,829] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 09:52:56,896] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 10:52:57,406] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 11:52:57,819] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 12:52:58,637] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 13:52:59,491] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 14:53:00,306] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 15:53:01,200] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 16:52:45,611] [DEBUG] [atlas:3471] (1.140587365824256) localhost:16264: Re-trying storage on missing zonefiles
[2017-10-27 16:53:01,516] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers
[2017-10-27 17:53:02,268] [DEBUG] [atlas:2957] (1.140587582494464) localhost:16264: revalidate old peers