1831323 - Intermittent TV browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | Test timed out -

Status: RESOLVED DUPLICATE of bug 1714342

(WebExtensions :: Untriaged, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=414629516&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YV2rekQpTO61MOVXMwny9A/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YV2rekQpTO61MOVXMwny9A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2023-05-04T13:18:09.102Z] 13:18:09     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | exactly one menu item found - 
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - Console message: [JavaScript Error: "FrameData missing for {07c168d9-6f13-4d98-aba0-5dac3ea61732} page moz-extension://9aa15a71-68f9-412c-a9e4-a969cfc8d571/test.html" {file: "resource://gre/modules/ExtensionPageChild.jsm" line: 462}]
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - initExtensionContext@resource://gre/modules/ExtensionPageChild.jsm:462:10
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - initExtensionDocument@resource://gre/modules/ExtensionProcessScript.jsm:385:31
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - 
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - Console message: [JavaScript Warning: "Layout was forced before the page was fully loaded. If stylesheets are not yet loaded this may cause a flash of unstyled content." {file: "moz-extension://9aa15a71-68f9-412c-a9e4-a969cfc8d571/test.html" line: 0}]
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - Buffered messages finished
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | Test timed out - 
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | no tasks awaiting on messages - Got "[\"test-opened\"]", expected "[]"
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - Stack trace:
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/browser-test.js:test_is:1612
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:51
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/browser-test.js:nextTest:703
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1440
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1380
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1122
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1056
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | Extension left running at test shutdown - 
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - Stack trace:
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/browser-test.js:test_ok:1584
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:132
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/browser-test.js:nextTest:703
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1440
[task 2023-05-04T13:18:09.103Z] 13:18:09     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1380
[task 2023-05-04T13:18:09.104Z] 13:18:09     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1122
[task 2023-05-04T13:18:09.104Z] 13:18:09     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1056
[task 2023-05-04T13:18:09.104Z] 13:18:09     INFO - GECKO(6570) | [Child 6640, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-04T13:18:09.104Z] 13:18:09     INFO - Console message: [JavaScript Error: "sendRemoveListener on closed conduit {07c168d9-6f13-4d98-aba0-5dac3ea61732}.137438953781" {file: "resource://gre/modules/ConduitsChild.jsm" line: 115}]
[task 2023-05-04T13:18:09.104Z] 13:18:09     INFO - _send@resource://gre/modules/ConduitsChild.jsm:115:13
[task 2023-05-04T13:18:09.104Z] 13:18:09     INFO - removeListener@resource://gre/modules/ExtensionChild.jsm:671:34
[task 2023-05-04T13:18:09.104Z] 13:18:09     INFO - removeListener@resource://gre/modules/ExtensionChild.jsm:931:40
[task 2023-05-04T13:18:09.104Z] 13:18:09     INFO - getAPI/register/api.menus.onClicked@chrome://browser/content/child/ext-menus.js:289:21
[task 2023-05-04T13:18:09.105Z] 13:18:09     INFO - removeListener@resource://gre/modules/ExtensionCommon.jsm:2885:7
[task 2023-05-04T13:18:09.105Z] 13:18:09     INFO - revoke@resource://gre/modules/ExtensionCommon.jsm:2907:12
[task 2023-05-04T13:18:09.105Z] 13:18:09     INFO - close@resource://gre/modules/ExtensionCommon.jsm:2912:10
[task 2023-05-04T13:18:09.105Z] 13:18:09     INFO - unload@resource://gre/modules/ExtensionCommon.jsm:991:11
[task 2023-05-04T13:18:09.105Z] 13:18:09     INFO - unload@resource://gre/modules/ExtensionPageChild.jsm:301:11
[task 2023-05-04T13:18:09.105Z] 13:18:09     INFO - unload@resource://gre/modules/ExtensionPageChild.jsm:338:11
[task 2023-05-04T13:18:09.105Z] 13:18:09     INFO - destroyExtensionContext@resource://gre/modules/ExtensionPageChild.jsm:498:15
[task 2023-05-04T13:18:09.106Z] 13:18:09     INFO - observe@resource://gre/modules/ExtensionPageChild.jsm:407:12
[task 2023-05-04T13:18:09.106Z] 13:18:09     INFO - 
[task 2023-05-04T13:18:09.152Z] 13:18:09     INFO - GECKO(6570) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2023-05-04T13:18:09.155Z] 13:18:09     INFO - GECKO(6570) | MEMORY STAT | vsize 3579MB | residentFast 467MB | heapAllocated 234MB
[task 2023-05-04T13:18:09.158Z] 13:18:09     INFO - TEST-OK | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | took 48332ms
[task 2023-05-04T13:18:09.160Z] 13:18:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-05-04T13:18:09.160Z] 13:18:09     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | Found a tab after previous test timed out: http://mochi.test:8888/browser/browser/components/extensions/test/browser/context.html?test=commands - 
[task 2023-05-04T13:18:09.267Z] 13:18:09     INFO - checking window state
[task 2023-05-04T13:18:09.383Z] 13:18:09     INFO - GECKO(6570) | Completed ShutdownLeaks collections in process 6570
[task 2023-05-04T13:18:09.387Z] 13:18:09     INFO - TEST-START | Shutdown

Comment 1

[BugBot [:suhaib / :marco/ :calixte]]

:robwu, since you are the author of the regressor, bug 1827910, could you take a look?

For more information, please visit BugBot documentation.

Comment 2

[Rob Wu [:robwu]]

bug 1827910 did not change the behavior, it only added a comment to the test that caused it to run in TV. This is not a regression, but a pre-existing issue.