1629034 - Intermittent TEST-UNEXPECTED-PASS | browser/base/content/test/general/browser_minimize.js | We expect at least one assertion to fail because this test file is marked as fail-if in the manifest. -

Status: RESOLVED FIXED

(Firefox :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-04-10T10:49:05.952Z] 10:49:05 INFO - GECKO(1224) | #10: nsContentUtils::RemoveScriptBlocker() [hg:hg.mozilla.org/mozilla-central:dom/base/nsContentUtils.cpp:280c84604ac05aff9091684f045106fee7268116:5369]
[task 2020-04-10T10:49:05.955Z] 10:49:05 INFO - GECKO(1224) | #11: nsFrameLoaderOwner::ChangeRemotenessCommon(bool, bool, nsTSubstring<char16_t> const&, std::function<void ()>&, mozilla::ErrorResult&) [hg:hg.mozilla.org/mozilla-central:dom/base/nsFrameLoaderOwner.cpp:280c84604ac05aff9091684f045106fee7268116:134]
[task 2020-04-10T10:49:05.956Z] 10:49:05 INFO - GECKO(1224) | #12: nsFrameLoaderOwner::ChangeRemoteness(mozilla::dom::RemotenessOptions const&, mozilla::ErrorResult&) [hg:hg.mozilla.org/mozilla-central:dom/base/nsFrameLoaderOwner.cpp:280c84604ac05aff9091684f045106fee7268116:210]
[task 2020-04-10T10:49:05.956Z] 10:49:05 INFO - GECKO(1224) | #13: mozilla::dom::XULFrameElement_Binding::changeRemoteness(JSContext*, JS::Handle<JSObject*>, void*, JSJitMethodCallArgs const&) [s3:gecko-generated-sources:20a54bb77609f2cfc4c18a09bea7d4e77c67e9158c4ad373bb89898bee8245318c713677325b7c7453a781d93548cfbdc5d6cfddb53cbdf56aaf87d3f3a88996/dom/bindings/XULFrameElementBinding.cpp::385]
[task 2020-04-10T10:49:05.956Z] 10:49:05 INFO - GECKO(1224) | #14: bool mozilla::dom::binding_detail::GenericMethod<mozilla::dom::binding_detail::NormalThisPolicy, mozilla::dom::binding_detail::ThrowExceptions>(JSContext*, unsigned int, JS::Value*) [hg:hg.mozilla.org/mozilla-central:dom/bindings/BindingUtils.cpp:280c84604ac05aff9091684f045106fee7268116:3207]
[task 2020-04-10T10:49:05.956Z] 10:49:05 INFO - GECKO(1224) | #15: ??? (???:???)
[task 2020-04-10T10:49:05.956Z] 10:49:05 INFO - GECKO(1224) | [Parent 1224, Main Thread] ###!!! ASSERTION: Wrong message manager!: 'fl->mMessageManager == mChromeMessageManager', file /builds/worker/checkouts/gecko/dom/base/InProcessBrowserChildMessageManager.cpp, line 263
[task 2020-04-10T10:49:05.956Z] 10:49:05 INFO - GECKO(1224) | #01: mozilla::EventTargetChainItem::GetEventTargetParent(mozilla::EventChainPreVisitor&) [hg:hg.mozilla.org/mozilla-central:dom/events/EventDispatcher.cpp:280c84604ac05aff9091684f045106fee7268116:425]
[task 2020-04-10T10:49:05.959Z] 10:49:05 INFO - GECKO(1224) | #02: mozilla::EventDispatcher::Dispatch(nsISupports*, nsPresContext*, mozilla::WidgetEvent*, mozilla::dom::Event*, nsEventStatus*, mozilla::EventDispatchingCallback*, nsTArray<mozilla::dom::EventTarget*>) [hg:hg.mozilla.org/mozilla-central:dom/events/EventDispatcher.cpp:280c84604ac05aff9091684f045106fee7268116:969]
[task 2020-04-10T10:49:05.959Z] 10:49:05 INFO - GECKO(1224) | #03: nsDocumentViewer::PageHide(bool) [hg:hg.mozilla.org/mozilla-central:layout/base/nsDocumentViewer.cpp:280c84604ac05aff9091684f045106fee7268116:1514]
[task 2020-04-10T10:49:05.959Z] 10:49:05 INFO - GECKO(1224) | #04: nsDocShell::FirePageHideNotificationInternal(bool, bool) [hg:hg.mozilla.org/mozilla-central:docshell/base/nsDocShell.cpp:280c84604ac05aff9091684f045106fee7268116:970]
[task 2020-04-10T10:49:05.960Z] 10:49:05 INFO - GECKO(1224) | #05: nsDocShell::Destroy() [hg:hg.mozilla.org/mozilla-central:docshell/base/nsDocShell.cpp:280c84604ac05aff9091684f045106fee7268116:4313]
[task 2020-04-10T10:49:05.960Z] 10:49:05 INFO - GECKO(1224) | #06: nsFrameLoader::DestroyDocShell() [hg:hg.mozilla.org/mozilla-central:dom/base/nsFrameLoader.cpp:280c84604ac05aff9091684f045106fee7268116:1947]
[task 2020-04-10T10:49:05.960Z] 10:49:05 INFO - GECKO(1224) | #07: nsFrameLoaderDestroyRunnable::Run() [hg:hg.mozilla.org/mozilla-central:dom/base/nsFrameLoader.cpp:280c84604ac05aff9091684f045106fee7268116:1887]
[task 2020-04-10T10:49:05.960Z] 10:49:05 INFO - GECKO(1224) | #08: mozilla::dom::Document::MaybeInitializeFinalizeFrameLoaders() [hg:hg.mozilla.org/mozilla-central:dom/base/Document.cpp:280c84604ac05aff9091684f045106fee7268116:8605]
[task 2020-04-10T10:49:05.961Z] 10:49:05 INFO - GECKO(1224) | #09: mozilla::detail::RunnableMethodImpl<mozilla::dom::Document, void (mozilla::dom::Document::)(), true, (mozilla::RunnableKind)0>::Run() [hg:hg.mozilla.org/mozilla-central:xpcom/threads/nsThreadUtils.h:280c84604ac05aff9091684f045106fee7268116:1223]
[task 2020-04-10T10:49:05.961Z] 10:49:05 INFO - GECKO(1224) | #10: nsContentUtils::RemoveScriptBlocker() [hg:hg.mozilla.org/mozilla-central:dom/base/nsContentUtils.cpp:280c84604ac05aff9091684f045106fee7268116:5369]
[task 2020-04-10T10:49:05.963Z] 10:49:05 INFO - GECKO(1224) | #11: nsFrameLoaderOwner::ChangeRemotenessCommon(bool, bool, nsTSubstring<char16_t> const&, std::function<void ()>&, mozilla::ErrorResult&) [hg:hg.mozilla.org/mozilla-central:dom/base/nsFrameLoaderOwner.cpp:280c84604ac05aff9091684f045106fee7268116:134]
[task 2020-04-10T10:49:05.971Z] 10:49:05 INFO - GECKO(1224) | #12: nsFrameLoaderOwner::ChangeRemoteness(mozilla::dom::RemotenessOptions const&, mozilla::ErrorResult&) [hg:hg.mozilla.org/mozilla-central:dom/base/nsFrameLoaderOwner.cpp:280c84604ac05aff9091684f045106fee7268116:210]
[task 2020-04-10T10:49:05.972Z] 10:49:05 INFO - GECKO(1224) | #13: mozilla::dom::XULFrameElement_Binding::changeRemoteness(JSContext, JS::Handle<JSObject*>, void*, JSJitMethodCallArgs const&) [s3:gecko-generated-sources:20a54bb77609f2cfc4c18a09bea7d4e77c67e9158c4ad373bb89898bee8245318c713677325b7c7453a781d93548cfbdc5d6cfddb53cbdf56aaf87d3f3a88996/dom/bindings/XULFrameElementBinding.cpp::385]
[task 2020-04-10T10:49:05.972Z] 10:49:05 INFO - GECKO(1224) | #14: bool mozilla::dom::binding_detail::GenericMethod<mozilla::dom::binding_detail::NormalThisPolicy, mozilla::dom::binding_detail::ThrowExceptions>(JSContext*, unsigned int, JS::Value*) [hg:hg.mozilla.org/mozilla-central:dom/bindings/BindingUtils.cpp:280c84604ac05aff9091684f045106fee7268116:3207]
[task 2020-04-10T10:49:05.975Z] 10:49:05 INFO - GECKO(1224) | #15: ??? (???:???)
[task 2020-04-10T10:49:05.975Z] 10:49:05 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd92c055400 == 3 [pid = 1366] [id = {929f8958-f10b-450b-9451-66ec1f51577c}]
[task 2020-04-10T10:49:05.975Z] 10:49:05 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0x7fd92d24bc80) [pid = 1366] [serial = 169] [outer = (nil)]
[task 2020-04-10T10:49:05.975Z] 10:49:05 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x7fd92c056400) [pid = 1366] [serial = 170] [outer = 0x7fd92d24bc80]
[task 2020-04-10T10:49:06.054Z] 10:49:06 INFO - GECKO(1224) | JavaScript error: resource://gre/modules/URIFixup.jsm, line 398: NS_ERROR_MALFORMED_URI: Couldn't build a valid uri
[task 2020-04-10T10:49:06.105Z] 10:49:06 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0x7fd92c05dc00) [pid = 1366] [serial = 171] [outer = 0x7fd92d24bc80]
[task 2020-04-10T10:49:06.247Z] 10:49:06 INFO - GECKO(1224) | [Child 1366, Main Thread] WARNING: '!mFailedChannel', file /builds/worker/checkouts/gecko/dom/base/Document.cpp, line 1564
[task 2020-04-10T10:49:06.453Z] 10:49:06 INFO - GECKO(1224) | [Parent 1224: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f9a56589400 == 27 [pid = 1224] [id = {a651e587-810c-4498-8213-b96a8ef8f00c}]
[task 2020-04-10T10:49:06.453Z] 10:49:06 INFO - GECKO(1224) | [Parent 1224: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 75 (0x7f9a7a3cb580) [pid = 1224] [serial = 249] [outer = (nil)]
[task 2020-04-10T10:49:06.453Z] 10:49:06 INFO - GECKO(1224) | [Parent 1224: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 76 (0x7f9a74d5e000) [pid = 1224] [serial = 250] [outer = 0x7f9a7a3cb580]
[task 2020-04-10T10:49:06.710Z] 10:49:06 INFO - GECKO(1224) | [Parent 1224: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 77 (0x7f9a7561e400) [pid = 1224] [serial = 251] [outer = 0x7f9a7a3cb580]
[task 2020-04-10T10:49:06.798Z] 10:49:06 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fd92c052000 == 2 [pid = 1366] [id = {61aa3924-b38e-458f-aff2-ae2f28d14e93}] [url = about:blank]
[task 2020-04-10T10:49:06.798Z] 10:49:06 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fd92c053400 == 1 [pid = 1366] [id = {3466781f-fadf-4bbd-822b-908b1f9c28a3}] [url = about:blank]
[task 2020-04-10T10:49:06.919Z] 10:49:06 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7fd92d24b3c0) [pid = 1366] [serial = 163] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:07.001Z] 10:49:07 INFO - GECKO(1224) | [Parent 1224, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorService.cpp, line 188
[task 2020-04-10T10:49:07.002Z] 10:49:07 INFO - GECKO(1224) | [Parent 1224, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorService.cpp, line 188
[task 2020-04-10T10:49:07.142Z] 10:49:07 INFO - GECKO(1224) | MEMORY STAT | vsize 3580MB | residentFast 588MB | heapAllocated 272MB
[task 2020-04-10T10:49:07.144Z] 10:49:07 INFO - TEST-OK | browser/base/content/test/general/browser_invalid_uri_back_forward_manipulation.js | took 1952ms
[task 2020-04-10T10:49:07.151Z] 10:49:07 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd92c052c00 == 2 [pid = 1366] [id = {8366b0c2-2820-4e51-b85b-256997d992e0}]
[task 2020-04-10T10:49:07.151Z] 10:49:07 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0x7fd92d24b3c0) [pid = 1366] [serial = 172] [outer = (nil)]
[task 2020-04-10T10:49:07.151Z] 10:49:07 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0x7fd92c054c00) [pid = 1366] [serial = 173] [outer = 0x7fd92d24b3c0]
[task 2020-04-10T10:49:07.209Z] 10:49:07 INFO - checking window state
[task 2020-04-10T10:49:07.270Z] 10:49:07 INFO - TEST-START | browser/base/content/test/general/browser_lastAccessedTab.js
[task 2020-04-10T10:49:07.492Z] 10:49:07 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f09bea28c00 == 2 [pid = 1308] [id = {34e36467-2e04-4faf-b224-297553193096}]
[task 2020-04-10T10:49:07.492Z] 10:49:07 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7f09beeea040) [pid = 1308] [serial = 218] [outer = (nil)]
[task 2020-04-10T10:49:07.493Z] 10:49:07 INFO - GECKO(1224) | [Child 1308, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 844
[task 2020-04-10T10:49:07.493Z] 10:49:07 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7f09bea2e000) [pid = 1308] [serial = 219] [outer = 0x7f09beeea040]
[task 2020-04-10T10:49:07.593Z] 10:49:07 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (0x7f09beea5400) [pid = 1308] [serial = 220] [outer = 0x7f09beeea040]
[task 2020-04-10T10:49:07.966Z] 10:49:07 INFO - GECKO(1224) | MEMORY STAT | vsize 3575MB | residentFast 583MB | heapAllocated 273MB
[task 2020-04-10T10:49:07.966Z] 10:49:07 INFO - TEST-OK | browser/base/content/test/general/browser_lastAccessedTab.js | took 689ms
[task 2020-04-10T10:49:08.002Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f09bea2f400 == 3 [pid = 1308] [id = {19ae337c-3546-4dd4-9d05-d8a93416d598}]
[task 2020-04-10T10:49:08.002Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (0x7f09beeea580) [pid = 1308] [serial = 221] [outer = (nil)]
[task 2020-04-10T10:49:08.003Z] 10:49:08 INFO - GECKO(1224) | [Child 1308, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 844
[task 2020-04-10T10:49:08.003Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (0x7f09bea32000) [pid = 1308] [serial = 222] [outer = 0x7f09beeea580]
[task 2020-04-10T10:49:08.023Z] 10:49:08 INFO - checking window state
[task 2020-04-10T10:49:08.084Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0x7f09bea28000) [pid = 1308] [serial = 208] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:08.084Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0x7f09bea2ac00) [pid = 1308] [serial = 206] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:08.084Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0x7f09bea31c00) [pid = 1308] [serial = 214] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:08.084Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7f09bea2b000) [pid = 1308] [serial = 210] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:08.084Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f09bea29000 == 2 [pid = 1308] [id = {ac1b5495-978b-402d-befc-5efe51abb5d3}] [url = about:blank]
[task 2020-04-10T10:49:08.099Z] 10:49:08 INFO - TEST-START | browser/base/content/test/general/browser_menuButtonFitts.js
[task 2020-04-10T10:49:08.337Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7f09beeea900) [pid = 1308] [serial = 211] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:08.338Z] 10:49:08 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7f09beeeaac0) [pid = 1308] [serial = 213] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:08.717Z] 10:49:08 INFO - GECKO(1224) | [Parent 1224, Main Thread] WARNING: '!mPresContext', file /builds/worker/checkouts/gecko/dom/events/UIEvent.cpp, line 137
[task 2020-04-10T10:49:08.959Z] 10:49:08 INFO - GECKO(1224) | JavaScript error: resource://gre/modules/URIFixup.jsm, line 199: NS_ERROR_FAILURE: Should pass a non-null uri
[task 2020-04-10T10:49:09.116Z] 10:49:09 INFO - GECKO(1224) | JavaScript error: resource:///modules/PanelMultiView.jsm, line 1229: TypeError: can't access property "descriptionHeightWorkaround", mainPanelView is undefined
[task 2020-04-10T10:49:09.480Z] 10:49:09 INFO - GECKO(1224) | MEMORY STAT | vsize 3577MB | residentFast 594MB | heapAllocated 284MB
[task 2020-04-10T10:49:09.480Z] 10:49:09 INFO - TEST-OK | browser/base/content/test/general/browser_menuButtonFitts.js | took 1385ms
[task 2020-04-10T10:49:09.490Z] 10:49:09 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd92c055800 == 3 [pid = 1366] [id = {f46675ba-c534-4eb3-8b02-1f14bf984920}]
[task 2020-04-10T10:49:09.491Z] 10:49:09 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7fd9330e1200) [pid = 1366] [serial = 174] [outer = (nil)]
[task 2020-04-10T10:49:09.491Z] 10:49:09 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7fd92c057400) [pid = 1366] [serial = 175] [outer = 0x7fd9330e1200]
[task 2020-04-10T10:49:09.535Z] 10:49:09 INFO - checking window state
[task 2020-04-10T10:49:09.700Z] 10:49:09 INFO - GECKO(1224) | JavaScript error: resource://gre/modules/URIFixup.jsm, line 199: NS_ERROR_FAILURE: Should pass a non-null uri
[task 2020-04-10T10:49:09.735Z] 10:49:09 INFO - TEST-START | browser/base/content/test/general/browser_middleMouse_noJSPaste.js
[task 2020-04-10T10:49:09.783Z] 10:49:09 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f09bea27400 == 3 [pid = 1308] [id = {06191969-66dd-4551-8f1e-f2c00b4c274d}]
[task 2020-04-10T10:49:09.784Z] 10:49:09 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0x7f09beeea740) [pid = 1308] [serial = 223] [outer = (nil)]
[task 2020-04-10T10:49:09.785Z] 10:49:09 INFO - GECKO(1224) | [Child 1308, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 844
[task 2020-04-10T10:49:09.785Z] 10:49:09 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7f09bea29000) [pid = 1308] [serial = 224] [outer = 0x7f09beeea740]
[task 2020-04-10T10:49:09.902Z] 10:49:09 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7f09bee9e800) [pid = 1308] [serial = 225] [outer = 0x7f09beeea740]
[task 2020-04-10T10:49:10.642Z] 10:49:10 INFO - GECKO(1224) | [Child 1308, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsLoadGroup.cpp, line 591
[task 2020-04-10T10:49:10.781Z] 10:49:10 INFO - GECKO(1224) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpVQSTp6.mozrunner/runtests_leaks_tab_pid3625.log
[task 2020-04-10T10:49:10.781Z] 10:49:10 INFO - GECKO(1224) | [3625, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 221
[task 2020-04-10T10:49:11.019Z] 10:49:11 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7fd92c056400) [pid = 1366] [serial = 170] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:11.019Z] 10:49:11 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fd92c051400) [pid = 1366] [serial = 164] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:11.035Z] 10:49:11 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fd92c052c00 == 2 [pid = 1366] [id = {8366b0c2-2820-4e51-b85b-256997d992e0}] [url = about:blank]
[task 2020-04-10T10:49:11.103Z] 10:49:11 INFO - GECKO(1224) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-04-10T10:49:11.220Z] 10:49:11 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fd92c055400 == 1 [pid = 1366] [id = {929f8958-f10b-450b-9451-66ec1f51577c}] [url = about:blank]
[task 2020-04-10T10:49:11.220Z] 10:49:11 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7fd92d24b580) [pid = 1366] [serial = 165] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:11.220Z] 10:49:11 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7fd92d24b900) [pid = 1366] [serial = 167] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:11.220Z] 10:49:11 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7fd92d24bc80) [pid = 1366] [serial = 169] [outer = (nil)] [url = about:neterror?e=malformedURI&u=%3A%3A2600&c=UTF-8&f=regular&d=Please%20check%20that%20the%20URL%20is%20correct%20and%20try%20again.]
[task 2020-04-10T10:49:11.302Z] 10:49:11 INFO - GECKO(1224) | [Child 3625, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1678
[task 2020-04-10T10:49:11.310Z] 10:49:11 INFO - GECKO(1224) | [Child 1447: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f077185a000 == 1 [pid = 1447] [id = {beb80b80-047f-49f7-b252-70817b14afc5}] [url = data:text/html,<body>]
[task 2020-04-10T10:49:11.310Z] 10:49:11 INFO - GECKO(1224) | [Child 1447: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f077185d000 == 0 [pid = 1447] [id = {ecfb5e87-f879-4a67-a3a5-1f1ea630eb69}] [url = about:blank]
[task 2020-04-10T10:49:11.326Z] 10:49:11 INFO - GECKO(1224) | [Child 3625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fc8f0adb400 == 1 [pid = 3625] [id = {06191969-66dd-4551-8f1e-f2c00b4c274d}]
[task 2020-04-10T10:49:11.346Z] 10:49:11 INFO - GECKO(1224) | [Child 3625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7fc905e65c80) [pid = 3625] [serial = 1] [outer = (nil)]
[task 2020-04-10T10:49:11.350Z] 10:49:11 INFO - GECKO(1224) | [Child 3625, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 844
[task 2020-04-10T10:49:11.351Z] 10:49:11 INFO - GECKO(1224) | [Child 3625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7fc8eab2bc00) [pid = 3625] [serial = 2] [outer = 0x7fc905e65c80]
[task 2020-04-10T10:49:11.432Z] 10:49:11 INFO - GECKO(1224) | [Child 1447: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f0771a0f900) [pid = 1447] [serial = 106] [outer = (nil)] [url = data:text/html,<body>]
[task 2020-04-10T10:49:11.557Z] 10:49:11 INFO - GECKO(1224) | [Child 3625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fc8f098fc00) [pid = 3625] [serial = 3] [outer = 0x7fc905e65c80]
[task 2020-04-10T10:49:11.715Z] 10:49:11 INFO - GECKO(1224) | [Parent 1224, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 92
[task 2020-04-10T10:49:11.716Z] 10:49:11 INFO - GECKO(1224) | [Parent 1224, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-10T10:49:11.716Z] 10:49:11 INFO - GECKO(1224) | [Parent 1224, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-10T10:49:12.199Z] 10:49:12 INFO - GECKO(1224) | MEMORY STAT | vsize 3589MB | residentFast 596MB | heapAllocated 280MB
[task 2020-04-10T10:49:12.201Z] 10:49:12 INFO - TEST-OK | browser/base/content/test/general/browser_middleMouse_noJSPaste.js | took 2464ms
[task 2020-04-10T10:49:12.261Z] 10:49:12 INFO - checking window state
[task 2020-04-10T10:49:12.269Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f09bea2b000 == 4 [pid = 1308] [id = {3139b340-fde3-4e08-b415-111736bba73c}]
[task 2020-04-10T10:49:12.270Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (0x7f09beeea900) [pid = 1308] [serial = 226] [outer = (nil)]
[task 2020-04-10T10:49:12.271Z] 10:49:12 INFO - GECKO(1224) | [Child 1308, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 844
[task 2020-04-10T10:49:12.274Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (0x7f09bea2f800) [pid = 1308] [serial = 227] [outer = 0x7f09beeea900]
[task 2020-04-10T10:49:12.319Z] 10:49:12 INFO - TEST-START | browser/base/content/test/general/browser_minimize.js
[task 2020-04-10T10:49:12.343Z] 10:49:12 INFO - GECKO(1224) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-10T10:49:12.607Z] 10:49:12 INFO - GECKO(1224) | [Child 3625: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fc8f0adb400 == 0 [pid = 3625] [id = {06191969-66dd-4551-8f1e-f2c00b4c274d}] [url = http://www.example.com/]
[task 2020-04-10T10:49:12.668Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0x7f09beea8800) [pid = 1308] [serial = 215] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:12.672Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0x7f09bea2fc00) [pid = 1308] [serial = 212] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:12.692Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f09bea2f400 == 3 [pid = 1308] [id = {19ae337c-3546-4dd4-9d05-d8a93416d598}] [url = about:blank]
[task 2020-04-10T10:49:12.693Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f09bea28c00 == 2 [pid = 1308] [id = {34e36467-2e04-4faf-b224-297553193096}] [url = about:blank]
[task 2020-04-10T10:49:12.694Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f09bea27400 == 1 [pid = 1308] [id = {06191969-66dd-4551-8f1e-f2c00b4c274d}] [url = about:blank]
[task 2020-04-10T10:49:12.714Z] 10:49:12 INFO - GECKO(1224) | [Child 3625: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fc905e65c80) [pid = 3625] [serial = 1] [outer = (nil)] [url = http://www.example.com/]
[task 2020-04-10T10:49:12.718Z] 10:49:12 INFO - GECKO(1224) | [Child 3625: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fc8eab2bc00) [pid = 3625] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:12.718Z] 10:49:12 INFO - GECKO(1224) | [Child 3625: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fc8f098fc00) [pid = 3625] [serial = 3] [outer = (nil)] [url = http://www.example.com/]
[task 2020-04-10T10:49:12.721Z] 10:49:12 INFO - GECKO(1224) | [Child 3625, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/checkouts/gecko/netwerk/base/nsNetUtil.cpp, line 2307
[task 2020-04-10T10:49:12.722Z] 10:49:12 INFO - GECKO(1224) | [Child 3625, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/caps/ContentPrincipal.cpp, line 398
[task 2020-04-10T10:49:12.722Z] 10:49:12 INFO - GECKO(1224) | [Child 3625, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/checkouts/gecko/caps/ContentPrincipal.cpp, line 423
[task 2020-04-10T10:49:12.745Z] 10:49:12 INFO - GECKO(1224) | [Child 3625, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-04-10T10:49:12.782Z] 10:49:12 INFO - GECKO(1224) | nsStringStats
[task 2020-04-10T10:49:12.782Z] 10:49:12 INFO - GECKO(1224) | => mAllocCount: 10033
[task 2020-04-10T10:49:12.782Z] 10:49:12 INFO - GECKO(1224) | => mReallocCount: 0
[task 2020-04-10T10:49:12.783Z] 10:49:12 INFO - GECKO(1224) | => mFreeCount: 10033
[task 2020-04-10T10:49:12.784Z] 10:49:12 INFO - GECKO(1224) | => mShareCount: 7642
[task 2020-04-10T10:49:12.784Z] 10:49:12 INFO - GECKO(1224) | => mAdoptCount: 580
[task 2020-04-10T10:49:12.784Z] 10:49:12 INFO - GECKO(1224) | => mAdoptFreeCount: 584
[task 2020-04-10T10:49:12.784Z] 10:49:12 INFO - GECKO(1224) | => Process ID: 3625, Thread ID: 140501388355456
[task 2020-04-10T10:49:12.922Z] 10:49:12 INFO - Buffered messages logged at 10:49:12
[task 2020-04-10T10:49:12.923Z] 10:49:12 INFO - Entering test bound
[task 2020-04-10T10:49:12.923Z] 10:49:12 INFO - TEST-PASS | browser/base/content/test/general/browser_minimize.js | Docshell should be active -
[task 2020-04-10T10:49:12.923Z] 10:49:12 INFO - TEST-PASS | browser/base/content/test/general/browser_minimize.js | Docshell should be Inactive -
[task 2020-04-10T10:49:12.923Z] 10:49:12 INFO - TEST-PASS | browser/base/content/test/general/browser_minimize.js | Docshell should be active again -
[task 2020-04-10T10:49:12.923Z] 10:49:12 INFO - Leaving test bound
[task 2020-04-10T10:49:12.923Z] 10:49:12 INFO - Buffered messages finished
[task 2020-04-10T10:49:12.923Z] 10:49:12 INFO - TEST-UNEXPECTED-PASS | browser/base/content/test/general/browser_minimize.js | We expect at least one assertion to fail because this test file is marked as fail-if in the manifest. -
[task 2020-04-10T10:49:12.923Z] 10:49:12 INFO - TEST-INFO | expected FAIL
[task 2020-04-10T10:49:12.929Z] 10:49:12 INFO - GECKO(1224) | MEMORY STAT | vsize 3578MB | residentFast 590MB | heapAllocated 279MB
[task 2020-04-10T10:49:12.930Z] 10:49:12 INFO - TEST-OK | browser/base/content/test/general/browser_minimize.js | took 600ms
[task 2020-04-10T10:49:12.930Z] 10:49:12 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd92c052400 == 2 [pid = 1366] [id = {f01baaad-0e30-4930-8e60-09e7f34f22ef}]
[task 2020-04-10T10:49:12.931Z] 10:49:12 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x7fd92d24b580) [pid = 1366] [serial = 176] [outer = (nil)]
[task 2020-04-10T10:49:12.931Z] 10:49:12 INFO - GECKO(1224) | [Child 1366: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0x7fd92c055000) [pid = 1366] [serial = 177] [outer = 0x7fd92d24b580]
[task 2020-04-10T10:49:12.957Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7f09beeeac80) [pid = 1308] [serial = 216] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:12.957Z] 10:49:12 INFO - GECKO(1224) | [Child 1308: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7f09beeea040) [pid = 1308] [serial = 218] [outer = (nil)] [url = about:blank]
[task 2020-04-10T10:49:12.972Z] 10:49:12 INFO - checking window state
[task 2020-04-10T10:49:12.974Z] 10:49:12 INFO - GECKO(1224) | must wait for focus
[task 2020-04-10T10:49:13.213Z] 10:49:13 INFO - TEST-START | browser/base/content/test/general/browser_modifiedclick_inherit_principal.js

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 2

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

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 5

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

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

Comment 6

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

New occurrence: https://treeherder.mozilla.org/logviewer.html#?job_id=315462084&repo=mozilla-central

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• try: 1

Platform breakdown:

• linux1804-64: 5

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64: 2

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

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• autoland: 2

Platform breakdown:

• linux1804-64: 3

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

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• linux1804-64: 3

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

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64: 2

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

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• autoland: 1

Platform breakdown:

• linux1804-64: 2

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

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 17

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

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

Comment 18

[Bogdan Tara[:bogdan_tara | bogdant]]

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer?job_id=333052978&repo=autoland&lineNumber=2705

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64-tsan: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-03-08&endday=2021-03-14&tree=all

Comment 20

[Intermittent Failures Robot]

31 failures in 714 pushes (0.043 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 29
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-tsan: 19
  • opt: 19
• linux1804-64: 5
  • debug: 2
  • opt: 3
• linux1804-64-asan: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-03-26&endday=2021-03-26&tree=all

Comment 21

[Cosmin Sabou [:CosminS]]

This is from Bug 1596165: https://treeherder.mozilla.org/jobs?repo=autoland&selectedTaskRun=Hv7kHXm5SkC0LfxWVIpmVA.0&resultStatus=success%2Ctestfailed%2Cbusted%2Cexception%2Cusercancel&test_paths=browser%2Fbase%2Fcontent%2Ftest%2Fgeneral%2F&tochange=8581dbde5b5b7bba41fe56e80a072f7cdc42ad08&fromchange=42906f68ffd564e2eead895c2156f8a5727762ba&searchStr=linux1804-64-tsan
Initially backed out, it resurfaced when it was relanded.
Florian, please have a look over this. Thank you.

Comment 22

[Intermittent Failures Robot]

52 failures in 3967 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #24 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: 46
• mozilla-central: 5
• try: 1

Platform and build breakdown:

• linux1804-64-tsan: 31
  • opt: 31
• linux1804-64-asan: 14
  • opt: 14
• linux1804-64: 7
  • debug: 3
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-03-22&endday=2021-03-28&tree=all

Comment 23

[Florian Quèze [:florian]]

So if I understand correctly, this test was a perma-fail on Linux (bug 1600177) and now it's passing more often than not on slow (tsan, asan) builds, but still failing most of the time on optimized builds. I guess that's an improvement?

Joel, any idea of what to do here?

Comment 24

[Joel Maher ( :jmaher ) (UTC -8) (PTO back normal Nov 17)]

this sounds like a timing error- I honestly don't do much to fix tests, just find patterns and collect more data. Could pernosco help

Comment 25

[Intermittent Failures Robot]

26 failures in 647 pushes (0.04 failures/push) were associated with this bug yesterday.

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64-tsan: 17
  • opt: 17
• linux1804-64: 1
  • debug: 1
• linux1804-64-asan: 8
  • opt: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-03-29&endday=2021-03-29&tree=all

Comment 26

[Intermittent Failures Robot]

32 failures in 698 pushes (0.046 failures/push) were associated with this bug yesterday.

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64-asan: 9
  • opt: 9
• linux1804-64: 4
  • debug: 4
• linux1804-64-tsan: 19
  • opt: 19

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-03-30&endday=2021-03-30&tree=all

Comment 27

[Intermittent Failures Robot]

21 failures in 633 pushes (0.033 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 20
• try: 1

Platform and build breakdown:

• linux1804-64-asan: 6
  • opt: 6
• linux1804-64-tsan: 12
  • opt: 12
• linux1804-64: 3
  • debug: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-03-31&endday=2021-03-31&tree=all

Comment 28

[Florian Quèze [:florian]]

I recorded a trace of the test passing once and then failing the second time on a local Ubuntu 18.04 machine. Here's the Pernosco trace: https://pernos.co/debug/yhMriIZ2H9mdxhnJfHiLMg/index.html

From there I saw that:

• BrowsingContext::IsActive always goes in the case where the active status has been set explicitly (ie. SetExplicitActive has been called).
• SetExplicitActive is always called from JS code, triggered by the dispatch of a DOM event.

Figuring out which JS code was running and which DOM event was triggered wasn't easy from the Pernosco trace, so I added a profiler marker in the SetIsActive method in BrowsingContext.h:

    PROFILER_MARKER_TEXT("BrowsingContext::SetIsActive", DOM,
                         MarkerStack::Capture(),
                         ProfilerString8View::WrapNullTerminatedString(aIsActive ? "active" : "inactive"));

I then captured profiles of the test using ./mach test browser/base/content/test/general/browser_minimize.js --run-until-failure --profiler.

Here's a profile on Linux with this new marker, with the test passing at the first run and failing at the second run: https://share.firefox.dev/3dvtPo5
There we see that the browsingContext is set to "inactive" from JS code triggered by the sizemodechange DOM event.
In the passing run, after the window has been restored, it is set back to "active" from this stack:

(root) []
XREMain::XRE_main []
Task onQueueUnload/this.unloadTimer<[AsyncTabSwitcher.jsm]:JS []
nsTimerImpl::Fire []
XPCWrappedJS method call []
js::RunScript []
onQueueUnload/this.unloadTimer< [resource:///modules/AsyncTabSwitcher.jsm:1060:6]
handleEvent [resource:///modules/AsyncTabSwitcher.jsm:1065:13]
postActions [resource:///modules/AsyncTabSwitcher.jsm:607:13]
js::RunScript []
loadRequestedTab [resource:///modules/AsyncTabSwitcher.jsm:520:18]
setTabState [resource:///modules/AsyncTabSwitcher.jsm:263:13]
js::RunScript []
set docShellIsActive [chrome://global/content/elements/browser-custom-element.js:416:24]
set BrowsingContext.isActive []

In the failing run, it's never set back to active (the next time we set a browsing context to active is when the test harness removes the tab and creates a new one, and we select it).

On Mac, where the test consistently passes, the browsing context is set to "active" after restoring the window, when JS code reacts to a sizemodechange event. Here's a profile of this: https://share.firefox.dev/3wlTTur

So:

• the root cause of the test failures on Linux is that the sizemodechange event is not sent when restoring the window. And the browser remains inactive; this likely has user-visible consequences until the next tab switch.
• the reason why the test became intermittent after bug 1596165 is that there's a race with the AsyncTabSwitcher timer.

Comment 29

[Intermittent Failures Robot]

27 failures in 644 pushes (0.042 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: 25
• try: 2

Platform and build breakdown:

• linux1804-64-asan: 10
  • opt: 10
• linux1804-64-tsan: 14
  • opt: 14
• linux1804-64: 3
  • debug: 3

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

Comment 30

[Florian Quèze [:florian]]

Attachment: Bug 1629034 - rewrite browser_minimize.js to avoid the race between waitForCondition and the AsyncTabSwitcher timer, r=jaws.

Comment 31

[Intermittent Failures Robot]

22 failures in 323 pushes (0.068 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 18
• mozilla-central: 4

Platform and build breakdown:

• linux1804-64-tsan: 10
  • opt: 10
• linux1804-64-asan: 8
  • opt: 8
• linux1804-64: 3
  • debug: 3
• linux1804-64-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-04-02&endday=2021-04-02&tree=all

Comment 32

[Narcis Beleuzu [:NarcisB]]

There are 137 failures in the last 7 days.
All failures are on linux1804-64
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=335394289&repo=autoland&lineNumber=2708

Comment 33

[Intermittent Failures Robot]

137 failures in 3125 pushes (0.044 failures/push) were associated with this bug in the last 7 days.

This is the #7 most frequent failure this week.

** 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: 120
• try: 6
• mozilla-central: 11

Platform and build breakdown:

• linux1804-64-tsan: 76
  • opt: 76
• linux1804-64: 17
  • debug: 17
• linux1804-64-asan: 42
  • opt: 42
• linux1804-64-shippable: 1
  • opt: 1
• linux1804-64-ccov: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-03-29&endday=2021-04-04&tree=all

Comment 34

[Intermittent Failures Robot]

16 failures in 463 pushes (0.035 failures/push) were associated with this bug yesterday.

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64-asan: 6
  • opt: 6
• linux1804-64-tsan: 8
  • opt: 8
• linux1804-64: 2
  • debug: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-04-05&endday=2021-04-05&tree=all

Comment 35

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/b7345ede64a4
rewrite browser_minimize.js to avoid the race between waitForCondition and the AsyncTabSwitcher timer, r=jaws.

Comment 36

[Bogdan Tara[:bogdan_tara | bogdant]]

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

Comment 37

[Intermittent Failures Robot]

24 failures in 808 pushes (0.03 failures/push) were associated with this bug yesterday.

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64-asan: 12
  • opt: 12
• linux1804-64: 5
  • debug: 4
  • opt: 1
• linux1804-64-tsan: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1629034&startday=2021-04-06&endday=2021-04-06&tree=all

Comment 38

[Intermittent Failures Robot]

40 failures in 3854 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #34 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: 32
• mozilla-central: 6
• try: 2

Platform and build breakdown:

• linux1804-64-asan: 18
  • opt: 18
• linux1804-64-tsan: 15
  • opt: 15
• linux1804-64: 7
  • debug: 5
  • opt: 2

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