1651261 - Intermittent browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js | Test timed out -

Status: RESOLVED INCOMPLETE

(WebExtensions :: General, defect, P3)

Description

[Treeherder Bug Filer]

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

---

[task 2020-07-08T01:23:31.736Z] 01:23:31 INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js | The New Tab notification is not set for this extension -
[task 2020-07-08T01:23:31.737Z] 01:23:31 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "moz-extension://e1d2066f-15e5-4d32-bd8e-1b90a94cfd58/restore-two.html" line: 0}]
[task 2020-07-08T01:23:31.738Z] 01:23:31 INFO - Buffered messages finished
[task 2020-07-08T01:23:31.739Z] 01:23:31 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js | Test timed out -
[task 2020-07-08T01:23:31.740Z] 01:23:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-08T01:23:31.741Z] 01:23:31 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js | no tasks awaiting on messages - Got ["from-newtab-page"], expected []
[task 2020-07-08T01:23:31.741Z] 01:23:31 INFO - Stack trace:
[task 2020-07-08T01:23:31.742Z] 01:23:31 INFO - chrome://mochikit/content/browser-test.js:test_is:1327
[task 2020-07-08T01:23:31.742Z] 01:23:31 INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:36
[task 2020-07-08T01:23:31.743Z] 01:23:31 INFO - chrome://mochikit/content/browser-test.js:nextTest:550
[task 2020-07-08T01:23:31.744Z] 01:23:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-08T01:23:31.745Z] 01:23:31 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js | Extension left running at test shutdown -
[task 2020-07-08T01:23:31.745Z] 01:23:31 INFO - Stack trace:
[task 2020-07-08T01:23:31.746Z] 01:23:31 INFO - chrome://mochikit/content/browser-test.js:test_ok:1299
[task 2020-07-08T01:23:31.746Z] 01:23:31 INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:117
[task 2020-07-08T01:23:31.747Z] 01:23:31 INFO - chrome://mochikit/content/browser-test.js:nextTest:550
[task 2020-07-08T01:23:31.748Z] 01:23:31 INFO - Console message: Warning: attempting to write 38184 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 2020-07-08T01:23:31.748Z] 01:23:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-08T01:23:31.749Z] 01:23:31 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js | Extension left running at test shutdown -
[task 2020-07-08T01:23:31.750Z] 01:23:31 INFO - Stack trace:
[task 2020-07-08T01:23:31.750Z] 01:23:31 INFO - chrome://mochikit/content/browser-test.js:test_ok:1299
[task 2020-07-08T01:23:31.751Z] 01:23:31 INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:117
[task 2020-07-08T01:23:31.751Z] 01:23:31 INFO - chrome://mochikit/content/browser-test.js:nextTest:550
[task 2020-07-08T01:23:31.795Z] 01:23:31 INFO - Console message: Warning: attempting to write 38115 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 2020-07-08T01:23:31.811Z] 01:23:31 INFO - GECKO(1253) | MEMORY STAT | vsize 3801MB | residentFast 584MB | heapAllocated 210MB
[task 2020-07-08T01:23:31.812Z] 01:23:31 INFO - TEST-OK | browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js | took 90401ms
[task 2020-07-08T01:23:31.812Z] 01:23:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-08T01:23:31.813Z] 01:23:31 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js | Found a tab after previous test timed out: moz-extension://e1d2066f-15e5-4d32-bd8e-1b90a94cfd58/restore-two.html -
[task 2020-07-08T01:23:31.881Z] 01:23:31 INFO - GECKO(1253) | [Child 1566: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f972a0a3000 == 1 [pid = 1566] [id = {918f9243-11bc-48e4-8875-af8c20d8a18e}]
[task 2020-07-08T01:23:31.882Z] 01:23:31 INFO - GECKO(1253) | [Child 1566: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f9747793350) [pid = 1566] [serial = 383] [outer = (nil)]
[task 2020-07-08T01:23:31.883Z] 01:23:31 INFO - GECKO(1253) | [Child 1566: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f972a0a6800) [pid = 1566] [serial = 384] [outer = 0x7f9747793350]
[task 2020-07-08T01:23:31.944Z] 01:23:31 INFO - GECKO(1253) | [Child 1566: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f972a0b2000) [pid = 1566] [serial = 385] [outer = 0x7f9747793350]
[task 2020-07-08T01:23:31.944Z] 01:23:31 INFO - checking window state
[task 2020-07-08T01:23:32.000Z] 01:23:31 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f3ad677d400 == 1 [pid = 1542] [id = {0a85c1cd-0b00-4699-881c-922da5f2e338}]
[task 2020-07-08T01:23:32.000Z] 01:23:32 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f3af6a93350) [pid = 1542] [serial = 272] [outer = (nil)]
[task 2020-07-08T01:23:32.003Z] 01:23:32 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f3ad677f000) [pid = 1542] [serial = 273] [outer = 0x7f3af6a93350]

Comment 1

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Nika, could this frequent failure be from bug 1599579? Backfills of the task didn't reproduce it.

Comment 2

[Intermittent Failures Robot]

26 failures in 993 pushes (0.026 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 18
• mozilla-central: 6
• try: 2

Platform breakdown:

• linux1804-64-asan: 11
• linux1804-64: 6
• linux1804-64-shippable: 5
• linux1804-64-ccov: 3
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-07-09&endday=2020-07-09&tree=all

Comment 3

[Nika Layzell [:nika] (ni? for response)]

This would be a bit easier to figure out with some MOZ_LOG logs enabled when running the tests. If there's a pernosco recording from one of these failures, it would make it easier to figure out what's going on because of that :-)

This definitely seems like an issue which is probably caused by bug 1599579.

Comment 4

[Intermittent Failures Robot]

22 failures in 1064 pushes (0.021 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 17
• mozilla-central: 4
• try: 1

Platform breakdown:

• linux1804-64: 9
• linux1804-64-asan: 9
• linux1804-64-ccov: 2
• windows7-32: 1
• linux1804-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-07-10&endday=2020-07-10&tree=all

Comment 5

[Intermittent Failures Robot]

79 failures in 5265 pushes (0.015 failures/push) were associated with this bug in the last 7 days.

This is the #18 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 60
• mozilla-central: 14
• try: 5

Platform breakdown:

• linux1804-64: 28
• linux1804-64-shippable: 7
• linux1804-64-asan: 36
• linux1804-64-ccov: 6
• macosx1014-64: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-07-06&endday=2020-07-12&tree=all

Comment 6

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

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

Comment 7

[Intermittent Failures Robot]

22 failures in 750 pushes (0.029 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 18
• try: 1
• mozilla-central: 3

Platform breakdown:

• linux1804-64-asan: 9
• linux1804-64-shippable: 5
• linux1804-64: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-07-13&endday=2020-07-13&tree=all

Comment 8

[Intermittent Failures Robot]

27 failures in 768 pushes (0.035 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 14
• mozilla-central: 8
• try: 5

Platform breakdown:

• linux1804-64: 13
• linux1804-64-shippable: 2
• linux1804-64-asan: 12

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-07-14&endday=2020-07-14&tree=all

Comment 9

[Intermittent Failures Robot]

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

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 14
• mozilla-central: 1
• try: 1

Platform breakdown:

• linux1804-64-shippable: 2
• linux1804-64-asan: 5
• linux1804-64: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-07-16&endday=2020-07-16&tree=all

Comment 10

[Narcis Beleuzu [:NarcisB]]

Attachment: Bug 1651261 - Disable browser_ext_url_overrides_newtab.js on linux64. r?#intermittent-reviewers

Comment 11

[Intermittent Failures Robot]

105 failures in 4014 pushes (0.026 failures/push) were associated with this bug in the last 7 days.

This is the #6 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 72
• try: 13
• mozilla-central: 20

Platform breakdown:

• linux1804-64-asan: 46
• linux1804-64: 48
• linux1804-64-shippable: 11

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-07-13&endday=2020-07-19&tree=all

Comment 12

[Nika Layzell [:nika] (ni? for response)]

:pbone - you've spent a lot of time fiddling with process switches and the new tab page. Any idea what might be causing this?

Comment 13

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/1358f376fb35
Disable browser_ext_url_overrides_newtab.js on linux64. r=jmaher

Comment 14

[Andreea Pavel [:apavel]]

https://hg.mozilla.org/mozilla-central/rev/1358f376fb35

Comment 15

[Intermittent Failures Robot]

10 failures in 4310 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 2
• try: 1

Platform breakdown:

• linux1804-64-asan: 6
• linux1804-64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-07-20&endday=2020-07-26&tree=all

Comment 16

[Paul Bone [:pbone]]

Sorry for the late response. I can reproduce this but I'm not sure what's causing it. I tried using a Promise.all for the series of awaits here: https://searchfox.org/mozilla-central/source/browser/components/extensions/test/browser/browser_ext_url_overrides_newtab.js#388 (which is one of the two places where the timeout can occur), but it made no difference. There's plenty more to try but there's noting I know off-the-top-of-my-head that can help.

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-10-12&endday=2020-10-18&tree=all

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-10-19&endday=2020-10-25&tree=all

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• try: 1

Platform breakdown:

• macosx1014-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-10-26&endday=2020-11-01&tree=all

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• autoland: 1

Platform breakdown:

• macosx1014-64: 1
• macosx1014-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1651261&startday=2020-11-02&endday=2020-11-08&tree=all

Comment 21

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• macosx1014-64-qr: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1651261&startday=2020-11-09&endday=2020-11-15&tree=all

Comment 22

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• macosx1014-64-qr: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1651261&startday=2020-11-16&endday=2020-11-22&tree=all

Comment 23

[Shane Caraveo (:mixedpuppy)]

Looks like this stopped when Bug 1595858 landed, might be worth trying to turn linux 1804 back on.

Comment 24

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

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