Closed Bug 847840 Opened 7 years ago Closed 4 years ago

TEST-UNEXPECTED-FAIL | addon-sdk/tests/test-selection.test Selection Listener on frame | Test output exceeded timeout (300s).

Categories

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

defect

Tracking

(Not tracked)

RESOLVED WORKSFORME

People

(Reporter: KWierso, Assigned: zer0)

References

Details

(Keywords: intermittent-failure)

Attachments

(2 files)

I'm seeing this intermittently in TBPL. Not sure what it means other than win debug being horribly slow, but filing this now to keep it tracked.


https://tbpl.mozilla.org/php/getParsedLog.php?id=20310183&tree=Jetpack&full=1 


TEST-UNEXPECTED-FAIL | tests/test-selection.test Selection Listener on frame | Test output exceeded timeout (60s).

Error Cleaning up: [Error 13] Access is denied: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpsswxsk.mozrunner\\cookies.sqlite-shm'

Error Cleaning up: [Error 13] Access is denied: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpsswxsk.mozrunner\\extensions\\cd6d9a88-956d-44eb-8d75-09585bdd30b5@jetpack.xpi'

Error Cleaning up: [Error 41] The directory is not empty: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpsswxsk.mozrunner\\extensions'

Error Cleaning up: [Error 41] The directory is not empty: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpsswxsk.mozrunner'

Traceback (most recent call last):

  File "bin/cfx", line 33, in <module>

    cuddlefish.run()

  File "C:\talos-slave\test\addonsdk-poller\addon-sdk-bce9c573ed1e\python-lib\cuddlefish\__init__.py", line 611, in run

    test_all(env_root, defaults=options.__dict__)

  File "C:\talos-slave\test\addonsdk-poller\addon-sdk-bce9c573ed1e\python-lib\cuddlefish\__init__.py", line 391, in test_all

    test_all_packages(env_root, defaults)

  File "C:\talos-slave\test\addonsdk-poller\addon-sdk-bce9c573ed1e\python-lib\cuddlefish\__init__.py", line 484, in test_all_packages

    env_root=env_root)

  File "C:\talos-slave\test\addonsdk-poller\addon-sdk-bce9c573ed1e\python-lib\cuddlefish\__init__.py", line 928, in run

    bundle_sdk=options.bundle_sdk)

  File "C:\talos-slave\test\addonsdk-poller\addon-sdk-bce9c573ed1e\python-lib\cuddlefish\runner.py", line 743, in run_app

    OUTPUT_TIMEOUT, test_name, parseable)

Exception: Test output exceeded timeout (60s).

TinderboxPrint:Jetpack<br/>163/0





https://tbpl.mozilla.org/php/getParsedLog.php?id=20332742&tree=Jetpack&full=1 

TEST-UNEXPECTED-FAIL | tests/test-selection.test Selection Listener on frame | Test output exceeded timeout (60s).

Error Cleaning up: [Error 13] Access is denied: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpwbz72m.mozrunner\\cookies.sqlite-shm'

Error Cleaning up: [Error 13] Access is denied: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpwbz72m.mozrunner\\extensions\\a98be923-8c4d-40b9-b0b7-d27c716d0f88@jetpack.xpi'

Error Cleaning up: [Error 41] The directory is not empty: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpwbz72m.mozrunner\\extensions'

Error Cleaning up: [Error 13] Access is denied: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpwbz72m.mozrunner\\places.sqlite-shm'

Error Cleaning up: [Error 13] Access is denied: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpwbz72m.mozrunner\\startupCache\\startupCache.4.little'

Error Cleaning up: [Error 41] The directory is not empty: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpwbz72m.mozrunner\\startupCache'

Error Cleaning up: [Error 13] Access is denied: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpwbz72m.mozrunner\\webappsstore.sqlite-shm'

Error Cleaning up: [Error 41] The directory is not empty: 'c:\\docume~1\\cltbld\\locals~1\\temp\\tmpwbz72m.mozrunner'

Traceback (most recent call last):

  File "bin/cfx", line 33, in <module>

    cuddlefish.run()

  File "c:\talos-slave\test\addonsdk-poller\addon-sdk-34acf83542ba\python-lib\cuddlefish\__init__.py", line 611, in run

    test_all(env_root, defaults=options.__dict__)

  File "c:\talos-slave\test\addonsdk-poller\addon-sdk-34acf83542ba\python-lib\cuddlefish\__init__.py", line 391, in test_all

    test_all_packages(env_root, defaults)

  File "c:\talos-slave\test\addonsdk-poller\addon-sdk-34acf83542ba\python-lib\cuddlefish\__init__.py", line 484, in test_all_packages

    env_root=env_root)

  File "c:\talos-slave\test\addonsdk-poller\addon-sdk-34acf83542ba\python-lib\cuddlefish\__init__.py", line 928, in run

    bundle_sdk=options.bundle_sdk)

  File "c:\talos-slave\test\addonsdk-poller\addon-sdk-34acf83542ba\python-lib\cuddlefish\runner.py", line 743, in run_app

    OUTPUT_TIMEOUT, test_name, parseable)

Exception: Test output exceeded timeout (60s).

TinderboxPrint:Jetpack<br/>163/0
Only happens on OSX:
https://tbpl.mozilla.org/php/getParsedLog.php?id=20333278&tree=Jetpack

So not win-specific, but yes, most likely due to slowness race condition.
(In reply to Alexandre Poirot (:ochameau) from comment #1)
> Only happens on OSX:

s/Only/Also/
Matteo, can you tackle this one?
Flags: needinfo?(zer0)
Priority: -- → P2
Assignee: nobody → zer0
Pointer to Github pull-request
Flags: needinfo?(zer0)
Attachment #730774 - Flags: review?(dtownsend+bugmail)
Attachment #730774 - Flags: review?(dtownsend+bugmail) → review+
Commits pushed to master at https://github.com/mozilla/addon-sdk

https://github.com/mozilla/addon-sdk/commit/a3905ee2341a9f6695189c989e3bc8fba187e9fd
Bug 847840 - TEST-UNEXPECTED-FAIL | tests/test-selection.test Selection Listener on frame | Test output exceeded timeout (60s).

Tried to wait the `document-shown` notification before resolve the window, and also move the shutdown functions (`close`, `loader.unload`, `done`) for the test that sometimes is failing.

https://github.com/mozilla/addon-sdk/commit/9f1dfe531868b394f2856f702d80c8a3349abba4
Merge pull request #908 from ZER0/selection-timeout/847840

Bug 847840 - TEST-UNEXPECTED-FAIL | tests/test-selection.test Selection Listener on frame r=@Mossop
In the last tests the error seems not be present. However, because it was a bit random, I would wait another couple of tests' run before mark this bug as fixed.
It seems the error is fixed! \o/
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → FIXED
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Haven't seen this in two months, closing.
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → FIXED
Duplicate of this bug: 943593
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → DUPLICATE
Duplicate of bug: 970062
it's a different bug, slightly different test name.
Status: RESOLVED → REOPENED
Resolution: DUPLICATE → ---
Summary: TEST-UNEXPECTED-FAIL | tests/test-selection.test Selection Listener on frame | Test output exceeded timeout (60s). → TEST-UNEXPECTED-FAIL | addon-sdk/tests/test-selection.test Selection Listener on frame | Test output exceeded timeout (300s).
Inactive; closing (see bug 1180138).
Status: REOPENED → RESOLVED
Closed: 5 years ago4 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.