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
Description
•