1750408 - Intermittent toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html | Test timed out. -

Status: RESOLVED INCOMPLETE

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=364255618&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/B6IVDW_AQ3eBijfoUySiFA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2022-01-16T01:08:07.901Z] 01:08:07     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html
[task 2022-01-16T01:08:07.936Z] 01:08:07     INFO - GECKO(4755) | Console message: Warning: attempting to write 9814 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes.
[task 2022-01-16T01:08:08.066Z] 01:08:08     INFO - GECKO(4755) | JavaScript error: chrome://browser/content/places/browserPlacesViews.js, line 137: Error: No DOM node set for aPlacesNode.
[task 2022-01-16T01:08:08.066Z] 01:08:08     INFO - GECKO(4755) | node.type: 0. node.parent: [xpconnect wrapped nsINavHistoryResultNode]
[task 2022-01-16T01:08:08.068Z] 01:08:08     INFO - GECKO(4755) | Console message: [JavaScript Error: "Error: No DOM node set for aPlacesNode.
[task 2022-01-16T01:08:08.070Z] 01:08:08     INFO - GECKO(4755) | node.type: 0. node.parent: [xpconnect wrapped nsINavHistoryResultNode]" {file: "chrome://browser/content/places/browserPlacesViews.js" line: 137}]
[task 2022-01-16T01:08:08.071Z] 01:08:08     INFO - GECKO(4755) | PVB__getDOMNodeForPlacesNode@chrome://browser/content/places/browserPlacesViews.js:137:13
[task 2022-01-16T01:08:08.071Z] 01:08:08     INFO - GECKO(4755) | PVB_nodeTitleChanged@chrome://browser/content/places/browserPlacesViews.js:532:20
[task 2022-01-16T01:08:08.096Z] 01:08:08     INFO - GECKO(4755) | JavaScript error: chrome://browser/content/places/browserPlacesViews.js, line 137: Error: No DOM node set for aPlacesNode.
[task 2022-01-16T01:08:08.097Z] 01:08:08     INFO - GECKO(4755) | node.type: 0. node.parent: [xpconnect wrapped nsINavHistoryResultNode]
[task 2022-01-16T01:08:08.097Z] 01:08:08     INFO - GECKO(4755) | Console message: [JavaScript Error: "Error: No DOM node set for aPlacesNode.
[task 2022-01-16T01:08:08.098Z] 01:08:08     INFO - GECKO(4755) | node.type: 0. node.parent: [xpconnect wrapped nsINavHistoryResultNode]" {file: "chrome://browser/content/places/browserPlacesViews.js" line: 137}]
[task 2022-01-16T01:08:08.098Z] 01:08:08     INFO - GECKO(4755) | PVB__getDOMNodeForPlacesNode@chrome://browser/content/places/browserPlacesViews.js:137:13
[task 2022-01-16T01:08:08.099Z] 01:08:08     INFO - GECKO(4755) | PVB_nodeIconChanged@chrome://browser/content/places/browserPlacesViews.js:514:20
[task 2022-01-16T01:13:24.879Z] 01:13:24     INFO - TEST-INFO | started process screencapture
[task 2022-01-16T01:13:24.966Z] 01:13:24     INFO - TEST-INFO | screencapture: exit 0
[task 2022-01-16T01:13:24.966Z] 01:13:24     INFO - Buffered messages logged at 01:08:07
[task 2022-01-16T01:13:24.966Z] 01:13:24     INFO - add_task | Entering test connect_from_background_frame
[task 2022-01-16T01:13:24.967Z] 01:13:24     INFO - Cannot load remote content in parent process; skipping test task
[task 2022-01-16T01:13:24.967Z] 01:13:24     INFO - add_task | Leaving test connect_from_background_frame
[task 2022-01-16T01:13:24.968Z] 01:13:24     INFO - add_task | Entering test connect_from_content_script_in_frame
[task 2022-01-16T01:13:24.968Z] 01:13:24     INFO - Extension loaded
[task 2022-01-16T01:13:24.968Z] 01:13:24     INFO - Buffered messages finished
[task 2022-01-16T01:13:24.969Z] 01:13:24     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html | Test timed out. - 
[task 2022-01-16T01:13:26.020Z] 01:13:26     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-01-16T01:13:26.022Z] 01:13:26     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html | Extension left running at test shutdown 
[task 2022-01-16T01:13:26.022Z] 01:13:26     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-01-16T01:13:26.022Z] 01:13:26     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:129:18
[task 2022-01-16T01:13:26.022Z] 01:13:26     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2022-01-16T01:13:26.022Z] 01:13:26     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1501:3
[task 2022-01-16T01:13:26.022Z] 01:13:26     INFO -     killTest@SimpleTest/TestRunner.js:194:22
[task 2022-01-16T01:13:26.022Z] 01:13:26     INFO -     async*delayedKillTest@SimpleTest/TestRunner.js:231:17
[task 2022-01-16T01:13:26.022Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:229:17
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:242:15
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:521:16
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     Async*TestRunner.runTests@SimpleTest/TestRunner.js:508:48
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     RunSet.runtests@SimpleTest/setup.js:232:14
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     RunSet.runall@SimpleTest/setup.js:211:12
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     hookupTests@SimpleTest/setup.js:308:12
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:51:13
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:64:28
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:60:3
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO -     hookup@SimpleTest/setup.js:284:20
[task 2022-01-16T01:13:26.023Z] 01:13:26     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Fvar%2Ffolders%2F17%2F4ly6sry13596bwqdvczzhjk8000014%2FT&cleanupCrashes=true:11:1
[task 2022-01-16T01:13:26.025Z] 01:13:26     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-01-16T01:13:26.025Z] 01:13:26     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html | no tasks awaiting on messages - got "[\"tab_port_checked_and_tab_closed\"]", expected "[]"
[task 2022-01-16T01:13:26.025Z] 01:13:26     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2022-01-16T01:13:26.025Z] 01:13:26     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:48:18
[task 2022-01-16T01:13:26.025Z] 01:13:26     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2022-01-16T01:13:26.028Z] 01:13:26     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-01-16T01:13:26.029Z] 01:13:26     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html | Test left extra windows or tabs: {"extraWindows":{},"extraTabs":{"0":"https://example.org/tests/toolkit/components/extensions/test/mochitest/file_contains_iframe.html"}}
[task 2022-01-16T01:13:26.029Z] 01:13:26     INFO -  
[task 2022-01-16T01:13:26.029Z] 01:13:26     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-01-16T01:13:26.029Z] 01:13:26     INFO -     @toolkit/components/extensions/test/mochitest/head.js:33:9
[task 2022-01-16T01:13:26.029Z] 01:13:26     INFO -     async*executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2022-01-16T01:13:26.029Z] 01:13:26     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1497:7
[task 2022-01-16T01:13:26.029Z] 01:13:26     INFO - GECKO(4755) | MEMORY STAT | vsize 6597MB | residentFast 102MB | heapAllocated 10MB
[task 2022-01-16T01:13:27.147Z] 01:13:27     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html | took 319247ms
[task 2022-01-16T01:13:30.477Z] 01:13:30     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2022-01-16T01:13:30.482Z] 01:13:30    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2022-01-16T01:13:30.482Z] 01:13:30    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html logged result after SimpleTest.finish(): no tasks awaiting on messages
[task 2022-01-16T01:13:30.483Z] 01:13:30    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_iframe.html logged result after SimpleTest.finish(): Test left extra windows or tabs: {"extraWindows":{},"extraTabs":{"0":"https://example.org/tests/toolkit/components/extensions/test/mochitest/file_contains_iframe.html"}}
[task 2022-01-16T01:13:30.483Z] 01:13:30    ERROR - 
[task 2022-01-16T01:13:30.486Z] 01:13:30     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_runtime_connect_twoway.html

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1750408&startday=2022-01-10&endday=2022-01-16&tree=all

Comment 2

[BugBot [:suhaib / :marco]]

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

Comment 3

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=367040784&repo=autoland

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • opt: 1
• macosx1015-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1750408&startday=2022-02-07&endday=2022-02-13&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2
• mozilla-central: 1

Platform and build breakdown:

• windows10-32-2004-shippable-qr: 2
  • opt: 2
• macosx1015-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1750408&startday=2022-02-21&endday=2022-02-27&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1750408&startday=2022-02-28&endday=2022-03-06&tree=all

Comment 7

[BugBot [:suhaib / :marco]]

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