Closed Bug 1728482 Opened 3 years ago Closed 2 years ago

Intermittent toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js|A promise chain failed to handle a rejection: tabbrowser.ownerGlobal.windowGlobalChild is null - stack: openNewForegroundTab/<@resource://testing-common/BrowserTestUtils.jsm:276:3

Categories

(Toolkit :: Add-ons Manager, defect)

defect

Tracking

()

RESOLVED FIXED
105 Branch
Tracking Status
firefox105 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: rpl)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Attachments

(1 file)

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


task 2021-08-31T22:34:10.324Z] 22:34:10     INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js
[task 2021-08-31T22:34:10.328Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.330Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1d8acbbbc00 == 6 [pid = 7972] [id = 18]
[task 2021-08-31T22:34:10.330Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (1d8a05d1900) [pid = 7972] [serial = 42] [outer = 0]
[task 2021-08-31T22:34:10.331Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (1d8ad072000) [pid = 7972] [serial = 43] [outer = 1d8a05d1900]
[task 2021-08-31T22:34:10.339Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972, Main Thread] WARNING: NS_ENSURE_TRUE(uri) failed: file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1332
[task 2021-08-31T22:34:10.341Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.341Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.350Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.354Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4233
[task 2021-08-31T22:34:10.362Z] 22:34:10     INFO - GECKO(8128) | [Child 6152: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1c8639d1800 == 0 [pid = 6152] [id = 0] [url = file:///Z:/task_163044738544008/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%7D]
[task 2021-08-31T22:34:10.372Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.377Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.380Z] 22:34:10     INFO - GECKO(8128) | [Child 6152: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1c8639d6ac0) [pid = 6152] [serial = 1] [outer = 0] [url = file:///Z:/task_163044738544008/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%7D]
[task 2021-08-31T22:34:10.381Z] 22:34:10     INFO - GECKO(8128) | [Child 6152: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (1c86409c800) [pid = 6152] [serial = 3] [outer = 0] [url = file:///Z:/task_163044738544008/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%7D]
[task 2021-08-31T22:34:10.381Z] 22:34:10     INFO - GECKO(8128) | [Child 6152: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (1c8639d2800) [pid = 6152] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:10.382Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-31T22:34:10.383Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.385Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.385Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.386Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:34:10.390Z] 22:34:10     INFO - GECKO(8128) | [Child 6152, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-31T22:34:10.488Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1d8b8cb6400 == 7 [pid = 7972] [id = 19]
[task 2021-08-31T22:34:10.490Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (1d8b198a040) [pid = 7972] [serial = 44] [outer = 0]
[task 2021-08-31T22:34:10.525Z] 22:34:10     INFO - GECKO(8128) | Warning: asking to enable_gpu_markers but no supporting extension was found
[task 2021-08-31T22:34:10.530Z] 22:34:10     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 250fa1a5800 == 7 [pid = 3676] [id = 24]
[task 2021-08-31T22:34:10.531Z] 22:34:10     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 23 (250fab74200) [pid = 3676] [serial = 55] [outer = 0]
[task 2021-08-31T22:34:10.531Z] 22:34:10     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 24 (250fab4b000) [pid = 3676] [serial = 56] [outer = 250fab74200]
[task 2021-08-31T22:34:10.559Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4231
[task 2021-08-31T22:34:10.567Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (1d8aebcc800) [pid = 7972] [serial = 45] [outer = 1d8b198a040]
[task 2021-08-31T22:34:10.689Z] 22:34:10     INFO - GECKO(8128) | [Parent 7972, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1582
[task 2021-08-31T22:34:10.772Z] 22:34:10     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 25 (250fab54400) [pid = 3676] [serial = 57] [outer = 250fab74200]
[task 2021-08-31T22:34:10.810Z] 22:34:10     INFO - GECKO(8128) | [Child 1636, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-08-31T22:34:10.818Z] 22:34:10     INFO - GECKO(8128) | [Child 3676, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-08-31T22:34:10.825Z] 22:34:10     INFO - GECKO(8128) | [Child 2072: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 16517c53400 == 10 [pid = 2072] [id = 39]
[task 2021-08-31T22:34:10.825Z] 22:34:10     INFO - GECKO(8128) | [Child 2072: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 47 (16517c8aac0) [pid = 2072] [serial = 117] [outer = 0]
[task 2021-08-31T22:34:10.826Z] 22:34:10     INFO - GECKO(8128) | [Child 2072: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 48 (16517c56000) [pid = 2072] [serial = 118] [outer = 16517c8aac0]
[task 2021-08-31T22:34:10.870Z] 22:34:10     INFO - GECKO(8128) | [Child 6004: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1dab9fc7000 == 2 [pid = 6004] [id = 1]
[task 2021-08-31T22:34:10.880Z] 22:34:10     INFO - GECKO(8128) | [Child 6004: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (1daba777040) [pid = 6004] [serial = 4] [outer = 0]
[task 2021-08-31T22:34:10.881Z] 22:34:10     INFO - GECKO(8128) | [Child 6004: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (1dab9fc8800) [pid = 6004] [serial = 5] [outer = 1daba777040]
[task 2021-08-31T22:34:10.894Z] 22:34:10     INFO - GECKO(8128) | [Child 2072: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 49 (16517c5c800) [pid = 2072] [serial = 119] [outer = 16517c8aac0]
[task 2021-08-31T22:34:10.957Z] 22:34:10     INFO - GECKO(8128) | Warning: asking to enable_gpu_markers but no supporting extension was found
[task 2021-08-31T22:34:10.973Z] 22:34:10     INFO - GECKO(8128) | Sending InstallTriggered
[task 2021-08-31T22:34:10.986Z] 22:34:10     INFO - GECKO(8128) | [Child 6004: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (1dabd1dac00) [pid = 6004] [serial = 6] [outer = 1daba777040]
[task 2021-08-31T22:34:11.073Z] 22:34:11     INFO - GECKO(8128) | Sending InstallComplete
[task 2021-08-31T22:34:11.098Z] 22:34:11     INFO - GECKO(8128) | Warning: asking to enable_gpu_markers but no supporting extension was found
[task 2021-08-31T22:34:11.102Z] 22:34:11     INFO - GECKO(8128) | [Parent 7972, StreamTrans #2] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/modules/libjar/nsJARChannel.cpp:365
[task 2021-08-31T22:34:11.138Z] 22:34:11     INFO - GECKO(8128) | [Child 1636, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-08-31T22:34:11.138Z] 22:34:11     INFO - GECKO(8128) | [Child 2072, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-08-31T22:34:11.139Z] 22:34:11     INFO - GECKO(8128) | [Child 3676, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-08-31T22:34:11.199Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 24 (250fa1a7c00) [pid = 3676] [serial = 37] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.203Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 23 (250f87c3000) [pid = 3676] [serial = 30] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.204Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 22 (250f21bac00) [pid = 3676] [serial = 28] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.204Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (250fa1a6800) [pid = 3676] [serial = 34] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.205Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 250fa1a4800 == 6 [pid = 3676] [id = 23] [url = about:blank]
[task 2021-08-31T22:34:11.206Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 250f87c2800 == 5 [pid = 3676] [id = 20] [url = about:blank]
[task 2021-08-31T22:34:11.206Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 250f87c6400 == 4 [pid = 3676] [id = 22] [url = about:blank]
[task 2021-08-31T22:34:11.207Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (250fab52c00) [pid = 3676] [serial = 39] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.208Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (250fab58c00) [pid = 3676] [serial = 40] [outer = 0] [url = http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/enabled.html]
[task 2021-08-31T22:34:11.208Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (250fab4dc00) [pid = 3676] [serial = 26] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.209Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (250fab52800) [pid = 3676] [serial = 35] [outer = 0] [url = data:text/html,<script>window.location.href='http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi'</script>]
[task 2021-08-31T22:34:11.210Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (250fa1a3c00) [pid = 3676] [serial = 32] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.210Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (250fab55400) [pid = 3676] [serial = 42] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.211Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 250f87c6000 == 3 [pid = 3676] [id = 21] [url = about:blank]
[task 2021-08-31T22:34:11.211Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 250f2121c00 == 2 [pid = 3676] [id = 18] [url = about:blank]
[task 2021-08-31T22:34:11.212Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 250f21b2800 == 1 [pid = 3676] [id = 19] [url = about:blank]
[task 2021-08-31T22:34:11.221Z] 22:34:11     INFO - GECKO(8128) | [GPU 6352, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2021-08-31T22:34:11.250Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (250f871e900) [pid = 3676] [serial = 43] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.252Z] 22:34:11     INFO - GECKO(8128) | JavaScript error: resource://testing-common/BrowserTestUtils.jsm, line 276: TypeError: tabbrowser.ownerGlobal.windowGlobalChild is null
[task 2021-08-31T22:34:11.253Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (250fab34580) [pid = 3676] [serial = 49] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.253Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (250fab34c80) [pid = 3676] [serial = 51] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.254Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (250fab39740) [pid = 3676] [serial = 53] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.255Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (250fa182c80) [pid = 3676] [serial = 47] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.256Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (250fa1823c0) [pid = 3676] [serial = 45] [outer = 0] [url = about:blank]
[task 2021-08-31T22:34:11.260Z] 22:34:11     INFO - TEST-INFO | started process screenshot
[task 2021-08-31T22:34:11.358Z] 22:34:11     INFO - TEST-INFO | screenshot: exit 0
[task 2021-08-31T22:34:11.367Z] 22:34:11     INFO - Buffered messages logged at 22:34:10
[task 2021-08-31T22:34:11.367Z] 22:34:11     INFO - Waiting for InstallComplete
[task 2021-08-31T22:34:11.368Z] 22:34:11     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | Should have seen the expected number of installs started - 
[task 2021-08-31T22:34:11.370Z] 22:34:11     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%7B%22URL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%2C%22IconURL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Ficon.png%22%7D%7D" line: 0}]
[task 2021-08-31T22:34:11.370Z] 22:34:11     INFO - Buffered messages logged at 22:34:11
[task 2021-08-31T22:34:11.371Z] 22:34:11     INFO - Console message: 1630449250990	addons.xpi	DEBUG	Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file C:\Users\task_163044738544008\AppData\Local\Temp\tmp-od6.xpi
[task 2021-08-31T22:34:11.371Z] 22:34:11     INFO - Console message: 1630449250990	addons.xpi	DEBUG	Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
[task 2021-08-31T22:34:11.372Z] 22:34:11     INFO - Console message: 1630449251015	addons.repository	DEBUG	cacheAddons: enabled false IDs ["amosigned-xpi@tests.mozilla.org"]
[task 2021-08-31T22:34:11.373Z] 22:34:11     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | Should have seen the name - 
[task 2021-08-31T22:34:11.373Z] 22:34:11     INFO - Console message: 1630449251073	addons.xpi	DEBUG	Starting install of amosigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
[task 2021-08-31T22:34:11.374Z] 22:34:11     INFO - Console message: 1630449251078	addons.xpi	DEBUG	Addon amosigned-xpi@tests.mozilla.org will be installed as a packed xpi
[task 2021-08-31T22:34:11.374Z] 22:34:11     INFO - Console message: 1630449251082	addons.xpi-utils	DEBUG	Make addon app-profile:amosigned-xpi@tests.mozilla.org visible
[task 2021-08-31T22:34:11.376Z] 22:34:11     INFO - Console message: 1630449251082	addons.xpi	DEBUG	XPIStates adding add-on amosigned-xpi@tests.mozilla.org in {"addons":{"special-powers@mozilla.org":{"dependencies":[],"enabled":true,"lastModifiedTime":1630449231448,"loader":null,"path":"special-powers@mozilla.org.xpi","rootURI":"jar:file:///C:/Users/task_163044738544008/AppData/Local/Temp/tmpw9g8z4wj.mozrunner/extensions/special-powers@mozilla.org.xpi!/","runInSafeMode":false,"signedState":0,"signedDate":null,"telemetryKey":"special-powers%40mozilla.org:2018.06.27","version":"2018.06.27"},"mochikit@mozilla.org":{"dependencies":[],"enabled":true,"lastModifiedTime":1630449231517,"loader":null,"path":"mochikit@mozilla.org.xpi","rootURI":"jar:file:///C:/Users/task_163044738544008/AppData/Local/Temp/tmpw9g8z4wj.mozrunner/extensions/mochikit@mozilla.org.xpi!/","runInSafeMode":false,"signedState":0,"signedDate":null,"telemetryKey":"mochikit%40mozilla.org:2.0","version":"2.0"}},"staged":{},"path":"C:\\Users\\task_163044738544008\\AppData\\Local\\Temp\\tmpw9g8z4wj.mozrunner\\extensions"}: C:\Users\task_163044738544008\AppData\Local\Temp\tmpw9g8z4wj.mozrunner\extensions\amosigned-xpi@tests.mozilla.org.xpi
[task 2021-08-31T22:34:11.380Z] 22:34:11     INFO - Console message: 1630449251083	addons.xpi	DEBUG	Updating XPIState for {"id":"amosigned-xpi@tests.mozilla.org","syncGUID":"{cdf59ffc-3dba-4a57-ad57-5a8c379c2c58}","version":"2.1","type":"extension","loader":null,"updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"XPI Test","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"embedderDisabled":false,"installDate":1630449251016,"updateDate":1630449251016,"applyBackgroundUpdates":1,"path":"C:\\Users\\task_163044738544008\\AppData\\Local\\Temp\\tmpw9g8z4wj.mozrunner\\extensions\\amosigned-xpi@tests.mozilla.org.xpi","skinnable":false,"sourceURI":"http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi","releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"toolkit@mozilla.org","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"signedState":2,"signedDate":1521323594000,"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"optionalPermissions":{"permissions":[],"origins":[]},"icons":{},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":false,"installTelemetryInfo":{"source":"test-host","sourceURL":"http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%7B%22URL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%2C%22IconURL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Ficon.png%22%7D%7D","method":"installTrigger"},"recommendationState":null,"rootURI":"jar:file:///C:/Users/task_163044738544008/AppData/Local/Temp/tmpw9g8z4wj.mozrunner/extensions/amosigned-xpi@tests.mozilla.org.xpi!/","location":"app-profile"}
[task 2021-08-31T22:34:11.381Z] 22:34:11     INFO - Console message: 1630449251083	addons.xpi	DEBUG	Install of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
[task 2021-08-31T22:34:11.381Z] 22:34:11     INFO - Saw InstallComplete,true
[task 2021-08-31T22:34:11.382Z] 22:34:11     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | Should be no running installs left - 
[task 2021-08-31T22:34:11.383Z] 22:34:11     INFO - Console message: 1630449251085	addons.xpi	DEBUG	Calling bootstrap method shutdown on amosigned-xpi@tests.mozilla.org version 2.1
[task 2021-08-31T22:34:11.383Z] 22:34:11     INFO - Console message: 1630449251087	addons.xpi	DEBUG	Calling bootstrap method install on amosigned-xpi@tests.mozilla.org version 2.1
[task 2021-08-31T22:34:11.384Z] 22:34:11     INFO - Console message: 1630449251101	addons.xpi	DEBUG	Calling bootstrap method startup on amosigned-xpi@tests.mozilla.org version 2.1
[task 2021-08-31T22:34:11.384Z] 22:34:11     INFO - Console message: 1630449251102	addons.xpi	DEBUG	Calling bootstrap method uninstall on amosigned-xpi@tests.mozilla.org version 2.1
[task 2021-08-31T22:34:11.385Z] 22:34:11     INFO - Console message: 1630449251104	addons.xpi	DEBUG	Disabling XPIState for amosigned-xpi@tests.mozilla.org
[task 2021-08-31T22:34:11.386Z] 22:34:11     INFO - Console message: 1630449251104	addons.xpi	DEBUG	uninstallAddon: flushing jar cache C:\Users\task_163044738544008\AppData\Local\Temp\tmpw9g8z4wj.mozrunner\extensions\amosigned-xpi@tests.mozilla.org.xpi for addon amosigned-xpi@tests.mozilla.org
[task 2021-08-31T22:34:11.387Z] 22:34:11     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | 1 Add-on should have been successfully installed - 
[task 2021-08-31T22:34:11.388Z] 22:34:11     INFO - Console message: [JavaScript Error: "Error while loading 'jar:file:///C:/Users/task_163044738544008/AppData/Local/Temp/tmpw9g8z4wj.mozrunner/extensions/amosigned-xpi@tests.mozilla.org.xpi!/manifest.json' (NS_ERROR_FILE_NOT_FOUND)" {file: "resource://gre/modules/Extension.jsm" line: 655}]
[task 2021-08-31T22:34:11.388Z] 22:34:11     INFO - readJSON/</<@resource://gre/modules/Extension.jsm:655:20
[task 2021-08-31T22:34:11.388Z] 22:34:11     INFO - onStopRequest@resource://gre/modules/NetUtil.jsm:128:18
[task 2021-08-31T22:34:11.388Z] 22:34:11     INFO - 
[task 2021-08-31T22:34:11.389Z] 22:34:11     INFO - Console message: 1630449251131	addons.xpi	WARN	Exception running bootstrap method startup on amosigned-xpi@tests.mozilla.org: Error: Error while loading 'jar:file:///C:/Users/task_163044738544008/AppData/Local/Temp/tmpw9g8z4wj.mozrunner/extensions/amosigned-xpi@tests.mozilla.org.xpi!/manifest.json' (NS_ERROR_FILE_NOT_FOUND)(resource://gre/modules/Extension.jsm:655:20) JS Stack trace: readJSON/</<@Extension.jsm:655:20
[task 2021-08-31T22:34:11.389Z] 22:34:11     INFO - onStopRequest@NetUtil.jsm:128:18
[task 2021-08-31T22:34:11.390Z] 22:34:11     INFO - Console message: 1630449251132	addons.xpi	DEBUG	removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file C:\Users\task_163044738544008\AppData\Local\Temp\tmp-od6.xpi
[task 2021-08-31T22:34:11.391Z] 22:34:11     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | installTrigger should have claimed success - 
[task 2021-08-31T22:34:11.391Z] 22:34:11     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | Callback should have seen a success - 
[task 2021-08-31T22:34:11.392Z] 22:34:11     INFO - Console message: [JavaScript Error: "TypeError: tabbrowser.ownerGlobal.windowGlobalChild is null" {file: "resource://testing-common/BrowserTestUtils.jsm" line: 276}]
[task 2021-08-31T22:34:11.393Z] 22:34:11     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | Should be no active installs at the end of the test - 
[task 2021-08-31T22:34:11.393Z] 22:34:11     INFO - Buffered messages finished
[task 2021-08-31T22:34:11.394Z] 22:34:11     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | A promise chain failed to handle a rejection: tabbrowser.ownerGlobal.windowGlobalChild is null - stack: openNewForegroundTab/<@resource://testing-common/BrowserTestUtils.jsm:276:31
[task 2021-08-31T22:34:11.394Z] 22:34:11     INFO - Rejection date: Tue Aug 31 2021 22:34:11 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 285
[task 2021-08-31T22:34:11.394Z] 22:34:11     INFO - Stack trace:
[task 2021-08-31T22:34:11.395Z] 22:34:11     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:285
[task 2021-08-31T22:34:11.395Z] 22:34:11     INFO - chrome://mochikit/content/browser-test.js:nextTest:625
[task 2021-08-31T22:34:11.395Z] 22:34:11     INFO - GECKO(8128) | MEMORY STAT | vsize 2104314MB | vsizeMaxContiguous 65482095MB | residentFast 314MB | heapAllocated 117MB
[task 2021-08-31T22:34:11.396Z] 22:34:11     INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | took 950ms
[task 2021-08-31T22:34:11.396Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 250f2122c00 == 2 [pid = 3676] [id = 25]
[task 2021-08-31T22:34:11.397Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (250f871e900) [pid = 3676] [serial = 58] [outer = 0]
[task 2021-08-31T22:34:11.397Z] 22:34:11     INFO - GECKO(8128) | [Child 3676: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (250f21b7000) [pid = 3676] [serial = 59] [outer = 250f871e900]
[task 2021-08-31T22:34:11.398Z] 22:34:11     INFO - checking window state
[task 2021-08-31T22:34:11.398Z] 22:34:11     INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_offline.js

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

Update:
There have been 39 failures within the last 7 days:
• 1 failures on Windows 10 x86 2004 WebRender debug
• 38 failures on Windows 10 x64 2004 WebRender debug
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=382217083&repo=autoland&lineNumber=81715

[task 2022-06-23T00:56:22.530Z] 00:56:22     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | Should be no active installs at the end of the test - 
[task 2022-06-23T00:56:22.530Z] 00:56:22     INFO - Buffered messages finished
[task 2022-06-23T00:56:22.531Z] 00:56:22     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | A promise chain failed to handle a rejection: tabbrowser.ownerGlobal.windowGlobalChild is null - stack: openNewForegroundTab/<@resource://testing-common/BrowserTestUtils.jsm:278:31
[task 2022-06-23T00:56:22.531Z] 00:56:22     INFO - Rejection date: Thu Jun 23 2022 00:56:22 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 271
[task 2022-06-23T00:56:22.532Z] 00:56:22     INFO - Stack trace:
[task 2022-06-23T00:56:22.532Z] 00:56:22     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:271
[task 2022-06-23T00:56:22.532Z] 00:56:22     INFO - chrome://mochikit/content/browser-test.js:nextTest:625
[task 2022-06-23T00:56:22.532Z] 00:56:22     INFO - GECKO(8224) | [Parent 5732, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3103
[task 2022-06-23T00:56:22.533Z] 00:56:22     INFO - GECKO(8224) | MEMORY STAT | vsize 2112491MB | vsizeMaxContiguous 65648030MB | residentFast 321MB | heapAllocated 123MB
[task 2022-06-23T00:56:22.533Z] 00:56:22     INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_newwindow.js | took 917ms
[task 2022-06-23T00:56:22.534Z] 00:56:22     INFO - GECKO(8224) | [Child 8316: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 2233c06e800 == 4 [pid = 8316] [id = 22]
[task 2022-06-23T00:56:22.534Z] 00:56:22     INFO - GECKO(8224) | [Child 8316: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (2233706bf10) [pid = 8316] [serial = 49] [outer = 0]
[task 2022-06-23T00:56:22.535Z] 00:56:22     INFO - GECKO(8224) | [Child 8316: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (2233c06ec00) [pid = 8316] [serial = 50] [outer = 2233706bf10]
[task 2022-06-23T00:56:22.535Z] 00:56:22     INFO - GECKO(8224) | [Child 6256, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-06-23T00:56:22.536Z] 00:56:22     INFO - GECKO(8224) | [Child 8348: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 292ac26a400 == 0 [pid = 8348] [id = 0] [url = http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%7B%22URL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%2C%22IconURL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Ficon.png%22%7D%7D]
[task 2022-06-23T00:56:22.537Z] 00:56:22     INFO - checking window state
[task 2022-06-23T00:56:22.538Z] 00:56:22     INFO - GECKO(8224) | [Child 8348: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (292a716d260) [pid = 8348] [serial = 1] [outer = 0] [url = http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%7B%22URL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%2C%22IconURL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Ficon.png%22%7D%7D]
[task 2022-06-23T00:56:22.539Z] 00:56:22     INFO - GECKO(8224) | [Child 8348: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (292ac26a800) [pid = 8348] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-06-23T00:56:22.540Z] 00:56:22     INFO - GECKO(8224) | [Child 8348: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (292ac26d800) [pid = 8348] [serial = 3] [outer = 0] [url = http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%7B%22URL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%2C%22IconURL%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Ficon.png%22%7D%7D]
[task 2022-06-23T00:56:22.540Z] 00:56:22     INFO - GECKO(8224) | [Child 8348, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-06-23T00:56:22.541Z] 00:56:22     INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_offline.js

Hi Shane! Can you please take a look at this? Maybe you could help us assign it to someone.
Thank you!

Flags: needinfo?(mixedpuppy)

There have been 33 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • windows10-64-2004-qr
  • windows10-32-2004-qr
Flags: needinfo?(mixedpuppy)
See Also: → 1541577

Unfortunately I was not able to trigger locally the same unhandled rejection (tabbrowser.ownerGlobal.windowGlobalChild is null
triggered by BTU.openNewForegroundTab from BrowserTestUtils.jsm line 279), but looking to the test case it seems not unlikely
that the test case may be getting to the call to BTU.closeWindow (nearby the end of finish_test test helper
function) while the promise returned from BTU.openNewForegroundTab was not resolved yet and that the call to
BTU.openNewForegroundTab is then trying to access tabbrowser.ownerGlobal.windowGlobalChild well the tab and the window where
it was open are already gone.

This patch adds an explicit await on the promise returned by BTU.openNewForegroundTab right before calling
BTU.closeWindow to make sure that the browser window containing that tab isn't closed until BTU.openNewForegroundTab
promise is resolved.

Assignee: nobody → lgreco
Pushed by luca.greco@alcacoop.it:
https://hg.mozilla.org/integration/autoland/rev/a920014426ba
Add to browser_newwindow.js an explicit await on BTU.openNewForegroundTab returned promise. r=mixedpuppy
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 105 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: