Closed Bug 1439596 Opened 6 years ago Closed 6 years ago

Intermittent toolkit/components/extensions/test/mochitest/test_ext_contentscript_exporthelpers.html | Test timed out.

Categories

(WebExtensions :: General, defect)

defect
Not set
normal

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Assigned: rpl)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell needswork:owner])

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=163191130&repo=autoland

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

13:04:22     INFO -  1155 INFO TEST-START | toolkit/components/extensions/test/mochitest/test_ext_contentscript_exporthelpers.html
13:04:22     INFO -  GECKO(4872) | JavaScript error: resource://testing-common/ExtensionTestCommon.jsm, line 266: NS_ERROR_FILE_ACCESS_DENIED: Component returned failure code: 0x80520015 (NS_ERROR_FILE_ACCESS_DENIED) [nsIFile.createUnique]
13:04:22     INFO -  GECKO(4872) | JavaScript error: chrome://specialpowers/content/SpecialPowersObserverAPI.js, line 599: TypeError: extension is undefined
13:04:22     INFO -  GECKO(4872) | Console message: [JavaScript Error: "NS_ERROR_FILE_ACCESS_DENIED: Component returned failure code: 0x80520015 (NS_ERROR_FILE_ACCESS_DENIED) [nsIFile.createUnique]" {file: "resource://testing-common/ExtensionTestCommon.jsm" line: 266}]
13:04:22     INFO -  GECKO(4872) | Console message: [JavaScript Error: "TypeError: extension is undefined" {file: "chrome://specialpowers/content/SpecialPowersObserverAPI.js" line: 599}]
13:04:22     INFO -  GECKO(4872) | Console message: [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_exporthelpers.html" line: 0}]
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] Shutdown: decoder=0000023F07D87800 state machine=0000023F05CC3000
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] Enter: state machine=0000023F05C4C7A0 reader=0000023F004F63A0
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] Shutdown: reader=0000023F079A7000 shutdown demuxer=0000023F0790B2E0
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] Shutdown: pool=0000023F0791F460 count=1
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] operator (): pool=0000023F0791F460 shutdown=true count=0
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] TearDownDecoders: reader=0000023F079A7000 shut down audio task queue
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] TearDownDecoders: reader=0000023F079A7000 shut down video task queue
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] FinishShutdown: state machine=0000023F05CC3000
13:04:28     INFO -  GECKO(4872) | [DEBUG SHUTDOWN] Unregister: decoder=0000023F07D87800, count=0
13:09:10     INFO -  GECKO(4872) | Console message: [JavaScript Error: "getScreenshot error: Win error 2 during operation open on file C:\Users\GenericWorker\AppData\Local\Temp\tmppqztvo.mozrunner\thumbnails\7a2a34de5c4886295371451e2c33f235.png (The system cannot find the file specified.
13:09:10     INFO -  GECKO(4872) | )" {file: "resource://activity-stream/lib/Screenshots.jsm" line: 64}]
13:09:10     INFO -  GECKO(4872) | getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:64:7
13:09:10     INFO -  GECKO(4872) | async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:107:32
13:09:10     INFO -  GECKO(4872) | async*_fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:185:13
13:09:10     INFO -  GECKO(4872) | async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:134:9
13:09:10     INFO -  GECKO(4872) | async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:153:25
13:09:10     INFO -  GECKO(4872) | async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:311:9
13:09:10     INFO -  GECKO(4872) | _middleware/</<@resource://activity-stream/lib/Store.jsm:49:11
13:09:10     INFO -  GECKO(4872) | Store/this[method]@resource://activity-stream/lib/Store.jsm:28:55
13:09:10     INFO -  GECKO(4872) | init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:18:41
13:09:10     INFO -  GECKO(4872) | notify@resource://gre/modules/Timer.jsm:42:7
13:09:10     INFO -  GECKO(4872) | Console message: [JavaScript Error: "getScreenshot error: Win error 2 during operation open on file C:\Users\GenericWorker\AppData\Local\Temp\tmppqztvo.mozrunner\thumbnails\a6bf1757fff057f266b697df9cf176fd.png (The system cannot find the file specified.
13:09:10     INFO -  GECKO(4872) | )" {file: "resource://activity-stream/lib/Screenshots.jsm" line: 64}]
13:09:10     INFO -  GECKO(4872) | getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:64:7
13:09:10     INFO -  GECKO(4872) | async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:107:32
13:09:10     INFO -  GECKO(4872) | async*_fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:185:13
13:09:10     INFO -  GECKO(4872) | async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:134:9
13:09:10     INFO -  GECKO(4872) | async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:153:25
13:09:10     INFO -  GECKO(4872) | async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:311:9
13:09:10     INFO -  GECKO(4872) | _middleware/</<@resource://activity-stream/lib/Store.jsm:49:11
13:09:10     INFO -  GECKO(4872) | Store/this[method]@resource://activity-stream/lib/Store.jsm:28:55
13:09:10     INFO -  GECKO(4872) | init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:18:41
13:09:10     INFO -  GECKO(4872) | notify@resource://gre/modules/Timer.jsm:42:7
13:09:41     INFO -  TEST-INFO | started process screenshot
13:09:41     INFO -  TEST-INFO | screenshot: exit 0
13:09:41     INFO -  Buffered messages logged at 13:04:22
13:09:41     INFO -  1156 INFO SpawnTask.js | Entering test test_contentscript_exportHelpers
13:09:41     INFO -  1157 INFO Extension loaded
13:09:41     INFO -  Buffered messages finished
13:09:41    ERROR -  1158 INFO TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_exporthelpers.html | Test timed out.
13:09:41     INFO -      reportError@SimpleTest/TestRunner.js:121:7
13:09:41     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:41     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
13:09:41     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
13:09:41     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
13:09:41     INFO -      hookupTests@SimpleTest/setup.js:266:5
13:09:41     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
13:09:41     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
13:09:41     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
13:09:41     INFO -      hookup@SimpleTest/setup.js:246:5
13:09:41     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
13:09:42     INFO -  Not taking screenshot here: see the one that was previously logged
13:09:42    ERROR -  1159 INFO TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_exporthelpers.html | Extension left running at test shutdown
13:09:42     INFO -      ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:109:7
13:09:42     INFO -      executeCleanupFunction@SimpleTest/SimpleTest.js:1217:19
13:09:42     INFO -      SimpleTest.finish@SimpleTest/SimpleTest.js:1230:5
13:09:42     INFO -      killTest@SimpleTest/TestRunner.js:130:7
13:09:42     INFO -      delayedKillTest@SimpleTest/TestRunner.js:157:47
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:157:7
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:09:42     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
13:09:42     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
13:09:42     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
13:09:42     INFO -      hookupTests@SimpleTest/setup.js:266:5
13:09:42     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
13:09:42     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
13:09:42     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
13:09:42     INFO -      hookup@SimpleTest/setup.js:246:5
13:09:42     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
13:09:42     INFO -  GECKO(4872) | JavaScript error: chrome://specialpowers/content/SpecialPowersObserverAPI.js, line 644: TypeError: extension is undefined
13:09:42     INFO -  GECKO(4872) | Console message: [JavaScript Error: "TypeError: extension is undefined" {file: "chrome://specialpowers/content/SpecialPowersObserverAPI.js" line: 644}]
13:10:11     INFO -  Not taking screenshot here: see the one that was previously logged
13:10:11    ERROR -  1160 INFO TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_exporthelpers.html | Test timed out.
13:10:11     INFO -      reportError@SimpleTest/TestRunner.js:121:7
13:10:11     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:10:11     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
13:10:11     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
13:10:11     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
13:10:11     INFO -      hookupTests@SimpleTest/setup.js:266:5
13:10:11     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
13:10:11     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
13:10:11     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
13:10:11     INFO -      hookup@SimpleTest/setup.js:246:5
13:10:11     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
13:10:12     INFO -  1161 ERROR [SimpleTest.finish()] this test already called finish!
13:10:12     INFO -  GECKO(4872) | MEMORY STAT | vsize 2098649MB | vsizeMaxContiguous 128196131MB | residentFast 110MB | heapAllocated 21MB
13:10:12     INFO -  1162 INFO TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_contentscript_exporthelpers.html | took 350062ms
13:10:12     INFO -  1163 INFO TEST-START | toolkit/components/extensions/test/mochitest/test_ext_contentscript_incognito.html
13:10:12     INFO -  GECKO(4872) | JavaScript error: resource://testing-common/ExtensionTestCommon.jsm, line 266: NS_ERROR_FILE_ACCESS_DENIED: Component returned failure code: 0x80520015 (NS_ERROR_FILE_ACCESS_DENIED) [nsIFile.createUnique]
13:10:12     INFO -  GECKO(4872) | JavaScript error: chrome://specialpowers/content/SpecialPowersObserverAPI.js, line 599: TypeError: extension is undefined
13:10:12     INFO -  GECKO(4872) | Console message: [JavaScript Error: "NS_ERROR_FILE_ACCESS_DENIED: Component returned failure code: 0x80520015 (NS_ERROR_FILE_ACCESS_DENIED) [nsIFile.createUnique]" {file: "resource://testing-common/ExtensionTestCommon.jsm" line: 266}]
13:10:12     INFO -  GECKO(4872) | Console message: [JavaScript Error: "TypeError: extension is undefined" {file: "chrome://specialpowers/content/SpecialPowersObserverAPI.js" line: 599}]
13:10:12     INFO -  GECKO(4872) | Console message: [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_incognito.html" line: 0}]
13:15:41     INFO -  Not taking screenshot here: see the one that was previously logged
13:15:41     INFO -  Buffered messages logged at 13:10:12
13:15:41     INFO -  1164 INFO SpawnTask.js | Entering test test_contentscript_incognito
13:15:41     INFO -  1165 INFO Extension loaded
13:15:41     INFO -  Buffered messages finished
13:15:41    ERROR -  1166 INFO TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_incognito.html | Test timed out.
13:15:41     INFO -      reportError@SimpleTest/TestRunner.js:121:7
13:15:41     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
13:15:41     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
13:15:41     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
13:15:41     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
13:15:41     INFO -      hookupTests@SimpleTest/setup.js:266:5
13:15:41     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
13:15:41     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
Depends on: 1435100
This test has started to fail intermittently right after Bug 1434777 has disabled the test file that was listed right before this one in the mochitest-common.ini file.

The reasons behind the high intermittency of the tests should be the same fixed by the patch landed yesterday from Bug 1435100, and so we expect that the fix landed should also fix the frequent intermittency of this test.

I'm adding myself as the assignee on this issue so that it is visible that we are working on it.
Assignee: nobody → lgreco
Status: NEW → ASSIGNED
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: ASSIGNED → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Product: Toolkit → WebExtensions
You need to log in before you can comment on or make changes to this bug.