1763227 - Intermittent browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | Test timed out | Extension left running at test shutdow

Status: RESOLVED DUPLICATE of bug 1763043

(WebExtensions :: Untriaged, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-04-05T19:21:54.305Z] 19:21:54     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | lastError message for missing id - Expected: The menu id test-click already exists in menus.create., Actual: The menu id test-click already exists in menus.create. - 
[task 2022-04-05T19:21:54.306Z] 19:21:54     INFO - Buffered messages finished
[task 2022-04-05T19:21:54.307Z] 19:21:54     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | Test timed out - 
[task 2022-04-05T19:21:54.308Z] 19:21:54     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-04-05T19:21:54.315Z] 19:21:54     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | Extension left running at test shutdown - 
[task 2022-04-05T19:21:54.315Z] 19:21:54     INFO - Stack trace:
[task 2022-04-05T19:21:54.315Z] 19:21:54     INFO - chrome://mochikit/content/browser-test.js:test_ok:1394
[task 2022-04-05T19:21:54.316Z] 19:21:54     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:133
[task 2022-04-05T19:21:54.316Z] 19:21:54     INFO - chrome://mochikit/content/browser-test.js:nextTest:561
[task 2022-04-05T19:21:54.316Z] 19:21:54     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1282
[task 2022-04-05T19:21:54.316Z] 19:21:54     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1222
[task 2022-04-05T19:21:54.317Z] 19:21:54     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:978
[task 2022-04-05T19:21:54.317Z] 19:21:54     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1041
[task 2022-04-05T19:21:54.318Z] 19:21:54     INFO - Console message: [JavaScript Warning: "browser.ui.customized_widgets - The key length must be limited to 72 characters."]
[task 2022-04-05T19:21:54.319Z] 19:21:54     INFO - GECKO(1562) | monitorConsole | extra message | {"message":"[JavaScript Warning: \"browser.ui.customized_widgets - The key length must be limited to 72 characters.\"]","errorMessage":"browser.ui.customized_widgets - The key length must be limited to 72 characters.","cssSelectors":"","sourceName":"","sourceLine":"","lineNumber":0,"columnNumber":0,"category":"chrome javascript","windowID":0,"isScriptError":true,"isConsoleEvent":false,"isWarning":true,"innerWindowID":0}
[task 2022-04-05T19:21:54.320Z] 19:21:54     INFO - Console message: Warning: attempting to write 16377 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-04-05T19:21:54.321Z] 19:21:54     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-04-05T19:21:54.323Z] 19:21:54     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | Uncaught exception received from previously timed out test - at resource://specialpowers/SpecialPowersParent.jsm:1237 - TypeError: can't access property "wakeupBackground", extension is undefined
[task 2022-04-05T19:21:54.323Z] 19:21:54     INFO - Stack trace:
[task 2022-04-05T19:21:54.323Z] 19:21:54     INFO - receiveMessage@resource://specialpowers/SpecialPowersParent.jsm:1237:11
[task 2022-04-05T19:21:54.324Z] 19:21:54     INFO - JSActor query*wakeupBackground@resource://specialpowers/SpecialPowersChild.jsm:2021:19
[task 2022-04-05T19:21:54.324Z] 19:21:54     INFO - test_menu_create_id_reuse@chrome://mochitests/content/browser/browser/components/extensions/test/browser/browser_ext_menus_eventpage.js:262:19
[task 2022-04-05T19:21:54.324Z] 19:21:54     INFO - Async*handleTask@chrome://mochikit/content/browser-test.js:989:26
[task 2022-04-05T19:21:54.325Z] 19:21:54     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1061:18
[task 2022-04-05T19:21:54.325Z] 19:21:54     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1189:14
[task 2022-04-05T19:21:54.325Z] 19:21:54     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:978:14
[task 2022-04-05T19:21:54.326Z] 19:21:54     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2022-04-05T19:21:54.327Z] 19:21:54     INFO - GECKO(1562) | monitorConsole | extra message | {"message":"Warning: attempting to write 16377 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.","errorMessage":null,"cssSelectors":null,"sourceName":null,"sourceLine":null,"lineNumber":null,"columnNumber":null,"category":null,"windowID":null,"isScriptError":false,"isConsoleEvent":false,"isWarning":false}
[task 2022-04-05T19:21:54.328Z] 19:21:54     INFO - GECKO(1562) | MEMORY STAT | vsize 4155MB | residentFast 845MB | heapAllocated 470MB
[task 2022-04-05T19:21:54.330Z] 19:21:54     INFO - TEST-OK | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | took 90272ms
[task 2022-04-05T19:21:54.332Z] 19:21:54     INFO - GECKO(1562) | [Child 1705: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fd787233800 == 1 [pid = 1705] [id = 62]
[task 2022-04-05T19:21:54.332Z] 19:21:54     INFO - GECKO(1562) | [Child 1705: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fd79cf959f0) [pid = 1705] [serial = 133] [outer = 0]
[task 2022-04-05T19:21:54.333Z] 19:21:54     INFO - GECKO(1562) | [Child 1705: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fd78727a400) [pid = 1705] [serial = 134] [outer = 7fd79cf959f0]
[task 2022-04-05T19:21:54.343Z] 19:21:54     INFO - checking window state
[task 2022-04-05T19:21:54.484Z] 19:21:54     INFO - TEST-START | browser/components/extensions/test/browser/browser_ext_menus_events.js

Comment 1

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

Set release status flags based on info from the regressing bug 1748558

Comment 2

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

:mixedpuppy, since you are the author of the regressor, bug 1748558, could you take a look?
For more information, please visit auto_nag documentation.

Comment 3

[Intermittent Failures Robot]

31 failures in 2922 pushes (0.011 failures/push) were associated with this bug in the last 7 days.

This is the #34 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: 24
• try: 3
• mozilla-central: 4

Platform and build breakdown:

• linux1804-64-qr: 18
  • debug: 18
• linux1804-64-tsan-qr: 12
  • opt: 12
• windows10-64-2004-qr: 1
  • debug: 1

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

Comment 4

[Iulian Moraru]

Update:
There have been 32 failures within the last 7 days:
• 22 failures on Linux 18.04 x64 WebRender debug
• 10 failures on Linux 18.04 x64 WebRender tsan opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=374653970&repo=autoland&lineNumber=13598

[task 2022-04-15T15:15:08.032Z] 15:15:08     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | lastError message for missing id - Expected: The menu id test-click already exists in menus.create., Actual: The menu id test-click already exists in menus.create. - 
[task 2022-04-15T15:15:08.032Z] 15:15:08     INFO - Buffered messages finished
[task 2022-04-15T15:15:08.034Z] 15:15:08     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | Test timed out - 
[task 2022-04-15T15:15:08.035Z] 15:15:08     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-04-15T15:15:08.039Z] 15:15:08     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | Extension left running at test shutdown - 
[task 2022-04-15T15:15:08.039Z] 15:15:08     INFO - Stack trace:
[task 2022-04-15T15:15:08.039Z] 15:15:08     INFO - chrome://mochikit/content/browser-test.js:test_ok:1394
[task 2022-04-15T15:15:08.039Z] 15:15:08     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:133
[task 2022-04-15T15:15:08.039Z] 15:15:08     INFO - chrome://mochikit/content/browser-test.js:nextTest:561
[task 2022-04-15T15:15:08.040Z] 15:15:08     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1282
[task 2022-04-15T15:15:08.040Z] 15:15:08     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1222
[task 2022-04-15T15:15:08.040Z] 15:15:08     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:978
[task 2022-04-15T15:15:08.040Z] 15:15:08     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1041
[task 2022-04-15T15:15:08.041Z] 15:15:08     INFO - Console message: [JavaScript Warning: "browser.ui.customized_widgets - The key length must be limited to 72 characters."]
[task 2022-04-15T15:15:08.042Z] 15:15:08     INFO - GECKO(1574) | monitorConsole | extra message | {"message":"[JavaScript Warning: \"browser.ui.customized_widgets - The key length must be limited to 72 characters.\"]","errorMessage":"browser.ui.customized_widgets - The key length must be limited to 72 characters.","cssSelectors":"","sourceName":"","sourceLine":"","lineNumber":0,"columnNumber":0,"category":"chrome javascript","windowID":0,"isScriptError":true,"isConsoleEvent":false,"isWarning":true,"innerWindowID":0}
[task 2022-04-15T15:15:08.042Z] 15:15:08     INFO - Console message: Warning: attempting to write 16377 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-04-15T15:15:08.043Z] 15:15:08     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | Uncaught exception received from previously timed out test - at resource://specialpowers/SpecialPowersParent.jsm:1236 - TypeError: can't access property "wakeupBackground", extension is undefined
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - Stack trace:
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - receiveMessage@resource://specialpowers/SpecialPowersParent.jsm:1236:11
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - JSActor query*wakeupBackground@resource://specialpowers/SpecialPowersChild.jsm:2021:19
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - test_menu_create_id_reuse@chrome://mochitests/content/browser/browser/components/extensions/test/browser/browser_ext_menus_eventpage.js:262:19
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - Async*handleTask@chrome://mochikit/content/browser-test.js:989:26
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1061:18
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1189:14
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:978:14
[task 2022-04-15T15:15:08.046Z] 15:15:08     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2022-04-15T15:15:08.052Z] 15:15:08     INFO - GECKO(1574) | monitorConsole | extra message | {"message":"Warning: attempting to write 16377 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.","errorMessage":null,"cssSelectors":null,"sourceName":null,"sourceLine":null,"lineNumber":null,"columnNumber":null,"category":null,"windowID":null,"isScriptError":false,"isConsoleEvent":false,"isWarning":false}
[task 2022-04-15T15:15:08.053Z] 15:15:08     INFO - GECKO(1574) | MEMORY STAT | vsize 4102MB | residentFast 839MB | heapAllocated 471MB
[task 2022-04-15T15:15:08.053Z] 15:15:08     INFO - TEST-OK | browser/components/extensions/test/browser/browser_ext_menus_eventpage.js | took 90239ms

Comment 5

[Intermittent Failures Robot]

32 failures in 2948 pushes (0.011 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: 27
• mozilla-central: 3
• try: 2

Platform and build breakdown:

• linux1804-64-tsan-qr: 8
  • opt: 8
• linux1804-64-qr: 24
  • debug: 24

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1763227&startday=2022-04-11&endday=2022-04-17&tree=all

Comment 6

[Shane Caraveo (:mixedpuppy)]

Given the timing of the last report, this was probably fixed by the intermittent fix in bug 1763043.