#3789 new defect

intermittent integration/test_i2p.py::test_i2p_service_storage failure

Reported by: exarkun Owned by:
Priority: normal Milestone: undecided
Component: unknown Version: n/a
Keywords: Cc:
Launchpad Bug:

Description

https://github.com/tahoe-lafs/tahoe-lafs/pull/1119/checks?check_run_id=3509324630 (will no doubt expire almost immediately)

___________________________ test_i2p_service_storage ___________________________

pyfuncitem = <Function test_i2p_service_storage>

    def pytest_pyfunc_call(pyfuncitem):
        """Interface to async test call handler."""
        # TODO: only handle 'our' tests?  what is the point of handling others?
        #       well, because our interface allowed people to return deferreds
        #       from arbitrary tests so we kinda have to keep this up for now
>       _run_inline_callbacks(_async_pytest_pyfunc_call, pyfuncitem)

.tox/integration3/lib/python3.9/site-packages/pytest_twisted.py:368: 
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
.tox/integration3/lib/python3.9/site-packages/pytest_twisted.py:356: in _run_inline_callbacks
    blockon_default(d)
.tox/integration3/lib/python3.9/site-packages/pytest_twisted.py:98: in blockon_default
    result[0].raiseException()
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 

self = <twisted.python.failure.Failure twisted.internet.error.ProcessTerminated: A process has ended with a probable error condition: process ended with exit code 1.>

    def raiseException(self):
>       raise self.value.with_traceback(self.tb)
E       twisted.internet.error.ProcessTerminated: A process has ended with a probable error condition: process ended with exit code 1.

.tox/integration3/lib/python3.9/site-packages/twisted/python/failure.py:475: ProcessTerminated
---------------------------- Captured stdout setup -----------------------------
2021-09-03T19:25:18+0000 [twisted.python.log#info] web: 127.0.0.1 PUT /uri/[CENSORED] 201 72
2021-09-03T19:25:19+0000 [twisted.python.log#info] web: 127.0.0.1 GET /uri/[CENSORED] 200 40
Unable to find image 'purplei2p/i2pd:latest' locally
latest: Pulling from purplei2p/i2pd
a0d0a0d46f8b: Pulling fs layer
2f7a227e4ee3: Pulling fs layer
8078106cc68f: Pulling fs layer
f092c943dfc6: Pulling fs layer
3f6651e2a697: Pulling fs layer
7263312c64b7: Pulling fs layer
0f9ad0740231: Pulling fs layer
f092c943dfc6: Waiting
3f6651e2a697: Waiting
7263312c64b7: Waiting
0f9ad0740231: Waiting
2f7a227e4ee3: Verifying Checksum
2f7a227e4ee3: Download complete
a0d0a0d46f8b: Download complete
f092c943dfc6: Verifying Checksum
f092c943dfc6: Download complete
a0d0a0d46f8b: Pull complete
3f6651e2a697: Verifying Checksum
3f6651e2a697: Download complete
0f9ad0740231: Verifying Checksum
0f9ad0740231: Download complete
7263312c64b7: Verifying Checksum
7263312c64b7: Download complete
8078106cc68f: Verifying Checksum
8078106cc68f: Download complete
2f7a227e4ee3: Pull complete
8078106cc68f: Pull complete
f092c943dfc6: Pull complete
3f6651e2a697: Pull complete
7263312c64b7: Pull complete
0f9ad0740231: Pull complete
Digest: sha256:acf9b14768d41e851533374140e53f5646b77c28c9e68e7626580ad3958b6aed
Status: Downloaded newer image for purplei2p/i2pd:latest
19:25:20@305/none - i2pd v2.39.0 starting
19:25:20@305/error - RouterInfo: Can't open file 
19:25:20@305/warn - NetIface: interface with yggdrasil network address not found
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/error - Reseed: Failed to connect to localhost
19:25:20@305/warn - Reseed: SU3 download failed
19:25:20@305/warn - Reseed: failed to reseed from servers after 10 attempts
19:25:20@305/warn - Reseed: failed to reseed from servers
19:25:20@305/warn - Transports: Can't find routers for peer test ipv4
19:25:20@564/warn - Transports: 15 ephemeral keys generated at the time
Saw 'ephemeral keys' in the logs
making introducer /tmp/tahoe_6jm35x1/introducer_i2p
19:25:20@305/warn - Addressbook: Can't open /home/i2pd/data/addressbook/addresses.csv
19:25:20@305/error - Addressbook: resetting eTags
19:25:20@305/warn - Clients: Can't read /home/i2pd/data/tunnels.conf: /home/i2pd/data/tunnels.conf: cannot open file
Support for Python 3 is an incomplete work-in-progress. Use at your own risk.
'tahoe run' in '/tmp/tahoe_6jm35x1/introducer_i2p'
running node in '/tmp/tahoe_6jm35x1/introducer_i2p'
2021-09-03T19:25:24+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] twistd 21.7.0 (/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/bin/python 3.9.6) starting up.
2021-09-03T19:25:24+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] reactor class: twisted.internet.epollreactor.EPollReactor.
2021-09-03T19:25:26+0000 [twisted.logger._global#warn] /home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/foolscap/pb.py:870: builtins.BytesWarning: str() on a bytes instance
2021-09-03T19:25:26+0000 [-] Foolscap logging initialized
2021-09-03T19:25:26+0000 [-] Note to developers: twistd.log does not receive very much.
2021-09-03T19:25:26+0000 [-] Use 'flogtool tail -c NODEDIR/private/logport.furl' instead
2021-09-03T19:25:26+0000 [-] and read docs/logging.rst
2021-09-03T19:25:26+0000 [-] TahoeLAFSSite starting on 4561
2021-09-03T19:25:26+0000 [allmydata.webish.TahoeLAFSSite#info] Starting factory <allmydata.webish.TahoeLAFSSite object at 0x7f4d8d737820>
2021-09-03T19:25:26+0000 [-] My pid: 2659
2021-09-03T19:25:26+0000 [-] Listener starting on 48099
2021-09-03T19:25:26+0000 [foolscap.pb.Listener#info] Starting factory <Listener at 0x7f4d8e6360a0 on CleanupEndpoint(_wrapped=<twisted.internet.endpoints.AdoptedStreamServerEndpoint object at 0x7f4d8d3f0c70>, _fd=11, _listened=True) with tub yppt7cr525xkfnmxtiyqdkvkroy7uujx>
2021-09-03T19:25:26+0000 [-] Listener starting on 39441
2021-09-03T19:25:26+0000 [foolscap.pb.Listener#info] Starting factory <Listener at 0x7f4d8d3f0dc0 on <twisted.internet.endpoints.TCP4ServerEndpoint object at 0x7f4d8d3f0fa0> with tub zynt6hifjy7zt5fexm2fay5thtbvuizw>
2021-09-03T19:25:26+0000 [-] Listener starting on 48129
2021-09-03T19:25:26+0000 [foolscap.pb.Listener#info] Starting factory <Listener at 0x7f4d8d3f0940 on CleanupEndpoint(_wrapped=<twisted.internet.endpoints.AdoptedStreamServerEndpoint object at 0x7f4d8d3f01f0>, _fd=10, _listened=True) with tub fkndvbrrdsckkd6chfkhr2hgcfuptwkg>
2021-09-03T19:25:26+0000 [-] introducer running
Saw 'introducer running' in the logs
----------------------------- Captured stdout call -----------------------------
creating /tmp/tahoe_6jm35x1/carol_i2p
2021-09-03T19:25:27+0000 [twisted.logger._global#warn] /home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/foolscap/negotiate.py:540: builtins.BytesWarning: str() on a bytes instance
Support for Python 3 is an incomplete work-in-progress. Use at your own risk.
Traceback (most recent call last):
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/internet/defer.py", line 701, in errback
    self._startRunCallbacks(fail)
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/internet/defer.py", line 764, in _startRunCallbacks
    self._runCallbacks()
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/internet/defer.py", line 858, in _runCallbacks
    current.result = callback(  # type: ignore[misc]
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/internet/defer.py", line 1751, in gotResult
    current_context.run(_inlineCallbacks, r, gen, status)
--- <exception caught here> ---
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/internet/defer.py", line 1657, in _inlineCallbacks
    result = current_context.run(
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/python/failure.py", line 500, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/allmydata/scripts/create_node.py", line 465, in create_node
    yield write_node_config(c, config)
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/internet/defer.py", line 1657, in _inlineCallbacks
    result = current_context.run(
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/python/failure.py", line 500, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/allmydata/scripts/create_node.py", line 290, in write_node_config
    yield i2p_provider.create_config(reactor, config)
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/internet/defer.py", line 1657, in _inlineCallbacks
    result = current_context.run(
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/python/failure.py", line 500, in throwExceptionIntoGenerator
    return g.throw(self.type, self.value, self.tb)
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/allmydata/util/i2p_provider.py", line 113, in create_config
    sam_port = yield _connect_to_i2p(reactor, cli_config, txi2p)
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/twisted/internet/defer.py", line 1661, in _inlineCallbacks
    result = current_context.run(gen.send, result)
  File "/home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/allmydata/util/i2p_provider.py", line 98, in _connect_to_i2p
    raise ValueError("unable to reach any default I2P SAM port")
builtins.ValueError: unable to reach any default I2P SAM port
connecting to I2P (to allocate .i2p address)..
Unable to reach I2P SAM API at 'tcp:127.0.0.1:7656': Connection was refused by other side: 111: Connection refused.
--------------------------- Captured stdout teardown ---------------------------
2021-09-03T19:25:29+0000 [-] Received SIGTERM, shutting down.
2021-09-03T19:25:29+0000 [-] (TCP Port 4561 Closed)
2021-09-03T19:25:29+0000 [allmydata.webish.TahoeLAFSSite#info] Stopping factory <allmydata.webish.TahoeLAFSSite object at 0x7f4d8d737820>
2021-09-03T19:25:29+0000 [-] (TCP Port 48129 Closed)
2021-09-03T19:25:29+0000 [foolscap.pb.Listener#info] Stopping factory <Listener at 0x7f4d8d3f0940 on CleanupEndpoint(_wrapped=<twisted.internet.endpoints.AdoptedStreamServerEndpoint object at 0x7f4d8d3f01f0>, _fd=10, _listened=True) with tub fkndvbrrdsckkd6chfkhr2hgcfuptwkg>
2021-09-03T19:25:29+0000 [-] (TCP Port 39441 Closed)
2021-09-03T19:25:29+0000 [foolscap.pb.Listener#info] Stopping factory <Listener at 0x7f4d8d3f0dc0 on <twisted.internet.endpoints.TCP4ServerEndpoint object at 0x7f4d8d3f0fa0> with tub zynt6hifjy7zt5fexm2fay5thtbvuizw>
2021-09-03T19:25:29+0000 [-] (TCP Port 48099 Closed)
2021-09-03T19:25:29+0000 [foolscap.pb.Listener#info] Stopping factory <Listener at 0x7f4d8e6360a0 on CleanupEndpoint(_wrapped=<twisted.internet.endpoints.AdoptedStreamServerEndpoint object at 0x7f4d8d3f0c70>, _fd=11, _listened=True) with tub yppt7cr525xkfnmxtiyqdkvkroy7uujx>
2021-09-03T19:25:29+0000 [-] Main loop terminated.
2021-09-03T19:25:29+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] Server Shut Down.
=============================== warnings summary ===============================
.tox/integration3/lib/python3.9/site-packages/future/standard_library/__init__.py:65
  /home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/future/standard_library/__init__.py:65: DeprecationWarning: the imp module is deprecated in favour of importlib; see the module's documentation for alternative uses
    import imp

.tox/integration3/lib/python3.9/site-packages/foolscap/remoteinterface.py:191: 49 warnings
  /home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/foolscap/remoteinterface.py:191: DeprecationWarning: inspect.getargspec() is deprecated since Python 3.0, use inspect.signature() or inspect.getfullargspec()
    names, _, _, typeList = inspect.getargspec(method)

.tox/integration3/lib/python3.9/site-packages/pyutil/dictutil.py:185
  /home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/pyutil/dictutil.py:185: DeprecationWarning: deprecated
    warnings.warn("deprecated", DeprecationWarning)

.tox/integration3/lib/python3.9/site-packages/_pytest/config/__init__.py:1233
  /home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/lib/python3.9/site-packages/_pytest/config/__init__.py:1233: PytestConfigWarning: Unknown config option: twisted
  
    self._warn_or_fail_if_strict(f"Unknown config option: {key}\n")

-- Docs: https://docs.pytest.org/en/stable/warnings.html
=========================== short test summary info ============================
FAILED integration/test_i2p.py::test_i2p_service_storage - twisted.internet.e...
============ 1 failed, 25 passed, 52 warnings in 174.22s (0:02:54) =============
ERROR: InvocationError for command /home/runner/work/tahoe-lafs/tahoe-lafs/.tox/integration3/bin/py.test --timeout=1800 --coverage -v integration (exited with code 1)

Change History (0)

Note: See TracTickets for help on using tickets.