694379 - intermittent test output timeout in test-tab-browser::testModuleListenersDontInteract

Status: RESOLVED FIXED

(Add-on SDK Graveyard :: General, defect, P1)

Description

[Myk Melez [:myk] [@mykmelez]]

After I updated the revision of the SDK being tested on mozilla-central yesterday <https://tbpl.mozilla.org/?noignore=1&rev=46a6d0fd13d5>, the Linux64 opt and Linux64 pgo tests failed with a test output timeout in test-tab-browser::testModuleListenersDontInteract, although the failures occurred at slightly different times, one before the first assertion, the other after the last (third) assertion.

https://tbpl.mozilla.org/php/getParsedLog.php?id=6823016&tree=Firefox&full=1
https://tbpl.mozilla.org/php/getParsedLog.php?id=6820074&tree=Firefox&full=1

Here's the relevant output in the latter case:

--------------------------------------------------------------------------------
info: executing 'test-tab-browser.testModuleListenersDontInteract'
info: pass: Correct global number of events
info: pass: Correct number of events on module 1
info: pass: Correct number of events on module 2
Traceback (most recent call last):
  File "bin/cfx", line 29, in <module>
    cuddlefish.run()
  File "/home/cltbld/talos-slave/test/build/addon-sdk-7bff862a8048/python-lib/cuddlefish/__init__.py", line 464, in run
    test_all(env_root, defaults=options.__dict__)
  File "/home/cltbld/talos-slave/test/build/addon-sdk-7bff862a8048/python-lib/cuddlefish/__init__.py", line 320, in test_all
    test_all_packages(env_root, defaults)
  File "/home/cltbld/talos-slave/test/build/addon-sdk-7bff862a8048/python-lib/cuddlefish/__init__.py", line 376, in test_all_packages
    defaults=defaults)
  File "/home/cltbld/talos-slave/test/build/addon-sdk-7bff862a8048/python-lib/cuddlefish/__init__.py", line 744, in run
    mobile_app_name=options.mobile_app_name)
  File "/home/cltbld/talos-slave/test/build/addon-sdk-7bff862a8048/python-lib/cuddlefish/runner.py", line 595, in run_app
    OUTPUT_TIMEOUT)
Exception: Test output exceeded timeout (60s).
--------------------------------------------------------------------------------

Subsequent test runs came up roses, making this intermittent (or, I suppose, currently a one-off):

https://tbpl.mozilla.org/?noignore=1&rev=85a61d68b152
https://tbpl.mozilla.org/?noignore=1&rev=b2f573afd17e
https://tbpl.mozilla.org/?noignore=1&rev=d6d3a1f90e2e
https://tbpl.mozilla.org/?noignore=1&rev=6d3a63f03f88

This update introduced the test output timeout, which I landed recently; how exciting that it saw action right away!  But it's perplexing that it did so in a test without a history of such behavior (and which behaved well afterward).

Comment 1

[Alexandre Poirot [:ochameau]]

Seems to have been automagically fixed.
Please reopen if I was wrong!