1829459 - Intermittent toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html | single tracking bug

Status: RESOLVED INCOMPLETE

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-04-22T05:05:49.777Z] 05:05:49     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html | DNR rule from other extension should have redirected the navigation - Expected: https://example.com/?dnr_redir_target, Actual: https://example.com/?dnr_redir_target 
[task 2023-04-22T05:05:49.778Z] 05:05:49     INFO - Waiting for navigation done, starting from https://example.org/?dnr_redir_me_pls
[task 2023-04-22T05:05:49.779Z] 05:05:49     INFO - Buffered messages finished
[task 2023-04-22T05:05:49.779Z] 05:05:49     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html | Test timed out. - 
[task 2023-04-22T05:05:50.642Z] 05:05:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-22T05:05:50.643Z] 05:05:50     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html | Extension left running at test shutdown 
[task 2023-04-22T05:05:50.644Z] 05:05:50     INFO - SimpleTest.ok@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:424:16
[task 2023-04-22T05:05:50.644Z] 05:05:50     INFO - ExtensionTestUtils.loadExtension/<@https://mochi.test:8888/tests/SimpleTest/ExtensionTestUtils.js:132:18
[task 2023-04-22T05:05:50.645Z] 05:05:50     INFO - executeCleanupFunction@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:1527:13
[task 2023-04-22T05:05:50.646Z] 05:05:50     INFO - SimpleTest.finish@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:1541:3
[task 2023-04-22T05:05:50.647Z] 05:05:50     INFO - killTest@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:202:22
[task 2023-04-22T05:05:50.648Z] 05:05:50     INFO - async*delayedKillTest@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:239:17
[task 2023-04-22T05:05:50.649Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:237:17
[task 2023-04-22T05:05:50.649Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.650Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.650Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.651Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.651Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.652Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.653Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.653Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.654Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.654Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.655Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.655Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.656Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.657Z] 05:05:50     INFO - setTimeout handler*TestRunner._checkForHangs@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:250:15
[task 2023-04-22T05:05:50.657Z] 05:05:50     INFO - TestRunner.runTests/<@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:530:16
[task 2023-04-22T05:05:50.658Z] 05:05:50     INFO - Async*TestRunner.runTests@https://mochi.test:8888/tests/SimpleTest/TestRunner.js:517:48
[task 2023-04-22T05:05:50.658Z] 05:05:50     INFO - RunSet.runtests@https://mochi.test:8888/tests/SimpleTest/setup.js:276:14
[task 2023-04-22T05:05:50.659Z] 05:05:50     INFO - RunSet.runall@https://mochi.test:8888/tests/SimpleTest/setup.js:255:12
[task 2023-04-22T05:05:50.659Z] 05:05:50     INFO - getPrefList/<@https://mochi.test:8888/tests/SimpleTest/setup.js:342:14
[task 2023-04-22T05:05:50.660Z] 05:05:50     INFO - loadFile/req.onload@https://mochi.test:8888/tests/SimpleTest/setup.js:80:19
[task 2023-04-22T05:05:50.660Z] 05:05:50     INFO - EventHandlerNonNull*loadFile@https://mochi.test:8888/tests/SimpleTest/setup.js:75:3
[task 2023-04-22T05:05:50.661Z] 05:05:50     INFO - getPrefList@https://mochi.test:8888/tests/SimpleTest/setup.js:340:13
[task 2023-04-22T05:05:50.661Z] 05:05:50     INFO - hookupTests@https://mochi.test:8888/tests/SimpleTest/setup.js:363:5
[task 2023-04-22T05:05:50.662Z] 05:05:50     INFO - parseTestManifest@https://mochi.test:8888/manifestLibrary.js:53:13
[task 2023-04-22T05:05:50.662Z] 05:05:50     INFO - getTestManifest/req.onload@https://mochi.test:8888/manifestLibrary.js:66:28
[task 2023-04-22T05:05:50.663Z] 05:05:50     INFO - EventHandlerNonNull*getTestManifest@https://mochi.test:8888/manifestLibrary.js:62:3
[task 2023-04-22T05:05:50.663Z] 05:05:50     INFO - hookup@https://mochi.test:8888/tests/SimpleTest/setup.js:328:20
[task 2023-04-22T05:05:50.664Z] 05:05:50     INFO - EventHandlerNonNull*@https://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest?autorun=1&closeWhenDone=1&consoleLevel=INFO&testname=tests/toolkit/components/extensions/test/mochitest/mochitest-remote.ini&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true&ignorePrefsFile=ignorePrefs.json:10:32
[task 2023-04-22T05:05:50.671Z] 05:05:50     INFO - GECKO(2052) | [Child 2123, 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-04-22T05:05:50.691Z] 05:05:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-22T05:05:50.693Z] 05:05:50     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html | no tasks awaiting on messages - got "[\"done\"]", expected "[]"
[task 2023-04-22T05:05:50.694Z] 05:05:50     INFO - SimpleTest.is@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:507:14
[task 2023-04-22T05:05:50.695Z] 05:05:50     INFO - ExtensionTestUtils.loadExtension/<@https://mochi.test:8888/tests/SimpleTest/ExtensionTestUtils.js:51:18
[task 2023-04-22T05:05:50.696Z] 05:05:50     INFO - executeCleanupFunction@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:1527:13
[task 2023-04-22T05:05:50.696Z] 05:05:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-22T05:05:50.698Z] 05:05:50     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html | Extension left running at test shutdown 
[task 2023-04-22T05:05:50.698Z] 05:05:50     INFO - SimpleTest.ok@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:424:16
[task 2023-04-22T05:05:50.699Z] 05:05:50     INFO - ExtensionTestUtils.loadExtension/<@https://mochi.test:8888/tests/SimpleTest/ExtensionTestUtils.js:132:18
[task 2023-04-22T05:05:50.699Z] 05:05:50     INFO - executeCleanupFunction@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:1527:13
[task 2023-04-22T05:05:50.700Z] 05:05:50     INFO - executeCleanupFunction@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:1537:7
[task 2023-04-22T05:05:50.710Z] 05:05:50     INFO - GECKO(2052) | [Child 2123, 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-04-22T05:05:50.732Z] 05:05:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-22T05:05:50.733Z] 05:05:50     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html | Test left extra windows or tabs: {"extraWindows":{},"extraTabs":{"0":"about:blank"}}
[task 2023-04-22T05:05:50.733Z] 05:05:50     INFO -  
[task 2023-04-22T05:05:50.734Z] 05:05:50     INFO - SimpleTest.ok@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:424:16
[task 2023-04-22T05:05:50.734Z] 05:05:50     INFO - @https://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/head.js:26:9
[task 2023-04-22T05:05:50.735Z] 05:05:50     INFO - async*executeCleanupFunction@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:1527:13
[task 2023-04-22T05:05:50.735Z] 05:05:50     INFO - executeCleanupFunction@https://mochi.test:8888/tests/SimpleTest/SimpleTest.js:1537:7
[task 2023-04-22T05:05:50.742Z] 05:05:50     INFO - GECKO(2052) | MEMORY STAT | vsize 2581MB | residentFast 153MB | heapAllocated 8MB
[task 2023-04-22T05:05:51.762Z] 05:05:51     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html | took 329477ms
[task 2023-04-22T05:05:54.772Z] 05:05:54     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2023-04-22T05:05:54.798Z] 05:05:54    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2023-04-22T05:05:54.801Z] 05:05:54    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html logged result after SimpleTest.finish(): no tasks awaiting on messages
[task 2023-04-22T05:05:54.803Z] 05:05:54    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2023-04-22T05:05:54.805Z] 05:05:54    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_dnr_other_extensions.html logged result after SimpleTest.finish(): Test left extra windows or tabs: {"extraWindows":{},"extraTabs":{"0":"about:blank"}}
[task 2023-04-22T05:05:54.805Z] 05:05:54    ERROR - 
[task 2023-04-22T05:05:55.005Z] 05:05:55     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_dnr_tabIds.html

Comment 1

[Intermittent Failures Robot]

1 failures in 4004 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=1829459&startday=2023-04-17&endday=2023-04-23&tree=all

Comment 2

[Natalia Csoregi [:nataliaCs]]

First occurrence so far, in this range of retriggers and backfills

This range does not seem to be relevant, but it was maybe caused by Bug 1825824.
Rob, do you have a few minutes to look into this?
Thank you.

Comment 3

[Rob Wu [:robwu]]

Bug 1825824 introduced the test, but the test failure does not look like an intrinsic issue with the test.

I do observe the following:

• The test is stuck trying to load a page from example.org
• At the end of the test (after the timeout), there is a remaining window with about:blank, implying that the example.org really failed to load.
• This test configuration runs with http3, i.e. with the http3 test server force-enabled.

I have seen a similar stack trace at 1827526. Since example.org is supposed to load but clearly didn't, I suspect that this may be an issue with the http3 test server.

Comment 4

[Intermittent Failures Robot]

1 failures in 3177 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=1829459&startday=2023-04-24&endday=2023-04-30&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 4077 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=1829459&startday=2023-05-08&endday=2023-05-14&tree=all

Comment 6

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

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

Comment 7

[Treeherder Bug Filer]

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

Comment 8

[Intermittent Failures Robot]

1 failures in 4079 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=1829459&startday=2023-06-12&endday=2023-06-18&tree=all

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1829459&startday=2023-06-26&endday=2023-07-02&tree=all

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 1
  • opt: 1

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1829459&startday=2023-07-31&endday=2023-08-06&tree=all

Comment 12

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

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

Comment 13

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=439750755&repo=mozilla-central

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

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

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

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1829459&startday=2024-01-01&endday=2024-01-07&tree=all

Comment 16

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

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

Comment 17

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=451698073&repo=mozilla-beta

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1829459&startday=2024-03-18&endday=2024-03-24&tree=all

Comment 19

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

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