Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

test_single_server_payment: Timeout waiting on wallet_pay_service.on_payment.first after 30s #3663

Closed
moodyjon opened this issue Sep 13, 2022 · 2 comments · Fixed by #3668
Closed

test_single_server_payment: Timeout waiting on wallet_pay_service.on_payment.first after 30s #3663

moodyjon opened this issue Sep 13, 2022 · 2 comments · Fixed by #3668
Assignees
@moodyjon
Labels
area: tests

Comments

@moodyjon
Copy link
Contributor

This seems similar to lbryio/hub#98 but happens after a shorter 30s timeout is hit.
The ledger is connected to a newly started SPVNode instead of reconnecting to the same node.

7119
test_single_server_payment (integration.blockchain.test_wallet_server_sessions.TestUsagePayment) ... 2022-09-13 12:43:35,101 - asyncio - WARNING - Executing <Task pending coro=<CommandTestCase.asyncSetUp() running at /home/runner/work/lbry-sdk/lbry-sdk/lbry/testcase.py:415> wait_for=<Future pending cb=[_chain_future.<locals>._call_check_cancel() at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/futures.py:351, <TaskWakeupMethWrapper object at 0x7ffb1589ae50>()] created at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py:395> cb=[_run_until_complete_cb() at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py:157] created at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py:566> took 0.298 seconds
[7120](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7121)
2022-09-13 12:43:35,762 - elasticsearch - WARNING - GET http://localhost:9200/_template/a7348f31bfea42aa871cb79677667b7eclaims [status:404 request:0.003s]
[7121](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7122)
2022-09-13 12:43:36,652 - asyncio - WARNING - Executing <Task pending coro=<CommandTestCase.asyncSetUp() running at /home/runner/work/lbry-sdk/lbry-sdk/lbry/testcase.py:415> wait_for=<_GatheringFuture pending cb=[<TaskWakeupMethWrapper object at 0x7ffb15c0efd0>()] created at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/tasks.py:642> cb=[_run_until_complete_cb() at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py:157] created at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py:566> took 0.233 seconds
[7122](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7123)
2022-09-13 12:43:36,686 - lbry.wallet.network - INFO - 1 possible spv servers to try (1 urls in config)
[7123](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7124)
2022-09-13 12:43:36,689 - lbry.wallet.network - INFO - localhost:50002 has latency of 0.85ms (available: True, height: 200)
[7124](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7125)
2022-09-13 12:43:36,697 - lbry.wallet.network - INFO - Connected to spv server localhost:50002
[7125](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7126)
2022-09-13 12:43:36,698 - lbry.wallet.network - DEBUG - send server.version('0.110.0', '0.110.0') to localhost:50002 (30 timeout)
[7126](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7127)
2022-09-13 12:43:36,698 - lbry.wallet.network - DEBUG - send version request to localhost:50002
[7127](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7128)
2022-09-13 12:43:36,700 - lbry.wallet.network - DEBUG - get spv server features localhost:50002
[7128](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7129)
2022-09-13 12:43:36,700 - lbry.wallet.network - DEBUG - send server.features() to localhost:50002 (30 timeout)
[7129](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7130)
2022-09-13 12:43:36,703 - lbry.wallet.network - DEBUG - response server.features() from localhost:50002 (30 timeout) -> {'hosts': {}, 'pruning': None, 'server_version': '0.107.0', 'protocol_min': '0.54.0', 'protocol_max': '0.199.0', 'genesis_hash': '6e3fcf1299d4ec5d79c3a4c91d624a4acf9e2e173d95a1a0504f677669687556', 'description': '', 'payment_address': '', 'donation_address': '', 'daily_fee': '0', 'hash_function': 'sha256', 'trending_algorithm': 'fast_ar'}
[7130](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7131)
2022-09-13 12:43:36,703 - lbry.wallet.network - DEBUG - discover other hubs localhost:50002
[7131](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7132)
2022-09-13 12:43:36,703 - lbry.wallet.network - DEBUG - send server.peers.subscribe() to localhost:50002 (30 timeout)
[7132](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7133)
2022-09-13 12:43:36,705 - lbry.wallet.network - DEBUG - response server.peers.subscribe() from localhost:50002 (30 timeout) -> []
[7133](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7134)
2022-09-13 12:43:36,705 - lbry.wallet.network - INFO - subscribe to headers localhost:50002
[7134](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7135)
2022-09-13 12:43:36,706 - lbry.wallet.network - DEBUG - send blockchain.headers.subscribe(True,) to localhost:50002 (30 timeout)
[7135](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7136)
2022-09-13 12:43:36,708 - lbry.wallet.network - DEBUG - response blockchain.headers.subscribe(True,) from localhost:50002 (30 timeout) -> {'hex': '0000003007a706a2bffae0ecaaecc50f452ed6c4fc3bca8653b472bc988890f7f96438cc6861540a4217a6a81686cefcd23545d84db8c866ec48e3fa3c8114fe225d0fb60100000000000000000000000000000000000000000000000000000000000000177b2063ffff7f2001000000', 'height': 200}
[7136](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7137)
2022-09-13 12:43:36,708 - lbry.wallet.network - INFO - maintaining connection to spv server localhost:50002
[7137](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7138)
2022-09-13 12:43:36,709 - lbry.wallet.network - WARNING - wait reconnect+keepalive
[7138](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7139)
2022-09-13 12:43:36,710 - lbry.wallet.network - DEBUG - send blockchain.block.headers(0, 2001, 0, False) to localhost:50002 (30 timeout)
[7139](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7140)
2022-09-13 12:43:36,754 - lbry.wallet.network - DEBUG - response blockchain.block.headers(0, 2001, 0, False) from localhost:50002 (30 timeout) -> [...snip...]
[7140](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7141)
2022-09-13 12:43:36,761 - lbry.wallet.network - DEBUG - send blockchain.block.headers(201, 2001, 0, False) to localhost:50002 (30 timeout)
[7141](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7142)
2022-09-13 12:43:36,764 - lbry.wallet.network - DEBUG - response blockchain.block.headers(201, 2001, 0, False) from localhost:50002 (30 timeout) -> {'hex': '', 'count': 0, 'max': 40960}
[7142](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7143)
2022-09-13 12:43:36,772 - lbry.wallet.network - DEBUG - send blockchain.address.subscribe('mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz', 'n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX', 'n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA', 'n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC', 'mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb', 'myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX', 'mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5', 'mw45E4VwxEyakvAM2hysSW5rvUBotftWEN', 'mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh', 'mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ', 'mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU', 'mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9', 'mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx', 'mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv', 'mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh', 'msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM', 'myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6', 'mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og', 'mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z', 'muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH') to localhost:50002 (30 timeout)
[7143](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7144)
2022-09-13 12:43:36,795 - lbry.wallet.network - DEBUG - response blockchain.address.subscribe('mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz', 'n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX', 'n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA', 'n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC', 'mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb', 'myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX', 'mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5', 'mw45E4VwxEyakvAM2hysSW5rvUBotftWEN', 'mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh', 'mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ', 'mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU', 'mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9', 'mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx', 'mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv', 'mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh', 'msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM', 'myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6', 'mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og', 'mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z', 'muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH') from localhost:50002 (30 timeout) -> [None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None]
[7144](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7145)
2022-09-13 12:43:36,795 - lbry.wallet.ledger - WARNING - initial status update address mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz status None
[7145](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7146)
2022-09-13 12:43:36,796 - lbry.wallet.ledger - WARNING - initial status update address n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX status None
[7146](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7147)
2022-09-13 12:43:36,796 - lbry.wallet.ledger - WARNING - initial status update address n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA status None
[7147](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7148)
2022-09-13 12:43:36,796 - lbry.wallet.ledger - WARNING - initial status update address n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC status None
[7148](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7149)
2022-09-13 12:43:36,796 - lbry.wallet.ledger - WARNING - initial status update address mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb status None
[7149](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7150)
2022-09-13 12:43:36,797 - lbry.wallet.ledger - WARNING - initial status update address myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX status None
[7150](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7151)
2022-09-13 12:43:36,797 - lbry.wallet.ledger - WARNING - initial status update address mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5 status None
[7151](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7152)
2022-09-13 12:43:36,797 - lbry.wallet.ledger - WARNING - initial status update address mw45E4VwxEyakvAM2hysSW5rvUBotftWEN status None
[7152](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7153)
2022-09-13 12:43:36,798 - lbry.wallet.ledger - WARNING - initial status update address mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh status None
[7153](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7154)
2022-09-13 12:43:36,798 - lbry.wallet.ledger - WARNING - initial status update address mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ status None
[7154](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7155)
2022-09-13 12:43:36,798 - lbry.wallet.ledger - WARNING - initial status update address mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU status None
[7155](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7156)
2022-09-13 12:43:36,799 - lbry.wallet.ledger - WARNING - initial status update address mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9 status None
[7156](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7157)
2022-09-13 12:43:36,799 - lbry.wallet.ledger - WARNING - initial status update address mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx status None
[7157](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7158)
2022-09-13 12:43:36,799 - lbry.wallet.ledger - WARNING - initial status update address mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv status None
[7158](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7159)
2022-09-13 12:43:36,800 - lbry.wallet.ledger - WARNING - initial status update address mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh status None
[7159](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7160)
2022-09-13 12:43:36,800 - lbry.wallet.ledger - WARNING - initial status update address msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM status None
[7160](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7161)
2022-09-13 12:43:36,800 - lbry.wallet.ledger - WARNING - initial status update address myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6 status None
[7161](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7162)
2022-09-13 12:43:36,800 - lbry.wallet.ledger - WARNING - initial status update address mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og status None
[7162](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7163)
2022-09-13 12:43:36,801 - lbry.wallet.ledger - WARNING - initial status update address mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z status None
[7163](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7164)
2022-09-13 12:43:36,801 - lbry.wallet.ledger - WARNING - initial status update address muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH status None
[7164](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7165)
2022-09-13 12:43:36,827 - lbry.wallet.network - DEBUG - send blockchain.address.subscribe('n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik', 'mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse', 'mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5', 'mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP', 'mjyQAtHyNhuwRCKPgse3w86n82orhctwgC', 'mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG') to localhost:50002 (30 timeout)
[7165](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7166)
2022-09-13 12:43:36,833 - lbry.wallet.network - DEBUG - response blockchain.address.subscribe('n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik', 'mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse', 'mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5', 'mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP', 'mjyQAtHyNhuwRCKPgse3w86n82orhctwgC', 'mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG') from localhost:50002 (30 timeout) -> [None, None, None, None, None, None]
[7166](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7167)
2022-09-13 12:43:36,834 - lbry.wallet.ledger - WARNING - initial status update address n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik status None
[7167](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7168)
2022-09-13 12:43:36,834 - lbry.wallet.ledger - WARNING - initial status update address mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse status None
[7168](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7169)
2022-09-13 12:43:36,834 - lbry.wallet.ledger - WARNING - initial status update address mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5 status None
[7169](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7170)
2022-09-13 12:43:36,834 - lbry.wallet.ledger - WARNING - initial status update address mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP status None
[7170](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7171)
2022-09-13 12:43:36,835 - lbry.wallet.ledger - WARNING - initial status update address mjyQAtHyNhuwRCKPgse3w86n82orhctwgC status None
[7171](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7172)
2022-09-13 12:43:36,835 - lbry.wallet.ledger - WARNING - initial status update address mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG status None
[7172](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7173)
2022-09-13 12:43:36,971 - lbry.testcase - WARNING - before: done = False, tx_watch = []
[7173](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7174)
2022-09-13 12:43:37,216 - lbry.wallet.ledger - WARNING - status update address mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz status 5e522064f4bd1138213d3b79248e89f5f06cadfa0a9dbdcb7178b6e514290142
[7174](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7175)
2022-09-13 12:43:37,218 - lbry.wallet.network - DEBUG - send blockchain.address.get_history('mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz',) to localhost:50002 (30 timeout)
[7175](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7176)
2022-09-13 12:43:37,224 - lbry.wallet.network - DEBUG - response blockchain.address.get_history('mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz',) from localhost:50002 (30 timeout) -> [{'tx_hash': '85d35f673bf5412dc9e9458430c2702318e960d7e7d86c66c0060422a3bcd19f', 'height': 0, 'fee': 1717}]
[7176](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7177)
2022-09-13 12:43:37,224 - lbry.wallet.network - DEBUG - send blockchain.transaction.get_batch('85d35f673bf5412dc9e9458430c2702318e960d7e7d86c66c0060422a3bcd19f',) to localhost:50002 (30 timeout)
[7177](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7178)
2022-09-13 12:43:37,233 - lbry.wallet.network - DEBUG - response blockchain.transaction.get_batch('85d35f673bf5412dc9e9458430c2702318e960d7e7d86c66c0060422a3bcd19f',) from localhost:50002 (30 timeout) -> {[...snip...], {'block_height': -1}]}
[7178](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7179)
2022-09-13 12:43:37,237 - lbry.testcase - WARNING - after: done = False, tx_watch = []
[7179](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7180)
2022-09-13 12:43:37,248 - lbry.wallet.ledger - WARNING - updated address: mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz history: 85d35f673bf5412dc9e9458430c2702318e960d7e7d86c66c0060422a3bcd19f:0:
[7180](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7181)
2022-09-13 12:43:37,259 - lbry.wallet.network - DEBUG - send blockchain.address.subscribe('mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu',) to localhost:50002 (30 timeout)
[7181](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7182)
2022-09-13 12:43:37,275 - lbry.wallet.network - DEBUG - response blockchain.address.subscribe('mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu',) from localhost:50002 (30 timeout) -> [None]
[7182](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7183)
2022-09-13 12:43:37,275 - lbry.wallet.ledger - WARNING - initial status update address mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu status None
[7183](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7184)
2022-09-13 12:43:37,862 - lbry.wallet.ledger - WARNING - status update address mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz status edabf85ea93e04f3606ea3450fcf8d10fe53d0bc36b8ca62c830f58c9938c9d2
[7184](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7185)
2022-09-13 12:43:37,865 - lbry.wallet.network - DEBUG - send blockchain.address.get_history('mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz',) to localhost:50002 (30 timeout)
[7185](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7186)
2022-09-13 12:43:37,877 - lbry.wallet.network - DEBUG - response blockchain.address.get_history('mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz',) from localhost:50002 (30 timeout) -> [{'tx_hash': '85d35f673bf5412dc9e9458430c2702318e960d7e7d86c66c0060422a3bcd19f', 'height': 201}]
[7186](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7187)
2022-09-13 12:43:37,877 - lbry.wallet.network - DEBUG - send blockchain.transaction.get_batch('85d35f673bf5412dc9e9458430c2702318e960d7e7d86c66c0060422a3bcd19f',) to localhost:50002 (30 timeout)
[7187](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7188)
2022-09-13 12:43:37,888 - lbry.wallet.network - DEBUG - response blockchain.transaction.get_batch('85d35f673bf5412dc9e9458430c2702318e960d7e7d86c66c0060422a3bcd19f',) from localhost:50002 (30 timeout) -> {[...snip...], {'block_height': 201, 'merkle': ['d612504c34e5c9c72e30d7a258c3f639fa5e58655e25a2296a4f11aeb7326afb'], 'pos': 1}]}
[7188](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7189)
2022-09-13 12:43:37,897 - lbry.wallet.ledger - WARNING - updated address: mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz history: 85d35f673bf5412dc9e9458430c2702318e960d7e7d86c66c0060422a3bcd19f:201:
[7189](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7190)
2022-09-13 12:43:37,911 - lbry.wallet.orchstr8.node.lbcwallet - INFO - /home/runner/work/lbry-sdk/lbry-sdk/lbry/wallet/bin/lbcctl --rpcuser=rpcuser --rpcpass=rpcpassword --notls --regtest --wallet getrawchangeaddress
[7190](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7191)
2022-09-13 12:43:37,921 - lbry.wallet.usage_payment - INFO - Stopping wallet server payments.
[7191](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7192)
2022-09-13 12:43:37,964 - lbry.wallet.orchstr8.node.lbcwallet - INFO - n3vmQVVyUipVrWLPsYgwEHu46cpdBe3pzJ
[7192](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7193)
2022-09-13 12:43:38,280 - asyncio - WARNING - Executing <Task pending coro=<TestUsagePayment.test_single_server_payment() running at /home/runner/work/lbry-sdk/lbry-sdk/tests/integration/blockchain/test_wallet_server_sessions.py:77> wait_for=<Future pending cb=[_chain_future.<locals>._call_check_cancel() at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/futures.py:351, <TaskWakeupMethWrapper object at 0x7ffb15cbd850>()] created at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py:395> cb=[_run_until_complete_cb() at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py:157] created at /opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py:566> took 0.315 seconds
[7193](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7194)
2022-09-13 12:43:38,921 - lbry.wallet.network - DEBUG - send server.features() to localhost:50002 (30 timeout)
[7194](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7195)
2022-09-13 12:43:38,929 - lbry.wallet.network - DEBUG - response server.features() from localhost:50002 (30 timeout) -> {'hosts': {}, 'pruning': None, 'server_version': '0.107.0', 'protocol_min': '0.54.0', 'protocol_max': '0.199.0', 'genesis_hash': '6e3fcf1299d4ec5d79c3a4c91d624a4acf9e2e173d95a1a0504f677669687556', 'description': '', 'payment_address': '', 'donation_address': '', 'daily_fee': '0', 'hash_function': 'sha256', 'trending_algorithm': 'fast_ar'}
[7195](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7196)
2022-09-13 12:43:39,181 - elasticsearch - WARNING - GET http://localhost:9200/_template/8b3b8c450c0d4fb49aba5822cbd5f565claims [status:404 request:0.002s]
[7196](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7197)
2022-09-13 12:43:39,849 - hub.herald.session - INFO - max session count: 1,000
[7197](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7198)
2022-09-13 12:43:39,850 - hub.herald.session - INFO - session timeout: 600 seconds
[7198](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7199)
2022-09-13 12:43:39,850 - hub.herald.session - INFO - max response size 1,000,000,000,000,000,000 bytes
[7199](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7200)
2022-09-13 12:43:39,929 - hub.herald.session - INFO - TCP server listening on 127.0.0.1:50003
[7200](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7201)
2022-09-13 12:43:39,931 - lbry.wallet.network - INFO - closing connection to localhost:50002
[7201](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7202)
2022-09-13 12:43:39,931 - lbry.wallet.network - INFO - connection lost to localhost:50002
[7202](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7203)
2022-09-13 12:43:39,931 - lbry.wallet.network - INFO - network loop finished
[7203](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7204)
2022-09-13 12:43:39,932 - lbry.wallet.network - DEBUG - Connection lost: localhost:50002
[7204](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7205)
2022-09-13 12:43:39,933 - lbry.wallet.usage_payment - INFO - Stopping wallet server payments.
[7205](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7206)
2022-09-13 12:43:39,934 - lbry.wallet.database - INFO - DB checkpoint finished.
[7206](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7207)
2022-09-13 12:43:39,937 - lbry.wallet.database - INFO - connecting to database: /tmp/tmpnrlq9ube/lbc_regtest/blockchain.db
[7207](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7208)
2022-09-13 12:43:39,944 - lbry.wallet.network - INFO - 1 possible spv servers to try (1 urls in config)
[7208](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7209)
2022-09-13 12:43:39,946 - lbry.wallet.network - INFO - localhost:50003 has latency of 0.82ms (available: True, height: 206)
[7209](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7210)
2022-09-13 12:43:39,947 - lbry.wallet.udp - INFO - closed udp spv server selection client
[7210](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7211)
2022-09-13 12:43:39,952 - lbry.wallet.network - INFO - Connected to spv server localhost:50003
[7211](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7212)
2022-09-13 12:43:39,952 - lbry.wallet.network - DEBUG - send server.version('0.110.0', '0.110.0') to localhost:50003 (30 timeout)
[7212](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7213)
2022-09-13 12:43:39,952 - lbry.wallet.network - DEBUG - send version request to localhost:50003
[7213](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7214)
2022-09-13 12:43:39,955 - lbry.wallet.network - DEBUG - get spv server features localhost:50003
[7214](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7215)
2022-09-13 12:43:39,955 - lbry.wallet.network - DEBUG - send server.features() to localhost:50003 (30 timeout)
[7215](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7216)
2022-09-13 12:43:39,957 - lbry.wallet.network - DEBUG - response server.features() from localhost:50003 (30 timeout) -> {'hosts': {}, 'pruning': None, 'server_version': '0.107.0', 'protocol_min': '0.54.0', 'protocol_max': '0.199.0', 'genesis_hash': '6e3fcf1299d4ec5d79c3a4c91d624a4acf9e2e173d95a1a0504f677669687556', 'description': '', 'payment_address': '', 'donation_address': '', 'daily_fee': '0', 'hash_function': 'sha256', 'trending_algorithm': 'fast_ar'}
[7216](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7217)
2022-09-13 12:43:39,957 - lbry.wallet.network - DEBUG - discover other hubs localhost:50003
[7217](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7218)
2022-09-13 12:43:39,957 - lbry.wallet.network - DEBUG - send server.peers.subscribe() to localhost:50003 (30 timeout)
[7218](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7219)
2022-09-13 12:43:39,960 - lbry.wallet.network - DEBUG - response server.peers.subscribe() from localhost:50003 (30 timeout) -> []
[7219](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7220)
2022-09-13 12:43:39,960 - lbry.wallet.network - INFO - subscribe to headers localhost:50003
[7220](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7221)
2022-09-13 12:43:39,960 - lbry.wallet.network - DEBUG - send blockchain.headers.subscribe(True,) to localhost:50003 (30 timeout)
[7221](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7222)
2022-09-13 12:43:39,962 - lbry.wallet.network - DEBUG - response blockchain.headers.subscribe(True,) from localhost:50003 (30 timeout) -> {'hex': '00000030a376f2b3613aa32a4359623653f32dccfb4e9cbef562ef4beed574125e6abecaf6152ffbb0d689db5f3c3a7e0ace69a455d89293784a95ad9c2fc52337b8e3ea0100000000000000000000000000000000000000000000000000000000000000187b2063ffff7f2000000000', 'height': 206}
[7222](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7223)
2022-09-13 12:43:39,962 - lbry.wallet.network - INFO - maintaining connection to spv server localhost:50003
[7223](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7224)
2022-09-13 12:43:39,963 - lbry.wallet.network - WARNING - wait reconnect+keepalive
[7224](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7225)
2022-09-13 12:43:39,965 - lbry.wallet.network - DEBUG - send blockchain.block.headers(207, 2001, 0, False) to localhost:50003 (30 timeout)
[7225](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7226)
2022-09-13 12:43:39,965 - lbry.wallet.ledger - INFO - Subscribing and updating accounts.
[7226](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7227)
2022-09-13 12:43:39,966 - lbry.wallet.ledger - INFO - Subscribe to 1 accounts
[7227](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7228)
2022-09-13 12:43:39,970 - lbry.wallet.network - DEBUG - send blockchain.address.subscribe('n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX', 'n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA', 'n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC', 'mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb', 'myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX', 'mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5', 'mw45E4VwxEyakvAM2hysSW5rvUBotftWEN', 'mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh', 'mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ', 'mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU', 'mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9', 'mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx', 'mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv', 'mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh', 'msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM', 'myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6', 'mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og', 'mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z', 'muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH', 'mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu', 'mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz') to localhost:50003 (30 timeout)
[7228](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7229)
2022-09-13 12:43:39,970 - lbry.wallet.network - DEBUG - response blockchain.block.headers(207, 2001, 0, False) from localhost:50003 (30 timeout) -> {'hex': '', 'count': 0, 'max': 40960}
[7229](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7230)
2022-09-13 12:43:39,971 - lbry.wallet.ledger - INFO - Subscribing and updating accounts.
[7230](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7231)
2022-09-13 12:43:39,972 - lbry.wallet.ledger - INFO - Subscribe to 1 accounts
[7231](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7232)
2022-09-13 12:43:39,977 - lbry.wallet.network - DEBUG - send blockchain.address.subscribe('n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX', 'n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA', 'n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC', 'mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb', 'myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX', 'mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5', 'mw45E4VwxEyakvAM2hysSW5rvUBotftWEN', 'mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh', 'mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ', 'mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU', 'mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9', 'mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx', 'mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv', 'mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh', 'msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM', 'myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6', 'mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og', 'mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z', 'muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH', 'mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu', 'mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz') to localhost:50003 (30 timeout)
[7232](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7233)
2022-09-13 12:43:39,991 - lbry.wallet.network - DEBUG - response blockchain.address.subscribe('n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX', 'n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA', 'n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC', 'mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb', 'myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX', 'mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5', 'mw45E4VwxEyakvAM2hysSW5rvUBotftWEN', 'mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh', 'mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ', 'mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU', 'mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9', 'mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx', 'mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv', 'mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh', 'msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM', 'myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6', 'mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og', 'mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z', 'muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH', 'mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu', 'mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz') from localhost:50003 (30 timeout) -> [None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, 'edabf85ea93e04f3606ea3450fcf8d10fe53d0bc36b8ca62c830f58c9938c9d2']
[7233](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7234)
2022-09-13 12:43:39,992 - lbry.wallet.ledger - WARNING - initial status update address n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX status None
[7234](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7235)
2022-09-13 12:43:39,992 - lbry.wallet.ledger - WARNING - initial status update address n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA status None
[7235](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7236)
2022-09-13 12:43:39,993 - lbry.wallet.ledger - WARNING - initial status update address n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC status None
[7236](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7237)
2022-09-13 12:43:39,993 - lbry.wallet.ledger - WARNING - initial status update address mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb status None
[7237](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7238)
2022-09-13 12:43:39,993 - lbry.wallet.ledger - WARNING - initial status update address myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX status None
[7238](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7239)
2022-09-13 12:43:39,994 - lbry.wallet.ledger - WARNING - initial status update address mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5 status None
[7239](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7240)
2022-09-13 12:43:39,994 - lbry.wallet.ledger - WARNING - initial status update address mw45E4VwxEyakvAM2hysSW5rvUBotftWEN status None
[7240](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7241)
2022-09-13 12:43:39,994 - lbry.wallet.ledger - WARNING - initial status update address mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh status None
[7241](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7242)
2022-09-13 12:43:39,995 - lbry.wallet.ledger - WARNING - initial status update address mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ status None
[7242](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7243)
2022-09-13 12:43:39,995 - lbry.wallet.ledger - WARNING - initial status update address mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU status None
[7243](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7244)
2022-09-13 12:43:39,995 - lbry.wallet.ledger - WARNING - initial status update address mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9 status None
[7244](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7245)
2022-09-13 12:43:39,995 - lbry.wallet.ledger - WARNING - initial status update address mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx status None
[7245](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7246)
2022-09-13 12:43:39,996 - lbry.wallet.ledger - WARNING - initial status update address mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv status None
[7246](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7247)
2022-09-13 12:43:39,996 - lbry.wallet.ledger - WARNING - initial status update address mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh status None
[7247](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7248)
2022-09-13 12:43:39,996 - lbry.wallet.ledger - WARNING - initial status update address msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM status None
[7248](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7249)
2022-09-13 12:43:39,997 - lbry.wallet.ledger - WARNING - initial status update address myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6 status None
[7249](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7250)
2022-09-13 12:43:39,997 - lbry.wallet.ledger - WARNING - initial status update address mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og status None
[7250](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7251)
2022-09-13 12:43:39,997 - lbry.wallet.ledger - WARNING - initial status update address mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z status None
[7251](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7252)
2022-09-13 12:43:39,997 - lbry.wallet.ledger - WARNING - initial status update address muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH status None
[7252](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7253)
2022-09-13 12:43:39,998 - lbry.wallet.ledger - WARNING - initial status update address mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu status None
[7253](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7254)
2022-09-13 12:43:39,998 - lbry.wallet.ledger - WARNING - initial status update address mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz status edabf85ea93e04f3606ea3450fcf8d10fe53d0bc36b8ca62c830f58c9938c9d2
[7254](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7255)
2022-09-13 12:43:39,999 - lbry.wallet.ledger - INFO - subscribed to 21/21 addresses on 127.0.0.1:50003
[7255](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7256)
2022-09-13 12:43:39,999 - lbry.wallet.ledger - INFO - finished subscribing to 21 addresses on 127.0.0.1:50003
[7256](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7257)
2022-09-13 12:43:40,009 - lbry.wallet.network - DEBUG - send blockchain.address.subscribe('n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik', 'mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse', 'mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5', 'mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP', 'mjyQAtHyNhuwRCKPgse3w86n82orhctwgC', 'mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG') to localhost:50003 (30 timeout)
[7257](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7258)
2022-09-13 12:43:40,139 - lbry.wallet.network - DEBUG - response blockchain.address.subscribe('n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX', 'n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA', 'n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC', 'mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb', 'myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX', 'mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5', 'mw45E4VwxEyakvAM2hysSW5rvUBotftWEN', 'mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh', 'mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ', 'mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU', 'mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9', 'mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx', 'mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv', 'mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh', 'msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM', 'myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6', 'mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og', 'mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z', 'muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH', 'mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu', 'mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz') from localhost:50003 (30 timeout) -> [None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, None, 'edabf85ea93e04f3606ea3450fcf8d10fe53d0bc36b8ca62c830f58c9938c9d2']
[7258](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7259)
2022-09-13 12:43:40,139 - lbry.wallet.ledger - WARNING - initial status update address n4LGGCFYz74AfCqtutfSJiL1Y6R9c1SxfX status None
[7259](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7260)
2022-09-13 12:43:40,140 - lbry.wallet.ledger - WARNING - initial status update address n2GcLxpitwPqFj5XZmFHiMu9fbeLPmoPaA status None
[7260](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7261)
2022-09-13 12:43:40,140 - lbry.wallet.ledger - WARNING - initial status update address n1HKYBFXrmZnw8tUBddvVWXWBbZsCfJmUC status None
[7261](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7262)
2022-09-13 12:43:40,140 - lbry.wallet.ledger - WARNING - initial status update address mjF21Uwu7NQSaVsm1J82fkVn1wPb37ngzb status None
[7262](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7263)
2022-09-13 12:43:40,141 - lbry.wallet.ledger - WARNING - initial status update address myhA9Bh5pevy3T4W6kkkQFZJrzzDMZqVAX status None
[7263](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7264)
2022-09-13 12:43:40,141 - lbry.wallet.ledger - WARNING - initial status update address mxQwW1RMh3VcY4syY1GUah7XSG9ByMfxQ5 status None
[7264](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7265)
2022-09-13 12:43:40,141 - lbry.wallet.ledger - WARNING - initial status update address mw45E4VwxEyakvAM2hysSW5rvUBotftWEN status None
[7265](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7266)
2022-09-13 12:43:40,141 - lbry.wallet.ledger - WARNING - initial status update address mjiVBwZh1Nmvd7db3bDzNETJ4zSdaQ3PHh status None
[7266](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7267)
2022-09-13 12:43:40,142 - lbry.wallet.ledger - WARNING - initial status update address mqyq9g3yNfRbsgK6Avb8rRB4vuuAraBKVJ status None
[7267](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7268)
2022-09-13 12:43:40,142 - lbry.wallet.ledger - WARNING - initial status update address mmhAFLTJckvpJcjEyxAmk21P4Qx8nVqfUU status None
[7268](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7269)
2022-09-13 12:43:40,142 - lbry.wallet.ledger - WARNING - initial status update address mm2GUYZKQhRkuHP1ptBDgxtWWKSWuoRgE9 status None
[7269](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7270)
2022-09-13 12:43:40,142 - lbry.wallet.ledger - WARNING - initial status update address mn16zNf1pvzPjJBNpDaSUnqvi1uQVEsoUx status None
[7270](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7271)
2022-09-13 12:43:40,143 - lbry.wallet.ledger - WARNING - initial status update address mn3E9S7fUuW3TYHFrbuDi4fkDtAfBMEAzv status None
[7271](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7272)
2022-09-13 12:43:40,143 - lbry.wallet.ledger - WARNING - initial status update address mux6pby76rsnDovkDbkfBZKtdWgmyyLMBh status None
[7272](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7273)
2022-09-13 12:43:40,143 - lbry.wallet.ledger - WARNING - initial status update address msGVX2Nm2uRH4WAgPDfBPBbtYcjEtfGoRM status None
[7273](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7274)
2022-09-13 12:43:40,143 - lbry.wallet.ledger - WARNING - initial status update address myZYitFqoWuDNECkZ3eepEmpGvJ93frnJ6 status None
[7274](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7275)
2022-09-13 12:43:40,144 - lbry.wallet.ledger - WARNING - initial status update address mzaDF8Pj16HzowTu5XKjKyQybe4RUNt7og status None
[7275](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7276)
2022-09-13 12:43:40,144 - lbry.wallet.ledger - WARNING - initial status update address mtWwEupP5ZhwAZ6mtJGpbDk8KoeERvYe1z status None
[7276](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7277)
2022-09-13 12:43:40,145 - lbry.wallet.ledger - WARNING - initial status update address muiPcX2F8VJXXbi5NyAzaHr9fvNTa841cH status None
[7277](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7278)
2022-09-13 12:43:40,145 - lbry.wallet.ledger - WARNING - initial status update address mwAEYLTkcxBrsaXPHpPC3bC3s5KAwLcGZu status None
[7278](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7279)
2022-09-13 12:43:40,145 - lbry.wallet.ledger - WARNING - initial status update address mmt43nGH5QiGWtoiZiJUk3ufJQF4fCNCCz status edabf85ea93e04f3606ea3450fcf8d10fe53d0bc36b8ca62c830f58c9938c9d2
[7279](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7280)
2022-09-13 12:43:40,145 - lbry.wallet.ledger - INFO - subscribed to 21/21 addresses on 127.0.0.1:50003
[7280](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7281)
2022-09-13 12:43:40,145 - lbry.wallet.ledger - INFO - finished subscribing to 21 addresses on 127.0.0.1:50003
[7281](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7282)
2022-09-13 12:43:40,152 - lbry.wallet.network - DEBUG - send blockchain.address.subscribe('n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik', 'mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse', 'mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5', 'mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP', 'mjyQAtHyNhuwRCKPgse3w86n82orhctwgC', 'mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG') to localhost:50003 (30 timeout)
[7282](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7283)
2022-09-13 12:43:40,168 - lbry.wallet.network - DEBUG - response blockchain.address.subscribe('n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik', 'mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse', 'mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5', 'mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP', 'mjyQAtHyNhuwRCKPgse3w86n82orhctwgC', 'mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG') from localhost:50003 (30 timeout) -> [None, None, None, None, None, None]
[7283](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7284)
2022-09-13 12:43:40,168 - lbry.wallet.ledger - WARNING - initial status update address n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik status None
[7284](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7285)
2022-09-13 12:43:40,169 - lbry.wallet.ledger - WARNING - initial status update address mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse status None
[7285](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7286)
2022-09-13 12:43:40,169 - lbry.wallet.ledger - WARNING - initial status update address mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5 status None
[7286](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7287)
2022-09-13 12:43:40,169 - lbry.wallet.ledger - WARNING - initial status update address mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP status None
[7287](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7288)
2022-09-13 12:43:40,169 - lbry.wallet.ledger - WARNING - initial status update address mjyQAtHyNhuwRCKPgse3w86n82orhctwgC status None
[7288](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7289)
2022-09-13 12:43:40,170 - lbry.wallet.ledger - WARNING - initial status update address mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG status None
[7289](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7290)
2022-09-13 12:43:40,170 - lbry.wallet.ledger - INFO - subscribed to 6/6 addresses on 127.0.0.1:50003
[7290](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7291)
2022-09-13 12:43:40,170 - lbry.wallet.ledger - INFO - finished subscribing to 6 addresses on 127.0.0.1:50003
[7291](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7292)
2022-09-13 12:43:40,190 - lbry.wallet.network - DEBUG - response blockchain.address.subscribe('n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik', 'mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse', 'mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5', 'mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP', 'mjyQAtHyNhuwRCKPgse3w86n82orhctwgC', 'mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG') from localhost:50003 (30 timeout) -> [None, None, None, None, None, None]
[7292](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7293)
2022-09-13 12:43:40,190 - lbry.wallet.ledger - WARNING - initial status update address n2RBSTDrJ9GLbBnpFbkpbkEjEoNPfTwdik status None
[7293](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7294)
2022-09-13 12:43:40,191 - lbry.wallet.ledger - WARNING - initial status update address mxe1cj3iPBsEFQ7piRVPeRZB93ZqeGvpse status None
[7294](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7295)
2022-09-13 12:43:40,191 - lbry.wallet.ledger - WARNING - initial status update address mkts59gurpV4ag6t8z8Q4to4pbaK1tFFk5 status None
[7295](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7296)
2022-09-13 12:43:40,191 - lbry.wallet.ledger - WARNING - initial status update address mo9yZsjHVHbkyawcYJsBTKVVTAg6CGCrpP status None
[7296](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7297)
2022-09-13 12:43:40,191 - lbry.wallet.ledger - WARNING - initial status update address mjyQAtHyNhuwRCKPgse3w86n82orhctwgC status None
[7297](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7298)
2022-09-13 12:43:40,192 - lbry.wallet.ledger - WARNING - initial status update address mgsmRKkQge3DHgg54uaRa4K7Bkxmwnf2EG status None
[7298](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7299)
2022-09-13 12:43:40,192 - lbry.wallet.ledger - INFO - subscribed to 6/6 addresses on 127.0.0.1:50003
[7299](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7300)
2022-09-13 12:43:40,192 - lbry.wallet.ledger - INFO - finished subscribing to 6 addresses on 127.0.0.1:50003
[7300](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7301)
2022-09-13 12:43:40,244 - lbry.wallet.ledger - INFO - Loaded account my2xfYsqYd56buy2Yyxg8LvbGXuVUXRaJu with 10.0 LBC, 21 receiving addresses (gap: 20), 6 change addresses (gap: 6), 0 channels, 0 certificates and 0 claims. 
[7301](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7302)
2022-09-13 12:43:40,245 - lbry.wallet.network - DEBUG - send server.features() to localhost:50003 (30 timeout)
[7302](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7303)
2022-09-13 12:43:40,248 - lbry.wallet.network - DEBUG - response server.features() from localhost:50003 (30 timeout) -> {'hosts': {}, 'pruning': None, 'server_version': '0.107.0', 'protocol_min': '0.54.0', 'protocol_max': '0.199.0', 'genesis_hash': '6e3fcf1299d4ec5d79c3a4c91d624a4acf9e2e173d95a1a0504f677669687556', 'description': '', 'payment_address': 'n3vmQVVyUipVrWLPsYgwEHu46cpdBe3pzJ', 'donation_address': '', 'daily_fee': '1.1', 'hash_function': 'sha256', 'trending_algorithm': 'fast_ar'}
[7303](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7304)
2022-09-13 12:44:10,255 - lbry.wallet.network - INFO - closing connection to localhost:50003
[7304](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7305)
2022-09-13 12:44:10,256 - lbry.wallet.network - INFO - connection lost to localhost:50003
[7305](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7306)
2022-09-13 12:44:10,256 - lbry.wallet.network - INFO - network loop finished
[7306](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7307)
2022-09-13 12:44:10,256 - lbry.wallet.database - INFO - DB checkpoint finished.
[7307](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7308)
2022-09-13 12:44:10,257 - lbry.wallet.network - DEBUG - Connection lost: localhost:50003
[7308](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7309)
2022-09-13 12:44:10,264 - lbry.wallet.database - INFO - DB checkpoint finished.
[7309](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7310)
2022-09-13 12:44:10,272 - hub.herald.session - INFO - closing down TCP listening servers
[7310](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7311)
2022-09-13 12:44:10,273 - hub.herald.session - INFO - disconnect 0 sessions
[7311](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7312)
2022-09-13 12:44:10,294 - lbry.wallet.orchstr8.node.lbcd - INFO - 2022-09-13 12:44:10.294 [INF] MAIN: RAM: using 0.0 GB with 4.1 available, DISK: using 0.0 GB with 30.2 available
[7312](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7313)

[7313](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7314)
2022-09-13 12:44:10,395 - hub.herald.session - INFO - closing down TCP listening servers
[7314](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7315)
2022-09-13 12:44:10,395 - hub.herald.session - INFO - disconnect 0 sessions
[7315](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7316)
2022-09-13 12:44:10,443 - lbry.wallet.orchstr8.node.lbcwallet - INFO - 2022-09-13 12:44:10.443 [INF] BTCW: Received signal (terminated).  Shutting down...
[7316](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7317)
2022-09-13 12:44:10.443 [WRN] BTCW: Stopping legacy RPC server...
[7317](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7318)
2022-09-13 12:44:10.443 [INF] BTCW: Legacy RPC server shutdown
[7318](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7319)
2022-09-13 12:44:10.443 [INF] BTCW: Shutdown complete
[7319](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7320)

[7320](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7321)
2022-09-13 12:44:10,446 - lbry.wallet.orchstr8.node.lbcd - INFO - 2022-09-13 12:44:10.445 [ERR] RPCS: Websocket receive error from 127.0.0.1:43868: websocket: close 1006 unexpected EOF
[7321](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7322)
2022-09-13 12:44:10.445 [INF] RPCS: Disconnected websocket client 127.0.0.1:43868
[7322](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7323)

[7323](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7324)
2022-09-13 12:44:10,446 - lbry.wallet.orchstr8.node.lbcwallet - INFO - Done shutting down /home/runner/work/lbry-sdk/lbry-sdk/lbry/wallet/bin/lbcwallet
[7324](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7325)
2022-09-13 12:44:10,448 - lbry.wallet.orchstr8.node.lbcd - INFO - 2022-09-13 12:44:10.447 [INF] MAIN: Received signal (terminated).  Shutting down...
[7325](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7326)
2022-09-13 12:44:10.447 [INF] MAIN: Gracefully shutting down the server...
[7326](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7327)
2022-09-13 12:44:10.447 [WRN] SRVR: Server shutting down
[7327](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7328)
2022-09-13 12:44:10.447 [WRN] RPCS: RPC server shutting down
[7328](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7329)
2022-09-13 12:44:10.447 [INF] RPCS: RPC server shutdown complete
[7329](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7330)
2022-09-13 12:44:10.447 [INF] SYNC: Sync manager shutting down
[7330](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7331)
2022-09-13 12:44:10.447 [INF] AMGR: Address manager shutting down
[7331](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7332)
2022-09-13 12:44:10.447 [INF] SRVR: Server shutdown complete
[7332](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7333)

[7333](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7334)
2022-09-13 12:44:10,456 - lbry.wallet.orchstr8.node.lbcd - INFO - 2022-09-13 12:44:10.455 [INF] MAIN: Gracefully shutting down the database...
[7334](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7335)

[7335](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7336)
2022-09-13 12:44:10,467 - lbry.wallet.orchstr8.node.lbcd - INFO - 2022-09-13 12:44:10.467 [INF] MAIN: Shutdown complete
[7336](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7337)

[7337](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7338)
2022-09-13 12:44:10,470 - lbry.wallet.orchstr8.node.lbcd - INFO - Done shutting down /home/runner/work/lbry-sdk/lbry-sdk/lbry/wallet/bin/lbcd
[7338](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7339)
ERROR
[7339](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7340)

======================================================================
[7341](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7342)
ERROR: test_single_server_payment (integration.blockchain.test_wallet_server_sessions.TestUsagePayment)
[7342](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7343)
----------------------------------------------------------------------
[7343](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7344)
Traceback (most recent call last):
[7344](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7345)
  File "/home/runner/work/lbry-sdk/lbry-sdk/lbry/testcase.py", line 145, in run
[7345](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7346)
    self.loop.run_until_complete(maybe_coroutine)
[7346](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7347)
  File "/opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/base_events.py", line 587, in run_until_complete
[7347](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7348)
    return future.result()
[7348](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7349)
  File "/home/runner/work/lbry-sdk/lbry-sdk/tests/integration/blockchain/test_wallet_server_sessions.py", line 86, in test_single_server_payment
[7349](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7350)
    await asyncio.wait_for(wallet_pay_service.on_payment.first, timeout=30)
[7350](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7351)
  File "/opt/hostedtoolcache/Python/3.7.13/x64/lib/python3.7/asyncio/tasks.py", line 449, in wait_for
[7351](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7352)
    raise futures.TimeoutError()
[7352](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7353)
concurrent.futures._base.TimeoutError
[7353](https://github.com/moodyjon/lbry-sdk/actions/runs/3044958920/jobs/4905929450#step:11:7354)

Test code:

        with self.assertRaises(ServerPaymentFeeAboveMaxAllowedError):
            await asyncio.wait_for(wallet_pay_service.on_payment.first, timeout=30)
@moodyjon moodyjon self-assigned this Sep 13, 2022
@moodyjon
Copy link
Contributor Author

Another:
https://github.com/moodyjon/lbry-sdk/actions/runs/3063168288/jobs/4944952002

This one has some logging, and it looks like the pay() task is about to call get_server_features() when an exception happens. The exception comes from retriable_call() when not self.running. At the moment the call is attempted, the wallet connection is being bounced with ledger.stop() and ledger.start().

Added some code to catch and log the exception.

@moodyjon
Copy link
Contributor Author

Got it! asyncio.Cancelled error is happening because jsonrpc_wallet_reconnect() disconnects & reconnects the network.

https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296

2022-09-16 15:34:43,165 - lbry.wallet.usage_payment - WARNING - pay loop: no address or no amount
[7363](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7364)
2022-09-16 15:34:43,165 - lbry.wallet.usage_payment - INFO - pay loop: before sleep
[7364](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7365)
2022-09-16 15:34:43,258 - hub.herald.session - INFO - TCP server listening on 127.0.0.1:50003
[7365](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7366)
2022-09-16 15:34:43,261 - lbry.wallet.network - INFO - closing connection to localhost:50002
[7366](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7367)
2022-09-16 15:34:43,261 - lbry.wallet.network - INFO - connection lost to localhost:50002
[7367](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7368)
2022-09-16 15:34:43,262 - lbry.wallet.network - INFO - network loop finished
[7368](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7369)
2022-09-16 15:34:43,262 - lbry.wallet.network - DEBUG - Connection lost: localhost:50002
[7369](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7370)
2022-09-16 15:34:43,265 - lbry.wallet.database - INFO - DB checkpoint finished.
[7370](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7371)
2022-09-16 15:34:43,267 - lbry.wallet.usage_payment - INFO - pay loop: before get_server_features
[7371](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7372)
2022-09-16 15:34:43,268 - lbry.wallet.usage_payment - WARNING - Caught exception: CancelledError.       <<<<<<<<<<<<<<<<<<<<<<<< pay task exits early   
[7372](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7373)
2022-09-16 15:34:43,269 - lbry.wallet.usage_payment - INFO - Stopping wallet server payments. Cancelled
[7373](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7374)
2022-09-16 15:34:43,273 - lbry.wallet.database - INFO - connecting to database: /tmp/tmpflyzv0iy/lbc_regtest/blockchain.db
[7374](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7375)
2022-09-16 15:34:43,289 - lbry.wallet.network - INFO - 1 possible spv servers to try (1 urls in config)
[7375](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7376)
2022-09-16 15:34:43,291 - lbry.wallet.network - INFO - localhost:50003 has latency of 1.08ms (available: True, height: 206)
[7376](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7377)
2022-09-16 15:34:43,292 - lbry.wallet.udp - INFO - closed udp spv server selection client
[7377](https://github.com/moodyjon/lbry-sdk/actions/runs/3068903241/jobs/4956923296#step:11:7378)
2022-09-16 15:34:43,301 - lbry.wallet.network - INFO - Connected to spv server localhost:50003

@moodyjon moodyjon mentioned this issue Sep 19, 2022
Merged
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@moodyjon moodyjon

Labels
area: tests
Projects
None yet
Milestone
No milestone
1 participant
@moodyjon