1435639 - Intermittent toolkit/components/extensions/test/mochitest/test_ext_contentscript_devtools_metadata.html | Test timed out.

Status: RESOLVED INCOMPLETE

(WebExtensions :: General, defect)

Description

[Treeherder Bug Filer]

Filed by: rgurzau [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=160337076&repo=mozilla-central

https://queue.taskcluster.net/v1/task/RQkkkxU7Sgqh7jXdWh_AmA/runs/0/artifacts/public/logs/live_backing.log

21:44:44     INFO -  1154 INFO SpawnTask.js | Entering test test_contentscript_devtools_sandbox_metadata
21:44:44     INFO -  1155 INFO Extension loaded
21:44:44     INFO -  Buffered messages finished
21:44:44    ERROR -  1156 INFO TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_devtools_metadata.html | Test timed out.
21:44:44     INFO -      reportError@SimpleTest/TestRunner.js:121:7
21:44:44     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7

Comment 1

[Intermittent Failures Robot]

1 failures in 735 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1435639&startday=2018-01-29&endday=2018-02-04&tree=all

Comment 2

[Tiberius Oros[:tiberius_oros]]

There have been 41 total failures in the last week.
This bug has been filed 5 days ago.

Occurrences per platform:
- windows10-64: 21
- Windows 7: 19
- windows7-32-nightly: 1

Occurrences per build type:
- pgo: 18
- opt: 16
- debug: 7

Here is a recent log file and a snippet with the failure:
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=161334760&lineNumber=14754

14:55:49     INFO -  TEST-INFO | screenshot: exit 0
14:55:49     INFO -  Buffered messages logged at 14:50:30
14:55:49     INFO -  1350 INFO SpawnTask.js | Entering test test_contentscript_devtools_sandbox_metadata
14:55:49     INFO -  1351 INFO Extension loaded
14:55:49     INFO -  Buffered messages finished
14:55:49    ERROR -  1352 INFO TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_contentscript_devtools_metadata.html | Test timed out.
14:55:49     INFO -      reportError@SimpleTest/TestRunner.js:121:7
14:55:49     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7

:ddurst, could you please have a look at this? Thank you!

Comment 3

[Intermittent Failures Robot]

50 failures in 702 pushes (0.071 failures/push) were associated with this bug in the last 7 days. 

This is the #22 most frequent failure this week.  

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **  

Repository breakdown:
* autoland: 17
* mozilla-inbound: 16
* mozilla-central: 15
* try: 2

Platform breakdown:
* windows10-64: 28
* windows7-32: 20
* windows7-32-nightly: 1
* windows10-64-devedition: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1435639&startday=2018-02-05&endday=2018-02-11&tree=all

Comment 4

[Eliza Balazs [:ebalazs_]]

In the last 7 days we have 49 failures.
They occur on Windows 7 (debug, pgo, opt), windows10-64, windows10-64-ccov (opt, debug, pgo).

Recent log example: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=162623915&lineNumber=2875

and a relevant part of it:
11:51:35    ERROR -  52 INFO TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_devtools_metadata.html | Test timed out.
11:51:35     INFO -      reportError@SimpleTest/TestRunner.js:121:7
11:51:35     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:51:35     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
11:51:35     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
11:51:35     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
11:51:35     INFO -      hookupTests@SimpleTest/setup.js:266:5
11:51:35     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
11:51:35     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
11:51:35     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
11:51:35     INFO -      hookup@SimpleTest/setup.js:246:5
11:51:35     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Cgenericworker%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
11:51:36     INFO -  Not taking screenshot here: see the one that was previously logged
11:51:36    ERROR -  53 INFO TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_devtools_metadata.html | Extension left running at test shutdown
11:51:36     INFO -      ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:109:7
:ddurst, can you please have a look at this? it seems that the failure rate is going up.

Comment 5

[Intermittent Failures Robot]

45 failures in 685 pushes (0.066 failures/push) were associated with this bug in the last 7 days. 

This is the #27 most frequent failure this week.  

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **  

Repository breakdown:
* autoland: 18
* mozilla-inbound: 16
* mozilla-central: 7
* mozilla-beta: 3
* try: 1

Platform breakdown:
* windows10-64: 30
* windows7-32: 11
* windows10-64-nightly: 2
* windows10-64-devedition: 1
* windows10-64-ccov: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1435639&startday=2018-02-12&endday=2018-02-18&tree=all

Comment 6

[Luca Greco [:rpl] [:luca] [:lgreco]]

The intermittent failures of this test are related to Bug 1435100, e.g. here is the NS_ERROR_FILE_ACCESS_DENIED error raised by nsIFile.createUnique when the test helpers are creating the generated xpi from a couple of the recent failures tracked by orangefactor:

- https://treeherder.mozilla.org/logviewer.html#?job_id=162761131&repo=mozilla-central&lineNumber=13251
- https://treeherder.mozilla.org/logviewer.html#?job_id=162761390&repo=mozilla-central&lineNumber=14641

Yesterday we landed the patch from Bug 1435100, expect that the fix landed should fix the frequent intermittency of this test too.

I'm adding myself as the assignee on this issue so that it is visible that we are working on it.

Comment 7

[Intermittent Failures Robot]

7 failures in 675 pushes (0.01 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-beta: 7

Platform breakdown:
* windows10-64: 3
* windows10-64-devedition: 2
* windows7-32: 1
* windows10-64-nightly: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1435639&startday=2018-02-19&endday=2018-02-25&tree=all

Comment 8

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup