1793743 - High freq browser/components/extensions/test/browser/browser_unified_extensions.js | single tracking bug

Status: RESOLVED FIXED

(WebExtensions :: Untriaged, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=392299920&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/OWYpAqtXRQStYnZ5Q1YsHA/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/OWYpAqtXRQStYnZ5Q1YsHA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2022-10-05T07:40:29.727Z] 07:40:29     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_unified_extensions.js | expected action button to be enabled - 
[task 2022-10-05T07:40:29.728Z] 07:40:29     INFO - Buffered messages logged at 07:39:44
[task 2022-10-05T07:40:29.728Z] 07:40:29     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2022-10-05T07:40:29.729Z] 07:40:29     INFO - Buffered messages finished
[task 2022-10-05T07:40:29.730Z] 07:40:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions.js | Test timed out - 
[task 2022-10-05T07:40:29.730Z] 07:40:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-10-05T07:40:29.731Z] 07:40:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions.js | Extension left running at test shutdown - 
[task 2022-10-05T07:40:29.732Z] 07:40:29     INFO - Stack trace:
[task 2022-10-05T07:40:29.732Z] 07:40:29     INFO - chrome://mochikit/content/browser-test.js:test_ok:1457
[task 2022-10-05T07:40:29.733Z] 07:40:29     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:132
[task 2022-10-05T07:40:29.733Z] 07:40:29     INFO - chrome://mochikit/content/browser-test.js:nextTest:616
[task 2022-10-05T07:40:29.734Z] 07:40:29     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1345
[task 2022-10-05T07:40:29.734Z] 07:40:29     INFO - setTimeout handler*chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest_setTimeoutShim:904
[task 2022-10-05T07:40:29.735Z] 07:40:29     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1306
[task 2022-10-05T07:40:29.735Z] 07:40:29     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1285
[task 2022-10-05T07:40:29.735Z] 07:40:29     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1028
[task 2022-10-05T07:40:29.736Z] 07:40:29     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1038
[task 2022-10-05T07:40:29.736Z] 07:40:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-10-05T07:40:29.736Z] 07:40:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions.js | Uncaught exception received from previously timed out test - mouseover listener not removed before the end of test
[task 2022-10-05T07:40:29.809Z] 07:40:29     INFO - GECKO(1630) | MEMORY STAT | vsize 3393MB | residentFast 516MB | heapAllocated 221MB
[task 2022-10-05T07:40:29.811Z] 07:40:29     INFO - TEST-OK | browser/components/extensions/test/browser/browser_unified_extensions.js | took 90511ms

Comment 1

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

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

:willdurand, since you are the author of the regressor, bug 1784218, could you take a look?

For more information, please visit auto_nag documentation.

Comment 2

[Intermittent Failures Robot]

7 failures in 2449 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7

Platform and build breakdown:

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

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

Comment 3

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

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

Comment 4

[Norisz Fay [:noriszfay]]

This appeared as tier 1 here

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• try: 1

Platform and build breakdown:

• windows10-64-2004-qr: 2
  • debug: 1
  • opt: 1
• linux1804-64-qr: 1
  • debug: 1
• windows10-32-2004-qr: 1
  • opt: 1

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows10-64-2004-ccov-qr: 1
  • opt: 1

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2
• autoland: 1

Platform and build breakdown:

• windows10-64-2004-ccov-qr: 2
  • opt: 2
• windows10-64-2004-qr: 1
  • debug: 1

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

Comment 9

[Noemi Erli[:noemi_erli]]

This started to fail frequently since https://bugzilla.mozilla.org/show_bug.cgi?id=1801471 landed.
Range:
https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&collapsedPushes=842382&resultStatus=success%2Cpending%2Crunning%2Ctestfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel&fromchange=82670c05383339b7547ed4c4099b00028a29a5a2&searchStr=os%2Cx%2C11%2Cwebrender%2Cshippable%2Copt%2Cmochitests%2Cwith%2Cnetworking%2Con%2Csocket%2Cprocess%2Ctest-macosx1100-64-shippable-qr%2Fopt-mochitest-browser-chrome-spi-nw%2Cbc5&tochange=f9ce6da7427876189b889c5e5f07bb7ba5b65c4b&selectedTaskRun=LL497KH0TDCs1AIkMwGrEQ.0
William, could you please take a look at this?

Comment 10

[William Durand [:willdurand]]

on it

Comment 11

[William Durand [:willdurand]]

Attachment: Bug 1793743 - Let `ExtensionTestUtils` generate extension IDs in `createExtensions()`. r?rpl!

Comment 12

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/b9f882512c90
Let `ExtensionTestUtils` generate extension IDs in `createExtensions()`. r=rpl

Comment 13

[Intermittent Failures Robot]

16 failures in 711 pushes (0.023 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 12
• autoland: 4

Platform and build breakdown:

• macosx1100-64-shippable-qr: 15
  • opt: 15
• macosx1015-64-qr: 1
  • debug: 1

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

Comment 14

[William Durand [:willdurand]]

Attachment: Bug 1793743 - Resize window to avoid overflowed extensions in the unified extensions panel. r?rpl!

Depending on the test chunks on Firefox CI, we might have a test file
executed before this one that resizes the window and does not reset it.

In most cases, this isn't an issue except when we have logic to overflow
extension buttons pinned in the toolbar into the unified extensions
panel. This is what's happening here, at least for some configurations.

By making sure that the window is large enough, we should not have
overflowed extension buttons in the panel anymore. That will make the
test more robust.

Comment 15

[Serban Stanca [:SerbanS]]

https://hg.mozilla.org/mozilla-central/rev/b9f882512c90

Comment 16

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/d3aa95c59621
Resize window to avoid overflowed extensions in the unified extensions panel. r=rpl

Comment 17

[Iulian Moraru]

https://hg.mozilla.org/mozilla-central/rev/d3aa95c59621

Comment 18

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

The patch landed in nightly and beta is affected.
:willdurand, is this bug important enough to require an uplift?

• If yes, please nominate the patch for beta approval.
• If no, please set status-firefox108 to wontfix.

For more information, please visit auto_nag documentation.

Comment 19

[Intermittent Failures Robot]

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

This is the #28 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: 28
• mozilla-central: 14
• try: 2

Platform and build breakdown:

• macosx1015-64-qr: 10
  • opt: 3
  • debug: 7
• windows10-32-2004-qr: 3
  • opt: 3
• macosx1100-64-shippable-qr: 30
  • opt: 30
• windows10-64-2004-qr: 1
  • opt: 1

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

Comment 20

[Iulian Moraru]

Hi William! Can you please take another look at this? This is not high frequency anymore but it is still failing with browser/components/extensions/test/browser/browser_unified_extensions.js | Test timed out - | followed by Extension left running at test shutdown - Should we reopen this bug or file a new bug for this?
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=398053651&repo=autoland&lineNumber=35318

[task 2022-11-29T17:25:18.197Z] 17:25:18     INFO - Console message: Warning: attempting to write 47293 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.
[task 2022-11-29T17:25:18.198Z] 17:25:18     INFO - Buffered messages logged at 17:22:26
[task 2022-11-29T17:25:18.199Z] 17:25:18     INFO - Console message: [JavaScript Warning: "browser.ui.customized_widgets - Keyed scalars cannot have more than 100 keys."]
[task 2022-11-29T17:25:18.200Z] 17:25:18     INFO - Console message: [JavaScript Warning: "browser.ui.customized_widgets - Keyed scalars cannot have more than 100 keys."]
[task 2022-11-29T17:25:18.203Z] 17:25:18     INFO - Console message: Warning: attempting to write 7487 bytes to preference browser.pageActions.persistedActions. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file.
[task 2022-11-29T17:25:18.203Z] 17:25:18     INFO - Console message: [JavaScript Warning: "browser.ui.customized_widgets - Keyed scalars cannot have more than 100 keys."]
[task 2022-11-29T17:25:18.203Z] 17:25:18     INFO - Registering mock add-on provider
[task 2022-11-29T17:25:18.203Z] 17:25:18     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_unified_extensions.js | expected add-on without a policy - 
[task 2022-11-29T17:25:18.203Z] 17:25:18     INFO - verifying extensions listed in the panel with private browsing disabled
[task 2022-11-29T17:25:18.204Z] 17:25:18     INFO - Console message: 1669742546494	addons.manager	DEBUG	Starting provider: <unnamed-provider>
[task 2022-11-29T17:25:18.205Z] 17:25:18     INFO - Console message: 1669742546495	addons.manager	DEBUG	Registering shutdown blocker for <unnamed-provider>
[task 2022-11-29T17:25:18.205Z] 17:25:18     INFO - Console message: 1669742546495	addons.manager	DEBUG	Provider finished startup: <unnamed-provider>
[task 2022-11-29T17:25:18.206Z] 17:25:18     INFO - Buffered messages logged at 17:23:47
[task 2022-11-29T17:25:18.207Z] 17:25:18     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2022-11-29T17:25:18.207Z] 17:25:18     INFO - Buffered messages finished
[task 2022-11-29T17:25:18.208Z] 17:25:18     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions.js | Test timed out - 
[task 2022-11-29T17:25:18.209Z] 17:25:18     INFO - GECKO(1637) | [Parent 1637, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2022-11-29T17:25:18.209Z] 17:25:18     INFO - GECKO(1637) | [Parent 1637, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2022-11-29T17:25:18.210Z] 17:25:18     INFO - GECKO(1637) | [Parent 1637, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2022-11-29T17:25:18.210Z] 17:25:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-11-29T17:25:18.212Z] 17:25:18     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions.js | Extension left running at test shutdown - 
[task 2022-11-29T17:25:18.212Z] 17:25:18     INFO - Stack trace:
[task 2022-11-29T17:25:18.213Z] 17:25:18     INFO - chrome://mochikit/content/browser-test.js:test_ok:1449
[task 2022-11-29T17:25:18.213Z] 17:25:18     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:132
[task 2022-11-29T17:25:18.213Z] 17:25:18     INFO - chrome://mochikit/content/browser-test.js:nextTest:611
[task 2022-11-29T17:25:18.214Z] 17:25:18     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1337
[task 2022-11-29T17:25:18.214Z] 17:25:18     INFO - setTimeout handler*chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest_setTimeoutShim:900
[task 2022-11-29T17:25:18.215Z] 17:25:18     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1298
[task 2022-11-29T17:25:18.215Z] 17:25:18     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1277
[task 2022-11-29T17:25:18.215Z] 17:25:18     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1026
[task 2022-11-29T17:25:18.215Z] 17:25:18     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1036
[task 2022-11-29T17:25:18.215Z] 17:25:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-11-29T17:25:18.216Z] 17:25:18     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions.js | Extension left running at test shutdown - 
[task 2022-11-29T17:25:18.216Z] 17:25:18     INFO - Stack trace:
[task 2022-11-29T17:25:18.216Z] 17:25:18     INFO - chrome://mochikit/content/browser-test.js:test_ok:1449
<...>

Comment 21

[William Durand [:willdurand]]

could you please open a new bug so that we can monitor the failures? I am really not sure why this is happening again :/

Comment 22

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

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