Closed Bug 1645599 Opened 5 years ago Closed 5 years ago

Intermittent Tier 2 TC toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | Test timed out.

Categories

(WebExtensions :: General, defect, P5)

Product:
WebExtensions
Component:
General
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=306209595&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bHqfWvGjSUiSDiWtiKuUxQ/runs/0/artifacts/public/logs/live_backing.log

[task 2020-06-13T11:15:59.525Z] 11:15:59     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html
[task 2020-06-13T11:15:59.630Z] 11:15:59     INFO - GECKO(1285) | 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_fission_frame.html" line: 0}]
[task 2020-06-13T11:16:00.201Z] 11:16:00     INFO - GECKO(1285) | [CodeCoverage] Requested flush for 1285.
[task 2020-06-13T11:16:00.444Z] 11:16:00     INFO - GECKO(1285) | [CodeCoverage] flush completed.
[task 2020-06-13T11:16:01.115Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] JS flush completed.
[task 2020-06-13T11:16:01.115Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] Requested flush for 1400.
[task 2020-06-13T11:16:01.117Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] Requested flush for 1469.
[task 2020-06-13T11:16:01.117Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] Requested flush for 1384.
[task 2020-06-13T11:16:01.375Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] flush completed.
[task 2020-06-13T11:16:01.395Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] JS flush completed.
[task 2020-06-13T11:16:01.632Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] flush completed.
[task 2020-06-13T11:16:01.708Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] JS flush completed.
[task 2020-06-13T11:16:01.950Z] 11:16:01     INFO - GECKO(1285) | [CodeCoverage] flush completed.
[task 2020-06-13T11:16:02.146Z] 11:16:02     INFO - GECKO(1285) | [CodeCoverage] JS flush completed.
[task 2020-06-13T11:16:02.146Z] 11:16:02     INFO - GECKO(1285) | JavaScript error: resource://testing-common/PerTestCoverageUtils.jsm, line 76: NS_ERROR_FILE_DIR_NOT_EMPTY: Component returned failure code: 0x80520014 (NS_ERROR_FILE_DIR_NOT_EMPTY) [nsIFile.moveTo]
[task 2020-06-13T11:21:25.276Z] 11:21:25     INFO - TEST-INFO | started process screentopng
[task 2020-06-13T11:21:25.460Z] 11:21:25     INFO - TEST-INFO | screentopng: exit 0
[task 2020-06-13T11:21:25.460Z] 11:21:25     INFO - Buffered messages logged at 11:15:59
[task 2020-06-13T11:21:25.461Z] 11:21:25     INFO - add_task | Entering test test_content_script_cross_origin_frame
[task 2020-06-13T11:21:25.461Z] 11:21:25     INFO - Extension loaded
[task 2020-06-13T11:21:25.461Z] 11:21:25     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | CS can access page DOM - Expected: Sample text, Actual: Sample text 
[task 2020-06-13T11:21:25.461Z] 11:21:25     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | undefined - Expected: 1.0, Actual: 1.0 
[task 2020-06-13T11:21:25.461Z] 11:21:25     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | undefined - Expected: Generated extension, Actual: Generated extension 
[task 2020-06-13T11:21:25.461Z] 11:21:25     INFO - Buffered messages logged at 11:16:00
[task 2020-06-13T11:21:25.462Z] 11:21:25     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | sender frameId is ok 
[task 2020-06-13T11:21:25.462Z] 11:21:25     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | url is ok 
[task 2020-06-13T11:21:25.462Z] 11:21:25     INFO - content script received tabs.sendMessage
[task 2020-06-13T11:21:25.462Z] 11:21:25     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | Got correct response - Expected: 21, Actual: 21 
[task 2020-06-13T11:21:25.462Z] 11:21:25     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | undefined assertion name 
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO - add_task | Leaving test test_content_script_cross_origin_frame
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO - Buffered messages finished
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html | Test timed out. 
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.463Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     Async*TestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     hookupTests@SimpleTest/setup.js:294:12
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:46:13
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:59:28
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:55:3
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     hookup@SimpleTest/setup.js:270:20
[task 2020-06-13T11:21:25.464Z] 11:21:25     INFO -     EventHandlerNonNull*@toolkit/components/extensions/test/mochitest?autorun=1&closeWhenDone=1&consoleLevel=INFO&testname=tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_fission_frame.html&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2020-06-13T11:21:26.284Z] 11:21:26    ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-06-13T11:21:26.300Z] 11:21:26     INFO - GECKO(1285) | [CodeCoverage] Requested flush for 1285.
[task 2020-06-13T11:21:26.517Z] 11:21:26     INFO - GECKO(1285) | [CodeCoverage] flush completed.
[task 2020-06-13T11:21:27.175Z] 11:21:27     INFO - GECKO(1285) | [CodeCoverage] JS flush completed.

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=309567651&repo=mozilla-central&lineNumber=1992

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED
Closed: 5 years ago → 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.