Closed Bug 1785827 Opened 2 years ago Closed 2 years ago

Intermittent TV browser/components/extensions/test/browser/browser_unified_extensions_context_menu.js | Test timed out -

Categories

(WebExtensions :: Untriaged, defect, P5)

Product:
WebExtensions
Component:
Untriaged
Type:
defect

Tracking

(firefox-esr91 unaffected, firefox-esr102 unaffected, firefox103 unaffected, firefox104 unaffected, firefox105 wontfix, firefox106 affected)

Status:
RESOLVED INCOMPLETE
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox-esr102 --- unaffected
firefox103 --- unaffected
firefox104 --- unaffected
firefox105 --- wontfix
firefox106 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

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

[task 2022-08-18T12:52:35.296Z] 12:52:35     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_unified_extensions_context_menu.js | expected list view - 
[task 2022-08-18T12:52:35.297Z] 12:52:35     INFO - Buffered messages logged at 12:51:04
[task 2022-08-18T12:52:35.298Z] 12:52:35     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2022-08-18T12:52:35.298Z] 12:52:35     INFO - Buffered messages finished
[task 2022-08-18T12:52:35.299Z] 12:52:35     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions_context_menu.js | Test timed out - 
[task 2022-08-18T12:52:35.300Z] 12:52:35     INFO - GECKO(2615) | [Parent 2615, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-08-18T12:52:35.301Z] 12:52:35     INFO - GECKO(2615) | [Parent 2615, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:162
[task 2022-08-18T12:52:35.301Z] 12:52:35     INFO - GECKO(2615) | [Parent 2615, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:162
[task 2022-08-18T12:52:35.302Z] 12:52:35     INFO - GECKO(2615) | [Parent 2615, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2022-08-18T12:52:35.303Z] 12:52:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-08-18T12:52:35.304Z] 12:52:35     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions_context_menu.js | Extension left running at test shutdown - 
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - Stack trace:
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - chrome://mochikit/content/browser-test.js:test_ok:1440
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:132
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - chrome://mochikit/content/browser-test.js:nextTest:599
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1328
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - setTimeout handler*chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest_setTimeoutShim:904
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1289
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1268
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1017
[task 2022-08-18T12:52:35.305Z] 12:52:35     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1038
[task 2022-08-18T12:52:35.306Z] 12:52:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-08-18T12:52:35.306Z] 12:52:35     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_unified_extensions_context_menu.js | Uncaught exception received from previously timed out test - ViewShown listener on #unified-extensions-view not removed before the end of test
[task 2022-08-18T12:52:35.307Z] 12:52:35     INFO - Entering test bound test_remove_extension_cancelled
[task 2022-08-18T12:52:35.307Z] 12:52:35     INFO - Extension loaded
[task 2022-08-18T12:52:35.308Z] 12:52:35     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f0785d44800 == 7 [pid = 2615] [id = 6] [url = about:blank]
[task 2022-08-18T12:52:35.366Z] 12:52:35     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_unified_extensions_context_menu.js | expected button - 
[task 2022-08-18T12:52:35.367Z] 12:52:35     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_unified_extensions_context_menu.js | expected list view - 
[task 2022-08-18T12:52:35.377Z] 12:52:35     INFO - GECKO(2615) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2022-08-18T12:52:35.378Z] 12:52:35     INFO - GECKO(2615) | MEMORY STAT | vsize 3666MB | residentFast 526MB | heapAllocated 221MB
[task 2022-08-18T12:52:35.380Z] 12:52:35     INFO - TEST-OK | browser/components/extensions/test/browser/browser_unified_extensions_context_menu.js | took 180516ms
[task 2022-08-18T12:52:35.395Z] 12:52:35     INFO - GECKO(2615) | [Child 2710: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fe6b591b400 == 1 [pid = 2710] [id = 3]
[task 2022-08-18T12:52:35.397Z] 12:52:35     INFO - GECKO(2615) | [Child 2710: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fe6d0b919f0) [pid = 2710] [serial = 11] [outer = 0]
[task 2022-08-18T12:52:35.398Z] 12:52:35     INFO - GECKO(2615) | [Child 2710: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fe6b591bc00) [pid = 2710] [serial = 12] [outer = 7fe6d0b919f0]
[task 2022-08-18T12:52:35.402Z] 12:52:35     INFO - GECKO(2615) | [Child 2742, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-08-18T12:52:35.443Z] 12:52:35     INFO - checking window state
[task 2022-08-18T12:52:35.497Z] 12:52:35     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (7f0778edd800) [pid = 2615] [serial = 28] [outer = 7f07b0eed180]
[task 2022-08-18T12:52:35.660Z] 12:52:35     INFO - GECKO(2615) | [Child 2742: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f80ff935800 == 0 [pid = 2742] [id = 0] [url = about:blank]
[task 2022-08-18T12:52:35.839Z] 12:52:35     INFO - GECKO(2615) | [Child 2741: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fd337447800 == 1 [pid = 2741] [id = 1] [url = about:newtab]
[task 2022-08-18T12:52:35.859Z] 12:52:35     INFO - GECKO(2615) | [Child 2741: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fd338532400 == 0 [pid = 2741] [id = 0] [url = about:newtab]
[task 2022-08-18T12:52:35.900Z] 12:52:35     INFO - GECKO(2615) | [Child 2741, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:306
[task 2022-08-18T12:52:35.940Z] 12:52:35     INFO - GECKO(2615) | [Child 2741: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7fd353893c60) [pid = 2741] [serial = 4] [outer = 0] [url = about:newtab]
[task 2022-08-18T12:52:35.942Z] 12:52:35     INFO - GECKO(2615) | [Child 2741: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7fd337448c00) [pid = 2741] [serial = 6] [outer = 0] [url = about:newtab]
[task 2022-08-18T12:52:36.030Z] 12:52:36     INFO - GECKO(2615) | [Child 2741: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7fd3538919f0) [pid = 2741] [serial = 1] [outer = 0] [url = about:newtab]
[task 2022-08-18T12:52:36.389Z] 12:52:36     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f078511c000 == 6 [pid = 2615] [id = 5] [url = chrome://browser/content/browser.xhtml]
[task 2022-08-18T12:52:36.389Z] 12:52:36     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f077fdcc400 == 5 [pid = 2615] [id = 7] [url = about:robots]
[task 2022-08-18T12:52:36.820Z] 12:52:36     INFO - GECKO(2615) | [Parent 2615, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1882
[task 2022-08-18T12:52:36.834Z] 12:52:36     INFO - GECKO(2615) | [Parent 2615, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1882
[task 2022-08-18T12:52:36.840Z] 12:52:36     INFO - GECKO(2615) | [Parent 2615, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1882
[task 2022-08-18T12:52:36.845Z] 12:52:36     INFO - GECKO(2615) | [2022-08-18T12:52:36Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2022-08-18T12:52:36.850Z] 12:52:36     INFO - GECKO(2615) | [Child 2741: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7fd338535000) [pid = 2741] [serial = 3] [outer = 0] [url = about:newtab]
[task 2022-08-18T12:52:36.862Z] 12:52:36     INFO - GECKO(2615) | [Child 2862, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-08-18T12:52:36.865Z] 12:52:36     INFO - GECKO(2615) | [Child 2753, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-08-18T12:52:36.869Z] 12:52:36     INFO - GECKO(2615) | [Child 2841, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-08-18T12:52:36.880Z] 12:52:36     INFO - GECKO(2615) | [Child 2753, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-08-18T12:52:36.882Z] 12:52:36     INFO - GECKO(2615) | [Child 2862, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-08-18T12:52:36.889Z] 12:52:36     INFO - GECKO(2615) | [Child 2841, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-08-18T12:52:36.900Z] 12:52:36     INFO - GECKO(2615) | [Child 2753, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4604
[task 2022-08-18T12:52:36.902Z] 12:52:36     INFO - GECKO(2615) | [Child 2742: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f80ff935c00) [pid = 2742] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-08-18T12:52:36.902Z] 12:52:36     INFO - GECKO(2615) | [Child 2742: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f811ac919f0) [pid = 2742] [serial = 1] [outer = 0] [url = about:blank]
[task 2022-08-18T12:52:36.904Z] 12:52:36     INFO - GECKO(2615) | [Child 2862, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4604
[task 2022-08-18T12:52:36.923Z] 12:52:36     INFO - GECKO(2615) | [Child 2841, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4604
[task 2022-08-18T12:52:37.657Z] 12:52:37     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (7f078659b6f0) [pid = 2615] [serial = 15] [outer = 0] [url = about:blank]
[task 2022-08-18T12:52:37.657Z] 12:52:37     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (7f078659ac10) [pid = 2615] [serial = 17] [outer = 0] [url = about:robots]
[task 2022-08-18T12:52:37.660Z] 12:52:37     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (7f0785124c00) [pid = 2615] [serial = 14] [outer = 0] [url = about:blank]
[task 2022-08-18T12:52:37.661Z] 12:52:37     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (7f0785d45c00) [pid = 2615] [serial = 16] [outer = 0] [url = about:blank]
[task 2022-08-18T12:52:37.663Z] 12:52:37     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (7f0786598600) [pid = 2615] [serial = 13] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2022-08-18T12:52:37.664Z] 12:52:37     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (7f0785935c00) [pid = 2615] [serial = 19] [outer = 0] [url = about:robots]
[task 2022-08-18T12:52:38.007Z] 12:52:38     INFO - GECKO(2615) | Completed ShutdownLeaks collections in process 2710
[task 2022-08-18T12:52:38.010Z] 12:52:38     INFO - GECKO(2615) | Completed ShutdownLeaks collections in process 2742
[task 2022-08-18T12:52:38.026Z] 12:52:38     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f07946a3800) [pid = 2615] [serial = 7] [outer = 0] [url = about:blank]
[task 2022-08-18T12:52:38.059Z] 12:52:38     INFO - GECKO(2615) | Completed ShutdownLeaks collections in process 2741
[task 2022-08-18T12:52:38.351Z] 12:52:38     INFO - GECKO(2615) | Completed ShutdownLeaks collections in process 2686
[task 2022-08-18T12:52:38.446Z] 12:52:38     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (7f0778ed7400) [pid = 2615] [serial = 29] [outer = 7f07b0eed180]
[task 2022-08-18T12:52:40.297Z] 12:52:40     INFO - GECKO(2615) | [Parent 2615: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f0778edd800) [pid = 2615] [serial = 28] [outer = 0] [url = about:blank]
[task 2022-08-18T12:52:40.637Z] 12:52:40     INFO - GECKO(2615) | Completed ShutdownLeaks collections in process 2615
[task 2022-08-18T12:52:40.641Z] 12:52:40     INFO - TEST-START | Shutdown
[task 2022-08-18T12:52:40.642Z] 12:52:40     INFO - Browser Chrome Test Summary
[task 2022-08-18T12:52:40.643Z] 12:52:40     INFO - Passed:  57
[task 2022-08-18T12:52:40.644Z] 12:52:40     INFO - Failed:  3
[task 2022-08-18T12:52:40.645Z] 12:52:40     INFO - Todo:    0
[task 2022-08-18T12:52:40.646Z] 12:52:40     INFO - Mode:    e10s
[task 2022-08-18T12:52:40.647Z] 12:52:40     INFO - *** End BrowserChrome Test Results ***

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

Flags: needinfo?(wdurand)

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

status-firefox103: --- → unaffected
status-firefox104: --- → unaffected
status-firefox105: --- → affected
status-firefox-esr102: --- → unaffected
status-firefox-esr91: --- → unaffected

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

status-firefox106: --- → affected
Flags: needinfo?(wdurand)

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

Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.