1700793 - Intermittent toolkit/components/printing/tests/browser_modal_print.js | leaked 2 window(s) until shutdown [url = about:blank]

Status: RESOLVED INCOMPLETE

(Toolkit :: Printing, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-03-24T22:36:28.361Z] 22:36:28     INFO - TEST-START | Shutdown
[task 2021-03-24T22:36:28.361Z] 22:36:28     INFO - Browser Chrome Test Summary
[task 2021-03-24T22:36:28.361Z] 22:36:28     INFO - Passed:  1172
[task 2021-03-24T22:36:28.361Z] 22:36:28     INFO - Failed:  0
[task 2021-03-24T22:36:28.362Z] 22:36:28     INFO - Todo:    0
[task 2021-03-24T22:36:28.362Z] 22:36:28     INFO - Mode:    e10s
[task 2021-03-24T22:36:28.362Z] 22:36:28     INFO - *** End BrowserChrome Test Results ***
[task 2021-03-24T22:36:28.400Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-03-24T22:36:28.401Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-03-24T22:36:28.419Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-03-24T22:36:28.441Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-03-24T22:36:28.465Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-03-24T22:36:28.465Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-03-24T22:36:28.546Z] 22:36:28     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1243e0400 == 4 [pid = 1525] [id = 2] [url = chrome://extensions/content/dummy.xhtml]
[task 2021-03-24T22:36:28.547Z] 22:36:28     INFO - GECKO(1525) | [2021-03-24T22:36:28Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2021-03-24T22:36:28.554Z] 22:36:28     INFO - GECKO(1525) | [2021-03-24T22:36:28Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2021-03-24T22:36:28.657Z] 22:36:28     INFO - GECKO(1525) | [Parent 1525, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2021-03-24T22:36:28.663Z] 22:36:28     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:28.663Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:751
[task 2021-03-24T22:36:28.663Z] 22:36:28     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:28.663Z] 22:36:28     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:28.670Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 10b6b9c00 == 3 [pid = 1526] [id = 4] [url = moz-extension://5f1ef310-4a10-034f-811b-71d713cce5b4/_generated_background_page.html]
[task 2021-03-24T22:36:28.670Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 10aff6000 == 2 [pid = 1526] [id = 3] [url = moz-extension://02b6b8d1-da46-6a4b-8c3d-3c34e80ed59f/_generated_background_page.html]
[task 2021-03-24T22:36:28.670Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 10afef400 == 1 [pid = 1526] [id = 0] [url = moz-extension://91e238c8-12d9-ec43-a0bb-4d42a137c684/_generated_background_page.html]
[task 2021-03-24T22:36:28.670Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 10aff3000 == 0 [pid = 1526] [id = 1] [url = moz-extension://2ef9a548-83af-1f4c-a4a4-365636c998fc/_generated_background_page.html]
[task 2021-03-24T22:36:28.676Z] 22:36:28     INFO - GECKO(1525) | [Parent 1525, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2021-03-24T22:36:28.724Z] 22:36:28     INFO - GECKO(1525) | [Parent 1525, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/netwerk/cache/nsApplicationCacheService.cpp:161
[task 2021-03-24T22:36:28.790Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (103e21900) [pid = 1526] [serial = 9] [outer = 0] [url = moz-extension://5f1ef310-4a10-034f-811b-71d713cce5b4/_generated_background_page.html]
[task 2021-03-24T22:36:28.790Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (10b610580) [pid = 1526] [serial = 1] [outer = 0] [url = moz-extension://91e238c8-12d9-ec43-a0bb-4d42a137c684/_generated_background_page.html]
[task 2021-03-24T22:36:28.791Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (103e213c0) [pid = 1526] [serial = 3] [outer = 0] [url = moz-extension://2ef9a548-83af-1f4c-a4a4-365636c998fc/_generated_background_page.html]
[task 2021-03-24T22:36:28.791Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (103e21740) [pid = 1526] [serial = 7] [outer = 0] [url = moz-extension://02b6b8d1-da46-6a4b-8c3d-3c34e80ed59f/_generated_background_page.html]
[task 2021-03-24T22:36:28.791Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (10b6bf400) [pid = 1526] [serial = 11] [outer = 0] [url = moz-extension://91e238c8-12d9-ec43-a0bb-4d42a137c684/_generated_background_page.html]
[task 2021-03-24T22:36:28.791Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (10c37d000) [pid = 1526] [serial = 12] [outer = 0] [url = moz-extension://2ef9a548-83af-1f4c-a4a4-365636c998fc/_generated_background_page.html]
[task 2021-03-24T22:36:28.791Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (10c382400) [pid = 1526] [serial = 14] [outer = 0] [url = moz-extension://02b6b8d1-da46-6a4b-8c3d-3c34e80ed59f/_generated_background_page.html]
[task 2021-03-24T22:36:28.791Z] 22:36:28     INFO - GECKO(1525) | [Child 1526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (10c384800) [pid = 1526] [serial = 15] [outer = 0] [url = moz-extension://5f1ef310-4a10-034f-811b-71d713cce5b4/_generated_background_page.html]
[task 2021-03-24T22:36:28.791Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-03-24T22:36:28.792Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-03-24T22:36:28.800Z] 22:36:28     INFO - GECKO(1525) | [Child 1526, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-03-24T22:36:28.846Z] 22:36:28     INFO - GECKO(1525) | [2021-03-24T22:36:28Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2021-03-24T22:36:28.847Z] 22:36:28     INFO - GECKO(1525) | [2021-03-24T22:36:28Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2021-03-24T22:36:29.225Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 124db0c00 == 3 [pid = 1525] [id = 6] [url = chrome://mochikit/content/browser-harness.xhtml]
[task 2021-03-24T22:36:29.225Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1252d0800 == 2 [pid = 1525] [id = 3] [url = about:blank]
[task 2021-03-24T22:36:29.226Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 122e6d800 == 1 [pid = 1525] [id = 0] [url = chrome://browser/content/hiddenWindowMac.xhtml]
[task 2021-03-24T22:36:29.226Z] 22:36:29     INFO - GECKO(1525) | [Child 1527, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:751
[task 2021-03-24T22:36:29.226Z] 22:36:29     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:29.226Z] 22:36:29     INFO - GECKO(1525) | [Child 1529, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:751
[task 2021-03-24T22:36:29.233Z] 22:36:29     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:29.234Z] 22:36:29     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:29.234Z] 22:36:29     INFO - GECKO(1525) | [Child 1527, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-03-24T22:36:29.234Z] 22:36:29     INFO - GECKO(1525) | [Child 1527, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-03-24T22:36:29.234Z] 22:36:29     INFO - GECKO(1525) | [Child 1529, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-03-24T22:36:29.234Z] 22:36:29     INFO - GECKO(1525) | [Child 1529, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-03-24T22:36:29.255Z] 22:36:29     INFO - GECKO(1525) | [Child 1527, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-03-24T22:36:29.256Z] 22:36:29     INFO - GECKO(1525) | [Child 1529, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-03-24T22:36:29.256Z] 22:36:29     INFO - GECKO(1525) | [Child 1531, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:751
[task 2021-03-24T22:36:29.269Z] 22:36:29     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:29.273Z] 22:36:29     INFO - GECKO(1525) | [Child 1530, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:751
[task 2021-03-24T22:36:29.275Z] 22:36:29     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:29.291Z] 22:36:29     INFO - GECKO(1525) | [Child 1531, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-03-24T22:36:29.291Z] 22:36:29     INFO - GECKO(1525) | [Child 1531, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-03-24T22:36:29.339Z] 22:36:29     INFO - GECKO(1525) | [Child 1531, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-03-24T22:36:29.339Z] 22:36:29     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:29.339Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.000002s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.339Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.000433s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.339Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.000454s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.339Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.001488s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.339Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.001508s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.339Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: cache: '0.001536s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.340Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: sdb: '0.001563s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.340Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: ls: '0.001581s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.340Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.001640s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.340Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.001648s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.340Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.001674s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.340Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.001688s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.341Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.001693s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.341Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: idb: '0.001702s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.341Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: cache: '0.001748s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.341Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: sdb: '0.001757s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.341Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, IPDL Background] WARNING: ls: '0.001769s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3684
[task 2021-03-24T22:36:29.341Z] 22:36:29     INFO - GECKO(1525) | [Child 1530: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1157cdc00 == 0 [pid = 1530] [id = 161] [url = about:blank]
[task 2021-03-24T22:36:29.359Z] 22:36:29     INFO - GECKO(1525) | [Child 1530: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (100bc4200) [pid = 1530] [serial = 425] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:29.359Z] 22:36:29     INFO - GECKO(1525) | [Child 1530: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (115bcd000) [pid = 1530] [serial = 426] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:29.359Z] 22:36:29     INFO - GECKO(1525) | [Child 1530, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-03-24T22:36:29.359Z] 22:36:29     INFO - GECKO(1525) | [Child 1530, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-03-24T22:36:29.360Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 118910000 == 0 [pid = 1525] [id = 1] [url = chrome://browser/content/browser.xhtml]
[task 2021-03-24T22:36:29.437Z] 22:36:29     INFO - GECKO(1525) | [Child 1530, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-03-24T22:36:29.521Z] 22:36:29     INFO - GECKO(1525) | 1616625389505	Marionette	TRACE	Received observer notification xpcom-will-shutdown
[task 2021-03-24T22:36:29.522Z] 22:36:29     INFO - GECKO(1525) | 1616625389506	Marionette	INFO	Stopped listening on port 2828
[task 2021-03-24T22:36:29.522Z] 22:36:29     INFO - GECKO(1525) | 1616625389506	Marionette	DEBUG	Marionette stopped listening
[task 2021-03-24T22:36:29.600Z] 22:36:29     INFO - GECKO(1525) | [Parent 1525, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:751
[task 2021-03-24T22:36:29.600Z] 22:36:29     INFO - GECKO(1525) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-24T22:36:30.596Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (1261c5040) [pid = 1525] [serial = 59] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:30.596Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (133071c00) [pid = 1525] [serial = 62] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:30.597Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (123132800) [pid = 1525] [serial = 4] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:30.597Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (11ff96c80) [pid = 1525] [serial = 3] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2021-03-24T22:36:30.597Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (123b44200) [pid = 1525] [serial = 13] [outer = 0] [url = chrome://mochikit/content/browser-harness.xhtml]
[task 2021-03-24T22:36:30.597Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (126d42000) [pid = 1525] [serial = 14] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:30.597Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (123b443c0) [pid = 1525] [serial = 5] [outer = 0] [url = chrome://extensions/content/dummy.xhtml]
[task 2021-03-24T22:36:30.598Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (124dac400) [pid = 1525] [serial = 6] [outer = 0] [url = chrome://extensions/content/dummy.xhtml]
[task 2021-03-24T22:36:30.598Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (11ff96740) [pid = 1525] [serial = 1] [outer = 0] [url = chrome://browser/content/hiddenWindowMac.xhtml]
[task 2021-03-24T22:36:30.598Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (123138800) [pid = 1525] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:30.598Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (109c81580) [pid = 1525] [serial = 7] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:30.598Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (11827bc00) [pid = 1525] [serial = 658] [outer = 0] [url = about:blank]
[task 2021-03-24T22:36:30.614Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-03-24T22:36:30.617Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-03-24T22:36:30.686Z] 22:36:30     INFO - GECKO(1525) | [Parent 1525, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-03-24T22:36:30.757Z] 22:36:30     INFO - TEST-INFO | Main app process: exit 0
[task 2021-03-24T22:36:30.758Z] 22:36:30     INFO - TEST-INFO | Confirming we saw 610 DOCSHELL created and 610 destroyed log strings.
[task 2021-03-24T22:36:30.758Z] 22:36:30     INFO - TEST-INFO | Confirming we saw 1521 DOMWINDOW created and 1521 destroyed log strings.
[task 2021-03-24T22:36:30.758Z] 22:36:30    ERROR - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_modal_print.js | leaked 2 window(s) until shutdown [url = about:blank]
[task 2021-03-24T22:36:30.760Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_modal_print.js | windows(s) leaked: [pid = 1525] [serial = 62], [pid = 1525] [serial = 59]
[task 2021-03-24T22:36:30.760Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_modal_print.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.760Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_modal_print.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.761Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_cancel_close_print.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.761Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_cancel_close_print.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.761Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_destination_change.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.761Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_destination_change.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.762Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_empty_paper_sizes.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.762Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_empty_paper_sizes.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.762Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_modal_resize.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.762Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_modal_resize.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.762Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_pdf_hidden_settings.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.764Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_pdf_hidden_settings.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.765Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_pdf_printer_settings.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.765Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_pdf_printer_settings.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.765Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_preview_in_container.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.765Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_preview_in_container.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.766Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_preview_more_settings.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.766Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_preview_more_settings.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.766Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_preview_navigation.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.766Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_preview_navigation.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.767Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_preview_print_coop.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.767Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_preview_print_coop.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.767Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_bcg_id_overflow.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.769Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_bcg_id_overflow.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.770Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_context_menu.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.770Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_context_menu.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.770Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_copies.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.770Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_copies.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.771Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_duplex.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.771Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_duplex.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.771Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_in_container.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.771Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_in_container.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.771Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_margins.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.772Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_margins.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.772Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_page_range.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.772Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_page_range.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.774Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_paper_sizes.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.774Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_paper_sizes.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.775Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_pdf_on_frame_load.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.775Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_pdf_on_frame_load.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.775Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_scaling.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.775Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_scaling.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.777Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_selection.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.777Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_print_selection.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.777Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_sheet_count.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.778Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_sheet_count.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.778Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_system_dialog_subdialog_hidden.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.778Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_system_dialog_subdialog_hidden.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.779Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_ui_labels.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.779Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_ui_labels.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.779Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_window_print.js | This test created 0 hidden window(s)
[task 2021-03-24T22:36:30.786Z] 22:36:30     INFO - TEST-INFO | toolkit/components/printing/tests/browser_window_print.js | This test created 1 hidden docshell(s)
[task 2021-03-24T22:36:30.786Z] 22:36:30     INFO - runtests.py | Application ran for: 0:02:28.713829
[task 2021-03-24T22:36:30.786Z] 22:36:30     INFO - zombiecheck | Reading PID log: /var/folders/33/v869c2tx0dld49mgc8r6592h000017/T/tmpOKOCBupidlog
[task 2021-03-24T22:36:30.787Z] 22:36:30     INFO - ==> process 1525 launched child process 1526
[task 2021-03-24T22:36:30.787Z] 22:36:30     INFO - ==> process 1525 launched child process 1527
[task 2021-03-24T22:36:30.787Z] 22:36:30     INFO - ==> process 1525 launched child process 1528
[task 2021-03-24T22:36:30.787Z] 22:36:30     INFO - ==> process 1525 launched child process 1529
[task 2021-03-24T22:36:30.787Z] 22:36:30     INFO - ==> process 1525 launched child process 1530
[task 2021-03-24T22:36:30.787Z] 22:36:30     INFO - ==> process 1525 launched child process 1531
[task 2021-03-24T22:36:30.787Z] 22:36:30     INFO - ==> process 1525 launched child process 1536
[task 2021-03-24T22:36:30.788Z] 22:36:30     INFO - zombiecheck | Checking for orphan process with PID: 1536
[task 2021-03-24T22:36:30.788Z] 22:36:30     INFO - zombiecheck | Checking for orphan process with PID: 1526
[task 2021-03-24T22:36:30.788Z] 22:36:30     INFO - zombiecheck | Checking for orphan process with PID: 1527
[task 2021-03-24T22:36:30.788Z] 22:36:30     INFO - zombiecheck | Checking for orphan process with PID: 1528
[task 2021-03-24T22:36:30.788Z] 22:36:30     INFO - zombiecheck | Checking for orphan process with PID: 1529
[task 2021-03-24T22:36:30.788Z] 22:36:30     INFO - zombiecheck | Checking for orphan process with PID: 1530
[task 2021-03-24T22:36:30.788Z] 22:36:30     INFO - zombiecheck | Checking for orphan process with PID: 1531
[task 2021-03-24T22:36:30.789Z] 22:36:30     INFO - Stopping web server
[task 2021-03-24T22:36:30.789Z] 22:36:30     INFO - Server shut down.
[task 2021-03-24T22:36:30.957Z] 22:36:30     INFO - Web server killed.
[task 2021-03-24T22:36:30.957Z] 22:36:30     INFO - Stopping web socket server
[task 2021-03-24T22:36:31.108Z] 22:36:31     INFO - Stopping ssltunnel
[task 2021-03-24T22:36:31.199Z] 22:36:31     INFO - leakcheck | Processing log file /var/folders/33/v869c2tx0dld49mgc8r6592h000017/T/tmpJsF3nA.mozrunner/runtests_leaks.log
[task 2021-03-24T22:36:31.200Z] 22:36:31     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2021-03-24T22:36:31.200Z] 22:36:31     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2021-03-24T22:36:31.200Z] 22:36:31     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2021-03-24T22:36:31.200Z] 22:36:31     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2021-03-24T22:36:31.201Z] 22:36:31     INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2021-03-24T22:36:31.201Z] 22:36:31     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2021-03-24T22:36:31.208Z] 22:36:31     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2021-03-24T22:36:31.208Z] 22:36:31     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 0 bytes
[task 2021-03-24T22:36:31.209Z] 22:36:31     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2021-03-24T22:36:31.209Z] 22:36:31     INFO - leakcheck | Processing leak log file /var/folders/33/v869c2tx0dld49mgc8r6592h000017/T/tmpJsF3nA.mozrunner/runtests_leaks_tab_pid1529.log
[task 2021-03-24T22:36:31.209Z] 22:36:31     INFO - 
[task 2021-03-24T22:36:31.209Z] 22:36:31     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1529
[task 2021-03-24T22:36:31.209Z] 22:36:31     INFO - 
[task 2021-03-24T22:36:31.209Z] 22:36:31     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-03-24T22:36:31.210Z] 22:36:31     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-03-24T22:36:31.210Z] 22:36:31     INFO -    0 |TOTAL                                 |       34        0|  819557        0|
[task 2021-03-24T22:36:31.210Z] 22:36:31     INFO - 
[task 2021-03-24T22:36:31.210Z] 22:36:31     INFO - nsTraceRefcnt::DumpStatistics: 990 entries
[task 2021-03-24T22:36:31.210Z] 22:36:31     INFO - TEST-PASS | leakcheck | tab no leaks detected!```

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 11
• mozilla-central: 2

Platform and build breakdown:

• macosx1014-64-qr: 3
  • debug: 3
• windows10-64: 4
  • debug: 4
• windows10-64-qr: 4
  • debug: 4
• linux1804-64: 1
  • debug: 1
• windows7-32: 1
  • debug: 1

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform and build breakdown:

• windows10-64: 6
  • debug: 6
• windows7-32: 6
  • debug: 6
• linux1804-64: 1
  • debug: 1
• windows10-64-qr: 8
  • debug: 8
• macosx1014-64-qr: 3
  • debug: 3

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform and build breakdown:

• windows10-64-qr: 6
  • debug: 6
• macosx1014-64-qr: 4
  • debug: 4
• windows7-32: 4
  • debug: 4
• windows10-64: 2
  • debug: 2
• linux1804-64: 3
  • debug: 3

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

Comment 4

[Intermittent Failures Robot]

11 failures in 4255 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64: 1
  • debug: 1
• windows10-64-qr: 6
  • debug: 6
• windows7-32: 2
  • debug: 2
• windows10-64: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1700793&startday=2021-04-12&endday=2021-04-18&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.