1804122 - Intermittent TV [tier 2] toolkit/components/extensions/test/mochitest/test_ext_browserAction_openPopup_windowId.html | single tracking bug

Status: RESOLVED FIXED

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-12-05T19:13:39.792Z] 19:13:39     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_browserAction_openPopup_windowId.html | new window is focused - Expected: true, Actual: true 
[task 2022-12-05T19:13:39.792Z] 19:13:39     INFO - Buffered messages finished
[task 2022-12-05T19:13:39.793Z] 19:13:39     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_browserAction_openPopup_windowId.html | Test timed out. - 
[task 2022-12-05T19:13:40.803Z] 19:13:40     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-12-05T19:13:40.805Z] 19:13:40     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_browserAction_openPopup_windowId.html | Extension left running at test shutdown 
[task 2022-12-05T19:13:40.805Z] 19:13:40     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:404:16
[task 2022-12-05T19:13:40.805Z] 19:13:40     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:132:18
[task 2022-12-05T19:13:40.805Z] 19:13:40     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1507:13
[task 2022-12-05T19:13:40.805Z] 19:13:40     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1521:3
[task 2022-12-05T19:13:40.805Z] 19:13:40     INFO -     killTest@SimpleTest/TestRunner.js:201:22
[task 2022-12-05T19:13:40.805Z] 19:13:40     INFO -     async*delayedKillTest@SimpleTest/TestRunner.js:238:17
[task 2022-12-05T19:13:40.805Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:236:17
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:249:15
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     TestRunner.resetTests@SimpleTest/TestRunner.js:550:14
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     _runNextTest@SimpleTest/TestRunner.js:655:18
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     TestRunner.testUnloaded@SimpleTest/TestRunner.js:922:16
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     @SimpleTest/iframe-between-tests.html:11:10
[task 2022-12-05T19:13:40.806Z] 19:13:40     INFO -     EventListener.handleEvent*@SimpleTest/iframe-between-tests.html:9:8
[task 2022-12-05T19:13:40.830Z] 19:13:40     INFO - GECKO(2018) | [Parent 2018, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2022-12-05T19:13:40.830Z] 19:13:40     INFO - GECKO(2018) | [Parent 2018, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2022-12-05T19:13:40.997Z] 19:13:40     INFO - GECKO(2018) | [Parent 2018, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-12-05T19:13:41.146Z] 19:13:41     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-12-05T19:13:41.147Z] 19:13:41     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_browserAction_openPopup_windowId.html | no tasks awaiting on messages - got "[\"window-closed\"]", expected "[]"
[task 2022-12-05T19:13:41.147Z] 19:13:41     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:487:14
[task 2022-12-05T19:13:41.147Z] 19:13:41     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:51:18
[task 2022-12-05T19:13:41.147Z] 19:13:41     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1507:13
[task 2022-12-05T19:13:41.158Z] 19:13:41     INFO - GECKO(2018) | MEMORY STAT | vsize 6653MB | residentFast 98MB | heapAllocated 7MB
[task 2022-12-05T19:13:41.203Z] 19:13:41     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_browserAction_openPopup_windowId.html | took 302606ms

Comment 1

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

:oliver, since you are the author of the regressor, bug 1800100, could you take a look?

For more information, please visit auto_nag documentation.

Comment 2

[[e-mail]]

I started looking at this one and noticed the following in the screenshot from the failure: https://imgur.com/a/zCzz9El

Is it possible that is related? I don't think so based on the no tasks awaiting on messages - got "[\"window-closed\"]", expected "[]", which implies it got stuck elsewhere, but it did feel interesting enough to be worth asking.

Adding :gbrown and :jmaher since they are listed at https://firefox-source-docs.mozilla.org/testing/test-verification/index.html. Hope that's ok.

Comment 3

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

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

Comment 4

[Joel Maher ( :jmaher ) (UTC -8)]

this is an intermittent test failure, it is filed as one and I retriggered the test and it didn't fail at all.

I would worry about this if we see it show up a few more times.

Comment 5

[Geoff Brown [:gbrown]]

:jmaher - Yes, but the screenshot is concerning, isn't it? Why is Bluetooth Setup Assistant running at all, and might that cause Firefox to lose focus unexpectedly, resulting in random test failures?

Comment 6

[Joel Maher ( :jmaher ) (UTC -8)]

true, and I saw it and forgot about it. Let me redirect to dhouse who knows a bit more about the OSX r8 machines in our data center; possibly we can turn that off somehow :)

Comment 7

[:dhouse]

I turned off the bluetooth assistant across the R8 test workers (confirmed turned off on the worker in the reported failure in comment #0)

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

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