Closed Bug 1575744 Opened 5 years ago Closed 5 years ago

Move NS_HTTP_ON_MAY_CHANGE_PROCESS_TOPIC to DocumentChannelParent

Categories

(Core :: Networking: HTTP, task, P2)

task

Tracking

()

RESOLVED FIXED
mozilla71
Fission Milestone M4
Tracking Status
firefox71 --- fixed

People

(Reporter: mattwoodrow, Assigned: jya)

References

(Blocks 1 open bug)

Details

(Whiteboard: [necko-triaged])

Attachments

(8 files, 1 obsolete file)

47 bytes, text/x-phabricator-request
Details | Review
47 bytes, text/x-phabricator-request
Details | Review
47 bytes, text/x-phabricator-request
Details | Review
47 bytes, text/x-phabricator-request
Details | Review
47 bytes, text/x-phabricator-request
Details | Review
47 bytes, text/x-phabricator-request
Details | Review
47 bytes, text/x-phabricator-request
Details | Review
47 bytes, text/x-phabricator-request
Details | Review

Currently we fire the NS_HTTP_ON_MAY_CHANGE_PROCESS_TOPIC event from multiple places in http code, and we want to move this to DocumentChannelParent so that it only gets called once, and after all redirects have been processed. It should also mean that we can handle protocols other than http.

The code currently passes an nsIHttpChannel as the subject, which we can't guarantee with this, since we might have redirected to a different protocol. We also currently have 'switchProcessTo' on nsIHttpChannel (for code within the callback to request a process switch), which is weird for other users of http.

I think we should add a new interface (nsIProcessSwitchRequestor?) that has an nsIChannel member, and the switchProcessTo method.

DocumentChannelParent can then call the event from OnStartRequest, passing an instance of the requestor helper that has the current mChannel.

browser_cross_process_redirect.js and SessionStore.jsm would need to be updated to accept this new interface, and to QI the channel to nsIHttpChannel as appropriate.

We likely also want to rename the event, and move the firing of it outside of nsHttpHandler.h.

Note that we may actually want to duplicate everything here, rather than moving, such that we can have things work when DocumentChannel is preffed off.

Matt, can you block any (meta-)bugs for better tracking?

Priority: -- → P2
Whiteboard: [necko-triaged]

This is a stepped transition ; as SessionStore currently only knows how to deal with nsHttpChannel we have to go through the redirect only if the current channel is a nsHttpChannel.
In a followup change we will allow SessionStore to directly deal with the DocumentParentProcess.

Will allow for SessionStore.jsm process switching to be used by other objects than nsHttpChannel.

There's only one consumer of these promises. It doesn't need to be non-exclusive.

Similar to MozPromise::FromGeckoResult.

Allows to create a MozPromise that will be resolved/rejected when the JS promise does the same.
It would be nice to be able to chain the two promise types, but it would be an additional effort.

Attachment #9093029 - Attachment is obsolete: true

SessionStore.jsm can't be called with a nsViewSourceChannel, as nsViewSourceChannel is only used from the parent process.

Thanks to the promisifying of SendCrossProcessRedirect we no longer needs callback to DocumentChannelParent from nsHttpChannelParent. So we can remove the interface that allowed to do so.

Attachment #9093329 - Attachment description: Bug 1575744 - P6. Remove unused nsnsIProcessSwitchRequestor implementation. r?mayhemer → Bug 1575744 - P6. Remove unused nsIProcessSwitchRequestor implementation. r?mayhemer
Attachment #9093024 - Attachment description: Bug 1575744 - P4. Add MozPromise::FromDomPromise. r?nika → Bug 1575744 - P4. Add MozPromise::FromDomPromise. r?bholley
Pushed by jyavenard@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/c74b81debf73
P1. Only perform process switch once we have completed all redirects. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/d5244c1bbfe8
P2. Add nsIProcessSwitchRequestor interface. r=mayhemer,nika
https://hg.mozilla.org/integration/autoland/rev/5abb38484f11
P3. Make ContentProcessIdPromise exclusive. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/382ee8672027
P4. Add MozPromise::FromDomPromise. r=bholley
https://hg.mozilla.org/integration/autoland/rev/c1547b3df672
P5. Implement process switch from DocumentChannelParent. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/39ffb74d2e12
P6. Remove unused nsIProcessSwitchRequestor implementation. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/bf794b9373c8
P7. Promisify SendCrossProcessRedirect to avoid needing PHttpChannel to get a response. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/af61675dd488
P8. Remove no longer necessary interface. r=mayhemer

Backed out 8 changesets (Bug 1575744) for HttpChannelParent related assertion failures

Push with failures: https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=os%2Cx%2C10.14%2Cdebug%2Cmochitests%2Ctest-macosx1014-64%2Fdebug-mochitest-browser-chrome-e10s&fromchange=840f7be6f7e3a0118c1b31cf9334a11cfd40f5e5&tochange=a4a45aa31c49c17043cf58643654bff78ed53a72&selectedJob=267577234

Backout link: https://hg.mozilla.org/integration/autoland/rev/a4a45aa31c49c17043cf58643654bff78ed53a72

Failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267577234&repo=autoland&lineNumber=9714

[task 2019-09-20T03:57:16.553Z] 03:57:16 INFO - TEST-START | dom/tests/browser/browser_bug1563629.js
[task 2019-09-20T03:57:16.553Z] 03:57:16 INFO - GECKO(1873) | Chrome file doesn't exist: /Users/cltbld/tasks/task_1568951315/build/tests/mochitest/browser/dom/tests/browser/head.js
[task 2019-09-20T03:57:16.553Z] 03:57:16 INFO - GECKO(1873) | ++DOCSHELL 0x120c51000 == 9 [pid = 1873] [id = {f260f203-8192-624f-9dcd-c260bc3d5bcd}]
[task 2019-09-20T03:57:16.553Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 39 (0x120f7e200) [pid = 1873] [serial = 47] [outer = 0x0]
[task 2019-09-20T03:57:16.553Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 40 (0x120c8b800) [pid = 1873] [serial = 48] [outer = 0x120f7e200]
[task 2019-09-20T03:57:16.553Z] 03:57:16 INFO - GECKO(1873) | [Parent 1873, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 416
[task 2019-09-20T03:57:16.553Z] 03:57:16 INFO - GECKO(1873) | ++DOCSHELL 0x120c3d000 == 10 [pid = 1873] [id = {df5ee3ca-366f-4542-b49d-32ad4f74ec9b}]
[task 2019-09-20T03:57:16.554Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 41 (0x120f7e3e0) [pid = 1873] [serial = 49] [outer = 0x0]
[task 2019-09-20T03:57:16.554Z] 03:57:16 INFO - GECKO(1873) | ++DOCSHELL 0x136211000 == 11 [pid = 1873] [id = {e4f6208b-ea86-d74e-87ee-7dcd5742fbfe}]
[task 2019-09-20T03:57:16.554Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 42 (0x120f7ef20) [pid = 1873] [serial = 50] [outer = 0x0]
[task 2019-09-20T03:57:16.554Z] 03:57:16 INFO - GECKO(1873) | ++DOCSHELL 0x110146000 == 2 [pid = 1878] [id = {83478857-bb8f-e240-9f88-14ac14133bcb}]
[task 2019-09-20T03:57:16.554Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 5 (0x110b3b020) [pid = 1878] [serial = 35] [outer = 0x0]
[task 2019-09-20T03:57:16.554Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 6 (0x110b1f400) [pid = 1878] [serial = 36] [outer = 0x110b3b020]
[task 2019-09-20T03:57:16.554Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 43 (0x135426800) [pid = 1873] [serial = 51] [outer = 0x120f7e3e0]
[task 2019-09-20T03:57:16.554Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 44 (0x135427800) [pid = 1873] [serial = 52] [outer = 0x120f7ef20]
[task 2019-09-20T03:57:16.555Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 9 (0x10e839000) [pid = 1879] [serial = 8] [outer = 0x0] [url = about:blank]
[task 2019-09-20T03:57:16.555Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 8 (0x10f3c2000) [pid = 1879] [serial = 9] [outer = 0x0] [url = about:blank]
[task 2019-09-20T03:57:16.555Z] 03:57:16 INFO - GECKO(1873) | --DOCSHELL 0x126509000 == 1 [pid = 1879] [id = {c062638f-d167-7a4a-b776-e069b3bd576a}] [url = data:text/html,<script>window.focus();var down = 0; var press = 0;onkeydown = function(e) { var startTime = Date.now(); document.body.setAttribute('data-down', ++down); if (e.keyCode == KeyboardEvent.DOM_VK_D) while (Date.now() - startTime < 500) {}};onkeypress = function(e) { var startTime = Date.now(); document.body.setAttribute('data-press', ++press); if (e.charCode == 'p'.charCodeAt(0)) while (Date.now() - startTime < 500) {}};</script>]
[task 2019-09-20T03:57:16.555Z] 03:57:16 INFO - GECKO(1873) | --DOCSHELL 0x12650f800 == 0 [pid = 1879] [id = {b07150b9-29e6-ad47-b5b0-1821d07a3a86}] [url = about:blank]
[task 2019-09-20T03:57:16.555Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 7 (0x12653e3e0) [pid = 1879] [serial = 11] [outer = 0x0] [url = about:blank]
[task 2019-09-20T03:57:16.555Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 6 (0x12653e020) [pid = 1879] [serial = 7] [outer = 0x0] [url = http://example.com/browser/dom/tests/browser/test_bug1004814.html]
[task 2019-09-20T03:57:16.556Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 7 (0x1177b2400) [pid = 1878] [serial = 37] [outer = 0x110b3b020]
[task 2019-09-20T03:57:16.556Z] 03:57:16 INFO - GECKO(1873) | [Parent 1873, Main Thread] WARNING: Ignoring duplicate observer.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2696
[task 2019-09-20T03:57:16.556Z] 03:57:16 INFO - GECKO(1873) | [1887, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-20T03:57:16.556Z] 03:57:16 INFO - GECKO(1873) | [1887, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-20T03:57:16.556Z] 03:57:16 INFO - GECKO(1873) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/g3/y3shk2v57mdbc0wpymgr90dm000017/T/tmpZGEBWh.mozrunner/runtests_leaks_tab_pid1887.log
[task 2019-09-20T03:57:16.556Z] 03:57:16 INFO - GECKO(1873) | [1887, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-20T03:57:16.556Z] 03:57:16 INFO - GECKO(1873) | [1887, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-20T03:57:16.557Z] 03:57:16 INFO - GECKO(1873) | 2019-09-20 03:57:03.294 firefox[1873:13382] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightlydebug.savedState/window_1.data: No such file or directory (2)
[task 2019-09-20T03:57:16.557Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 0 (0x119f83c00) [pid = 1882] [serial = 3] [outer = 0x0] [url = about:blank]
[task 2019-09-20T03:57:16.557Z] 03:57:16 INFO - GECKO(1873) | ++DOCSHELL 0x11ea08000 == 3 [pid = 1878] [id = {31efdc3c-97cf-fa41-a362-70ea93a2b974}]
[task 2019-09-20T03:57:16.557Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 8 (0x110b3bf20) [pid = 1878] [serial = 38] [outer = 0x0]
[task 2019-09-20T03:57:16.557Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 9 (0x11720b800) [pid = 1878] [serial = 39] [outer = 0x110b3bf20]
[task 2019-09-20T03:57:16.557Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 10 (0x11720dc00) [pid = 1878] [serial = 40] [outer = 0x110b3bf20]
[task 2019-09-20T03:57:16.557Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 1 (0x1100e2200) [pid = 1881] [serial = 1] [outer = 0x0] [url = about:blank]
[task 2019-09-20T03:57:16.557Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 43 (0x12fdf6d40) [pid = 1873] [serial = 35] [outer = 0x0] [url = about:support]
[task 2019-09-20T03:57:16.558Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 42 (0x12fdf6b60) [pid = 1873] [serial = 32] [outer = 0x0] [url = about:support]
[task 2019-09-20T03:57:16.558Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 41 (0x122372880) [pid = 1873] [serial = 29] [outer = 0x0] [url = about:support]
[task 2019-09-20T03:57:16.558Z] 03:57:16 INFO - GECKO(1873) | ++DOCSHELL 0x12350a000 == 1 [pid = 1887] [id = {e387fc6f-62bf-e24d-803c-1250584d87a2}]
[task 2019-09-20T03:57:16.558Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 1 (0x123540020) [pid = 1887] [serial = 1] [outer = 0x0]
[task 2019-09-20T03:57:16.558Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 2 (0x11d9d6000) [pid = 1887] [serial = 2] [outer = 0x123540020]
[task 2019-09-20T03:57:16.558Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 3 (0x123586800) [pid = 1887] [serial = 3] [outer = 0x123540020]
[task 2019-09-20T03:57:16.558Z] 03:57:16 INFO - GECKO(1873) | Content running top level script http://example.com/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-09-20T03:57:16.558Z] 03:57:16 INFO - GECKO(1873) | Content got load of http://example.com/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-09-20T03:57:16.559Z] 03:57:16 INFO - GECKO(1873) | Content got event http://example.com/browser/dom/tests/browser/file_postMessage_parent.html {"event":"load","location":"http://example.com/browser/dom/tests/browser/file_postMessage_parent.html"}
[task 2019-09-20T03:57:16.559Z] 03:57:16 INFO - GECKO(1873) | ++DOCSHELL 0x123512000 == 2 [pid = 1887] [id = {3ba6964c-42b0-e143-aef7-cd91e870005a}]
[task 2019-09-20T03:57:16.559Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 4 (0x1235403e0) [pid = 1887] [serial = 4] [outer = 0x0]
[task 2019-09-20T03:57:16.559Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 5 (0x1239c5800) [pid = 1887] [serial = 5] [outer = 0x1235403e0]
[task 2019-09-20T03:57:16.559Z] 03:57:16 INFO - GECKO(1873) | [Child 1887, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-09-20T03:57:16.559Z] 03:57:16 INFO - GECKO(1873) | [1888, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-20T03:57:16.560Z] 03:57:16 INFO - GECKO(1873) | [1888, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-20T03:57:16.560Z] 03:57:16 INFO - GECKO(1873) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/g3/y3shk2v57mdbc0wpymgr90dm000017/T/tmpZGEBWh.mozrunner/runtests_leaks_tab_pid1888.log
[task 2019-09-20T03:57:16.560Z] 03:57:16 INFO - GECKO(1873) | [1888, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-20T03:57:16.560Z] 03:57:16 INFO - GECKO(1873) | [1888, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-20T03:57:16.560Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 6 (0x146b3a000) [pid = 1877] [serial = 11] [outer = 0x0] [url = about:blank]
[task 2019-09-20T03:57:16.560Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 5 (0x146bdcc00) [pid = 1877] [serial = 15] [outer = 0x0] [url = http://mochi.test:8888/browser/dom/tests/browser/dummy.html]
[task 2019-09-20T03:57:16.560Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 4 (0x122f3b7a0) [pid = 1877] [serial = 16] [outer = 0x0] [url = about:blank]
[task 2019-09-20T03:57:16.560Z] 03:57:16 INFO - GECKO(1873) | --DOCSHELL 0x123512000 == 1 [pid = 1887] [id = {3ba6964c-42b0-e143-aef7-cd91e870005a}] [url = about:blank]
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO - GECKO(1873) | --DOMWINDOW == 0 (0x127ac2800) [pid = 1883] [serial = 3] [outer = 0x0] [url = about:privatebrowsing]
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO - GECKO(1873) | ++DOCSHELL 0x119ce3000 == 1 [pid = 1888] [id = {1d49f040-34b2-4b48-9b5a-848c1d6589e8}]
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 1 (0x11f838020) [pid = 1888] [serial = 1] [outer = 0x0]
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 2 (0x119ccac00) [pid = 1888] [serial = 2] [outer = 0x11f838020]
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO - GECKO(1873) | Assertion failure: mBgParent, at /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelParent.cpp:1534
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO - GECKO(1873) | #01: mozilla::net::nsHttpChannel::CallOnStartRequest() [netwerk/protocol/http/nsHttpChannel.cpp:1864]
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO - GECKO(1873) | #02: mozilla::net::nsHttpChannel::ContinueProcessNormal(nsresult) [netwerk/protocol/http/nsHttpChannel.cpp:3034]
[task 2019-09-20T03:57:16.561Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO - GECKO(1873) | #03: mozilla::net::nsHttpChannel::ProcessNormal() [netwerk/protocol/http/nsHttpChannel.cpp:2970]
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO - GECKO(1873) | #04: mozilla::net::nsHttpChannel::ContinueProcessResponse3(nsresult) [netwerk/protocol/http/nsHttpChannel.cpp:0]
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO - GECKO(1873) | #05: mozilla::net::nsHttpChannel::ContinueProcessResponse2(nsresult) [netwerk/protocol/http/nsHttpChannel.cpp:2634]
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO - GECKO(1873) | #06: mozilla::net::nsHttpChannel::OnRedirectVerifyCallback(nsresult) [netwerk/protocol/http/nsHttpChannel.cpp:9109]
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO - GECKO(1873) | #07: mozilla::net::HttpChannelParent::CrossProcessRedirectDone(nsresult const&, mozilla::Maybe<mozilla::net::LoadInfoArgs> const&) [netwerk/protocol/http/HttpChannelParent.cpp:1304]
[task 2019-09-20T03:57:16.562Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO - GECKO(1873) | #08: mozilla::MozPromise<mozilla::Tuple<nsresult, mozilla::Maybe<mozilla::net::LoadInfoArgs> >, mozilla::ipc::ResponseRejectReason, true>::ThenValue<mozilla::net::HttpChannelParent::TriggerCrossProcessSwitch(nsIHttpChannel*, unsigned long long)::$_28::operator()(unsigned long long) const::'lambda'(mozilla::Tuple<nsresult, mozilla::Maybe<mozilla::net::LoadInfoArgs> >&&), mozilla::net::HttpChannelParent::TriggerCrossProcessSwitch(nsIHttpChannel*, unsigned long long)::$_28::operator()(unsigned long long) const::'lambda'(mozilla::ipc::ResponseRejectReason)>::DoResolveOrRejectInternal(mozilla::MozPromise<mozilla::Tuple<nsresult, mozilla::Maybe<mozilla::net::LoadInfoArgs> >, mozilla::ipc::ResponseRejectReason, true>::ResolveOrRejectValue&) [xpcom/threads/MozPromise.h:739]
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO - GECKO(1873) | #09: mozilla::MozPromise<mozilla::Tuple<nsresult, mozilla::Maybe<mozilla::net::LoadInfoArgs> >, mozilla::ipc::ResponseRejectReason, true>::ThenValueBase::ResolveOrRejectRunnable::Run() [xpcom/threads/MozPromise.h:403]
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO - GECKO(1873) | #10: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1214]
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO - GECKO(1873) | #11: NS_ProcessPendingEvents(nsIThread*, unsigned int) [xpcom/threads/nsThreadUtils.cpp:434]
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO - GECKO(1873) | #12: nsBaseAppShell::NativeEventCallback() [widget/nsBaseAppShell.cpp:88]
[task 2019-09-20T03:57:16.567Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO - GECKO(1873) | #13: nsAppShell::ProcessGeckoEvents(void*) [widget/cocoa/nsAppShell.mm:441]
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO - GECKO(1873) | #14: CoreFoundation + 0x58083
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO - GECKO(1873) | #15: CoreFoundation + 0x58029
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO - GECKO(1873) | #16: CoreFoundation + 0x3b9eb
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO - GECKO(1873) | #17: CoreFoundation + 0x3afb5
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO - GECKO(1873) | #18: CoreFoundation + 0x3a8be
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO - GECKO(1873) | #19: HIToolbox + 0xa96b
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO - GECKO(1873) | #20: HIToolbox + 0xa6a5
[task 2019-09-20T03:57:16.568Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #21: HIToolbox + 0xa436
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #22: AppKit + 0x1a987
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #23: AppKit + 0x1971f
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #24: -[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [widget/cocoa/nsAppShell.mm:0]
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #25: AppKit + 0x1383c
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #26: nsAppShell::Run() [widget/cocoa/nsAppShell.mm:708]
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #27: nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:277]
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #28: XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:4601]
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO - GECKO(1873) | #29: XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:4736]
[task 2019-09-20T03:57:16.569Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | #30: XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:4817]
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | #31: main [browser/app/nsBrowserApp.cpp:300]
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO -
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | ++DOMWINDOW == 3 (0x11f881800) [pid = 1888] [serial = 3] [outer = 0x11f838020]
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.570Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.571Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.571Z] 03:57:16 INFO - GECKO(1873) | Exiting due to channel error.
[task 2019-09-20T03:57:16.646Z] 03:57:16 INFO - TEST-INFO | Main app process: exit 1
[task 2019-09-20T03:57:16.646Z] 03:57:16 ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
[task 2019-09-20T03:57:16.646Z] 03:57:16 INFO - Buffered messages logged at 03:57:02
[task 2019-09-20T03:57:16.647Z] 03:57:16 INFO - Entering test bound
[task 2019-09-20T03:57:16.647Z] 03:57:16 INFO - Console message: OpenGL compositor Initialized Succesfully.
[task 2019-09-20T03:57:16.647Z] 03:57:16 INFO - Version: 2.1 INTEL-12.9.22
[task 2019-09-20T03:57:16.647Z] 03:57:16 INFO - Vendor: Intel Inc.
[task 2019-09-20T03:57:16.647Z] 03:57:16 INFO - Renderer: Intel Iris OpenGL Engine
[task 2019-09-20T03:57:16.647Z] 03:57:16 INFO - FBO Texture Target: TEXTURE_2D
[task 2019-09-20T03:57:16.647Z] 03:57:16 INFO - Buffered messages logged at 03:57:04
[task 2019-09-20T03:57:16.647Z] 03:57:16 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/dom/tests/browser/file_postMessage_parent.html" line: 0}]
[task 2019-09-20T03:57:16.647Z] 03:57:16 INFO - Buffered messages finished
[task 2019-09-20T03:57:16.647Z] 03:57:16 ERROR - TEST-UNEXPECTED-FAIL | dom/tests/browser/browser_bug1563629.js | application terminated with exit code 1
[task 2019-09-20T03:57:16.648Z] 03:57:16 INFO - runtests.py | Application ran for: 0:00:44.105259
...

Flags: needinfo?(jyavenard)

Another failure introduced with these changes: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267577432&repo=autoland&lineNumber=5543

[task 2019-09-20T04:23:09.877Z] 04:23:09 INFO - TEST-START | dom/html/test/browser_form_post_from_file_to_http.js
[task 2019-09-20T04:23:10.933Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.973Z] 04:23:10 INFO - GECKO(1088) | [Child 10604, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.973Z] 04:23:10 INFO - GECKO(1088) | [Child 10604, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.975Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.985Z] 04:23:10 INFO - GECKO(1088) | [GPU 10684, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.985Z] 04:23:10 INFO - GECKO(1088) | [GPU 10684, Chrome_ChildThrea
[task 2019-09-20T04:23:10.985Z] 04:23:10 INFO - GECKO(1088) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-09-20T04:23:10.986Z] 04:23:10 INFO - GECKO(1088) | d] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.986Z] 04:23:10 INFO - GECKO(1088) | [GPU 10684, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.986Z] 04:23:10 INFO - GECKO(1088) | [GPU 10684, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.987Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.987Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.987Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:24:39.918Z] 04:24:39 INFO - TEST-INFO | started process screenshot
[task 2019-09-20T04:24:40.124Z] 04:24:40 INFO - TEST-INFO | screenshot: exit 0
[task 2019-09-20T04:24:40.125Z] 04:24:40 INFO - Buffered messages logged at 04:23:09
[task 2019-09-20T04:24:40.125Z] 04:24:40 INFO - Entering test bound
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - Buffered messages finished
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/browser_form_post_from_file_to_http.js | Test timed out -
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - GECKO(1088) | MEMORY STAT | vsize 19406102MB | vsizeMaxContiguous 68972474MB | residentFast 1144MB
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - TEST-OK | dom/html/test/browser_form_post_from_file_to_http.js | took 90087ms
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/browser_form_post_from_file_to_http.js | Found a tab after previous test timed out: about:blank -
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - checking window state
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - TEST-START | dom/html/test/browser_fullscreen-api-keys.js
[task 2019-09-20T04:24:41.137Z] 04:24:41 INFO - GECKO(1088) | MEMORY STAT | vsize 19406135MB | vsizeMaxContiguous 68972474MB | residentFast 1141MB
[task 2019-09-20T04:24:41.137Z] 04:24:41 INFO - TEST-OK | dom/html/test/browser_fullscreen-api-keys.js | took 1021ms

Another new failure on the push: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267578409&repo=autoland&lineNumber=3122

[task 2019-09-20T04:25:54.667Z] 04:25:54 INFO - TEST-START | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js
[task 2019-09-20T04:26:00.932Z] 04:26:00 INFO - GECKO(1849) | JavaScript error: resource://formautofill/FormAutofillHandler.jsm, line 1000: TypeError: this.form.rootElement.ownerGlobal is null
[task 2019-09-20T04:26:05.321Z] 04:26:05 INFO - GECKO(1849) | Exiting due to channel error.
[task 2019-09-20T04:26:05.321Z] 04:26:05 INFO - GECKO(1849) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2019-09-20T04:26:05.321Z] 04:26:05 INFO - GECKO(1849) | Exiting due to channel error.
[task 2019-09-20T04:26:05.328Z] 04:26:05 INFO - GECKO(1849) | Exiting due to channel error.
[task 2019-09-20T04:26:05.328Z] 04:26:05 INFO - GECKO(1849) | Exiting due to channel error.
[task 2019-09-20T04:26:05.329Z] 04:26:05 INFO - GECKO(1849) | Exiting due to channel error.
[task 2019-09-20T04:26:05.329Z] 04:26:05 INFO - GECKO(1849) | Exiting due to channel error.
[task 2019-09-20T04:26:05.329Z] 04:26:05 INFO - GECKO(1849) | [Child 1862, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 728
[task 2019-09-20T04:26:05.329Z] 04:26:05 INFO - GECKO(1849) | Exiting due to channel error.
[task 2019-09-20T04:26:05.329Z] 04:26:05 INFO - GECKO(1849) | Exiting due to channel error.
[task 2019-09-20T04:26:05.466Z] 04:26:05 INFO - TEST-INFO | Main app process: exit 1
[task 2019-09-20T04:26:05.467Z] 04:26:05 INFO - Buffered messages logged at 04:25:54
[task 2019-09-20T04:26:05.467Z] 04:26:05 INFO - Entering test bound setup
[task 2019-09-20T04:26:05.467Z] 04:26:05 INFO - Leaving test bound setup
[task 2019-09-20T04:26:05.467Z] 04:26:05 INFO - Entering test bound activity_stream_in_privileged_content_process
[task 2019-09-20T04:26:05.467Z] 04:26:05 INFO - Buffered messages logged at 04:25:55
[task 2019-09-20T04:26:05.467Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.467Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.468Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.468Z] 04:26:05 INFO - Buffered messages logged at 04:25:56
[task 2019-09-20T04:26:05.468Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.468Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.468Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.468Z] 04:26:05 INFO - Buffered messages logged at 04:25:57
[task 2019-09-20T04:26:05.469Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.469Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.469Z] 04:26:05 INFO - Buffered messages logged at 04:25:58
[task 2019-09-20T04:26:05.469Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.469Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.470Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab tabs are in the same privileged about: content process. -
[task 2019-09-20T04:26:05.470Z] 04:26:05 INFO - Leaving test bound activity_stream_in_privileged_content_process
[task 2019-09-20T04:26:05.470Z] 04:26:05 INFO - Entering test bound process_switching_through_loading_in_the_same_tab
[task 2019-09-20T04:26:05.470Z] 04:26:05 INFO - Buffered messages logged at 04:25:59
[task 2019-09-20T04:26:05.471Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.471Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.471Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.472Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.472Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.472Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.472Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.473Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.473Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.473Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.473Z] 04:26:05 INFO - Buffered messages logged at 04:26:00
[task 2019-09-20T04:26:05.474Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.474Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.474Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.474Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.474Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.474Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.475Z] 04:26:05 INFO - Console message: [JavaScript Error: "TypeError: this.form.rootElement.ownerGlobal is null" {file: "resource://formautofill/FormAutofillHandler.jsm" line: 1000}]
[task 2019-09-20T04:26:05.475Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.475Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.475Z] 04:26:05 INFO - Buffered messages logged at 04:26:01
[task 2019-09-20T04:26:05.475Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.476Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.476Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.476Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.476Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.476Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.477Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.477Z] 04:26:05 INFO - Buffered messages logged at 04:26:02
[task 2019-09-20T04:26:05.477Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.477Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.478Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.478Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.478Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.478Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.479Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.479Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.479Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.479Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.479Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.479Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.480Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.480Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.480Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.480Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.480Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.480Z] 04:26:05 INFO - Leaving test bound process_switching_through_loading_in_the_same_tab
[task 2019-09-20T04:26:05.480Z] 04:26:05 INFO - Entering test bound process_switching_through_navigation_features
[task 2019-09-20T04:26:05.481Z] 04:26:05 INFO - Buffered messages logged at 04:26:03
[task 2019-09-20T04:26:05.481Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the privilegedabout content process. -
[task 2019-09-20T04:26:05.481Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.481Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that new tab opened from about:newtab is loaded in privileged about: content process. -
[task 2019-09-20T04:26:05.482Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab is still in privileged about: content process after reload. -
[task 2019-09-20T04:26:05.482Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Ensures that tab runs in the web content process. -
[task 2019-09-20T04:26:05.482Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Parent and child process should agree on the remote type. -
[task 2019-09-20T04:26:05.482Z] 04:26:05 INFO - Buffered messages logged at 04:26:04
[task 2019-09-20T04:26:05.482Z] 04:26:05 INFO - TEST-PASS | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | Check that about:newtab is still in privileged about: content process after history goBack. -
[task 2019-09-20T04:26:05.482Z] 04:26:05 INFO - Buffered messages finished
[task 2019-09-20T04:26:05.482Z] 04:26:05 ERROR - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | application terminated with exit code 1
[task 2019-09-20T04:26:05.482Z] 04:26:05 INFO - runtests.py | Application ran for: 0:01:57.069266
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - zombiecheck | Reading PID log: /var/folders/kb/25mntl_94mq8m3vckk6sv7jw000017/T/tmpBBfabCpidlog
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1850
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1851
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1852
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1853
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1854
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1855
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1856
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1857
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1858
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1859
[task 2019-09-20T04:26:05.483Z] 04:26:05 INFO - ==> process 1849 launched child process 1861
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1862
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1863
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1864
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1865
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1866
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1867
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1868
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1869
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1870
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - ==> process 1849 launched child process 1871
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1856
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1857
[task 2019-09-20T04:26:05.484Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1858
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1859
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1861
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1862
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1863
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1864
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1865
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1866
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1867
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1868
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1869
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1870
[task 2019-09-20T04:26:05.485Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1871
[task 2019-09-20T04:26:05.486Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1850
[task 2019-09-20T04:26:05.486Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1851
[task 2019-09-20T04:26:05.486Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1852
[task 2019-09-20T04:26:05.486Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1853
[task 2019-09-20T04:26:05.486Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1854
[task 2019-09-20T04:26:05.486Z] 04:26:05 INFO - zombiecheck | Checking for orphan process with PID: 1855
[task 2019-09-20T04:26:05.486Z] 04:26:05 INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/R0gaRRDzR72zgkv5PS4fcQ/artifacts/public/build/target.crashreporter-symbols.zip
[task 2019-09-20T04:26:11.150Z] 04:26:11 INFO - mozcrash Copy/paste: /Users/cltbld/tasks/task_1568953047/build/macosx64-minidump_stackwalk /var/folders/kb/25mntl_94mq8m3vckk6sv7jw000017/T/tmpbqcxMk.mozrunner/minidumps/9E9672BB-2F9C-4CCE-AC58-E1C747E074F8.dmp /var/folders/kb/25mntl_94mq8m3vckk6sv7jw000017/T/tmpK42TMk
[task 2019-09-20T04:26:16.488Z] 04:26:16 INFO - mozcrash Saved minidump as /Users/cltbld/tasks/task_1568953047/build/blobber_upload_dir/9E9672BB-2F9C-4CCE-AC58-E1C747E074F8.dmp
[task 2019-09-20T04:26:16.489Z] 04:26:16 INFO - mozcrash Saved app info as /Users/cltbld/tasks/task_1568953047/build/blobber_upload_dir/9E9672BB-2F9C-4CCE-AC58-E1C747E074F8.extra
[task 2019-09-20T04:26:16.627Z] 04:26:16 INFO - PROCESS-CRASH | browser/base/content/test/tabs/browser_new_tab_in_privilegedabout_process_pref.js | application crashed [@ mozilla::net::HttpBackgroundChannelParent::OnStartRequestSent()]
[task 2019-09-20T04:26:16.627Z] 04:26:16 INFO - Crash dump filename: /var/folders/kb/25mntl_94mq8m3vckk6sv7jw000017/T/tmpbqcxMk.mozrunner/minidumps/9E9672BB-2F9C-4CCE-AC58-E1C747E074F8.dmp
[task 2019-09-20T04:26:16.627Z] 04:26:16 INFO - Operating system: Mac OS X
[task 2019-09-20T04:26:16.627Z] 04:26:16 INFO - 10.14.5 18F132
[task 2019-09-20T04:26:16.627Z] 04:26:16 INFO - CPU: amd64
[task 2019-09-20T04:26:16.627Z] 04:26:16 INFO - family 6 model 69 stepping 1
[task 2019-09-20T04:26:16.627Z] 04:26:16 INFO - 4 CPUs
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO -
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - GPU: UNKNOWN
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO -
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - Crash reason: EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - Crash address: 0x38
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - Process uptime: 117 seconds
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO -
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - Thread 0 (crashed)
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - 0 XUL!mozilla::net::HttpBackgroundChannelParent::OnStartRequestSent() [HttpBackgroundChannelParent.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 149 + 0x1b]
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - rax = 0x0000000115fae900 rdx = 0x0000000000000000
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - rcx = 0x0000000000000000 rbx = 0x0000000000000038
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - rsi = 0x00000000000120a8 rdi = 0x0000000000000000
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - rbp = 0x00007ffeec58e8c0 rsp = 0x00007ffeec58e890
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - r8 = 0x00000000000130a8 r9 = 0x00000001161bf298
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - r10 = 0x0000000000000000 r11 = 0x00000001161bf290
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - r12 = 0x0000000000000000 r13 = 0x00000001036e0130
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - r14 = 0x00007ffeec58eb98 r15 = 0x00000001161bf000
[task 2019-09-20T04:26:16.628Z] 04:26:16 INFO - rip = 0x0000000108707c9d
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - Found by: given as instruction pointer in context
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - 1 XUL!mozilla::net::HttpChannelParent::OnStartRequest(nsIRequest*) [HttpChannelParent.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 1535 + 0xc]
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - rbp = 0x00007ffeec58ec20 rsp = 0x00007ffeec58e8d0
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - rip = 0x000000010873c6f2
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - 2 XUL!mozilla::net::nsHttpChannel::CallOnStartRequest() [nsHttpChannel.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 1864 + 0xe]
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - rbp = 0x00007ffeec58ee00 rsp = 0x00007ffeec58ec30
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - rip = 0x0000000108787700
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - 3 XUL!mozilla::net::nsHttpChannel::ContinueOnStartRequest1(nsresult) [nsHttpChannel.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 7774 + 0xd]
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - rbp = 0x00007ffeec58ee90 rsp = 0x00007ffeec58ee10
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - rip = 0x00000001087a752e
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - 4 XUL!mozilla::net::nsHttpChannel::OnRedirectVerifyCallback(nsresult) [nsHttpChannel.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 9109 + 0x1a]
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - rbp = 0x00007ffeec58eef0 rsp = 0x00007ffeec58eea0
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - rip = 0x00000001087af7ba
[task 2019-09-20T04:26:16.629Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - 5 XUL!mozilla::net::HttpChannelParent::CrossProcessRedirectDone(nsresult const&, mozilla::Maybe<mozilla::net::LoadInfoArgs> const&) [HttpChannelParent.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 1304 + 0x28]
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - rbp = 0x00007ffeec58ef50 rsp = 0x00007ffeec58ef00
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - rip = 0x000000010873b876
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - 6 XUL!mozilla::MozPromise<mozilla::Tuple<nsresult, mozilla::Maybe<mozilla::net::LoadInfoArgs> >, mozilla::ipc::ResponseRejectReason, true>::ThenValue<mozilla::net::HttpChannelParent::TriggerCrossProcessSwitch(nsIHttpChannel*, unsigned long long)::$_28::operator()(unsigned long long) const::'lambda'(mozilla::Tuple<nsresult, mozilla::Maybe<mozilla::net::LoadInfoArgs> >&&), mozilla::net::HttpChannelParent::TriggerCrossProcessSwitch(nsIHttpChannel*, unsigned long long)::$_28::operator()(unsigned long long) const::'lambda'(mozilla::ipc::ResponseRejectReason)>::DoResolveOrRejectInternal(mozilla::MozPromise<mozilla::Tuple<nsresult, mozilla::Maybe<mozilla::net::LoadInfoArgs> >, mozilla::ipc::ResponseRejectReason, true>::ResolveOrRejectValue&) [MozPromise.h:af61675dd48876bfc97d932f3bcafd6a108fae5c : 726 + 0x1e]
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - rbp = 0x00007ffeec58fcf0 rsp = 0x00007ffeec58ef60
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - rip = 0x0000000108773a2f
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - 7 XUL!mozilla::MozPromise<mozilla::Tuple<nsresult, mozilla::Maybe<mozilla::net::LoadInfoArgs> >, mozilla::ipc::ResponseRejectReason, true>::ThenValueBase::ResolveOrRejectRunnable::Run() [MozPromise.h:af61675dd48876bfc97d932f3bcafd6a108fae5c : 402 + 0x1e]
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - rbp = 0x00007ffeec58fd10 rsp = 0x00007ffeec58fd00
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - rip = 0x00000001087740b4
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - 8 XUL!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 1225 + 0x6]
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - rbp = 0x00007ffeec590210 rsp = 0x00007ffeec58fd20
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - rip = 0x00000001082022f1
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.630Z] 04:26:16 INFO - 9 XUL!NS_ProcessPendingEvents(nsIThread*, unsigned int) [nsThreadUtils.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 434 + 0xe]
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - rbp = 0x00007ffeec590260 rsp = 0x00007ffeec590220
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - rip = 0x00000001081ffd62
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - 10 XUL!nsBaseAppShell::NativeEventCallback() [nsBaseAppShell.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 87 + 0x14]
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - rbp = 0x00007ffeec590290 rsp = 0x00007ffeec590270
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - rip = 0x000000010b6215c7
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - 11 XUL!nsAppShell::ProcessGeckoEvents(void*) [nsAppShell.mm:af61675dd48876bfc97d932f3bcafd6a108fae5c : 440 + 0x8]
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - rbp = 0x00007ffeec5902e0 rsp = 0x00007ffeec5902a0
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - rip = 0x000000010b6a83fc
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - 12 CoreFoundation!-[_CFXNotificationRegistrar match:object:observer:enumerator:] + 0x816
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - rbp = 0x00007ffeec5902f0 rsp = 0x00007ffeec5902f0
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - rip = 0x00007fff51b2e083
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.631Z] 04:26:16 INFO - 13 CoreFoundation!-[_CFXNotificationRegistrar match:object:observer:enumerator:] + 0x7bc
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rbp = 0x00007ffeec590320 rsp = 0x00007ffeec590300
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rip = 0x00007fff51b2e029
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - 14 CoreFoundation!__CFStringDecodeByteStream3 + 0x84b
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rbp = 0x00007ffeec590390 rsp = 0x00007ffeec590330
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rip = 0x00007fff51b119eb
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - 15 CoreFoundation!+[NSDate allocWithZone:] + 0x21
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rbp = 0x00007ffeec5910a0 rsp = 0x00007ffeec5903a0
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rip = 0x00007fff51b10fb5
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - 16 CoreFoundation!__CFRunLoopRun + 0xb37
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rbp = 0x00007ffeec591130 rsp = 0x00007ffeec5910b0
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rip = 0x00007fff51b108be
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - 17 HIToolbox!RunCurrentEventLoopInMode + 0x124
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rbp = 0x00007ffeec591180 rsp = 0x00007ffeec591140
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rip = 0x00007fff50dfc96b
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - 18 HIToolbox!ReceiveNextEventCommon + 0x25b
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rbp = 0x00007ffeec591200 rsp = 0x00007ffeec591190
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - rip = 0x00007fff50dfc6a5
[task 2019-09-20T04:26:16.632Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - 19 HIToolbox!_BlockUntilNextEventMatchingListInModeWithFilter + 0x40
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rbp = 0x00007ffeec591220 rsp = 0x00007ffeec591210
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rip = 0x00007fff50dfc436
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - 20 AppKit!_DPSNextEvent + 0x3c5
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rbp = 0x00007ffeec591630 rsp = 0x00007ffeec591230
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rip = 0x00007fff4f196987
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - 21 AppKit!-[NSApplication(NSEvent) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] + 0x551
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rbp = 0x00007ffeec5918b0 rsp = 0x00007ffeec591640
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rip = 0x00007fff4f19571f
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - 22 XUL!-[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [nsAppShell.mm:af61675dd48876bfc97d932f3bcafd6a108fae5c : 169 + 0x2c]
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rbp = 0x00007ffeec591920 rsp = 0x00007ffeec5918c0
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rip = 0x000000010b6a7451
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - 23 AppKit!-[NSApplication run] + 0x2bb
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rbp = 0x00007ffeec5919f0 rsp = 0x00007ffeec591930
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rip = 0x00007fff4f18f83c
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - 24 XUL!nsAppShell::Run() [nsAppShell.mm:af61675dd48876bfc97d932f3bcafd6a108fae5c : 703 + 0x1a]
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rbp = 0x00007ffeec591a30 rsp = 0x00007ffeec591a00
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - rip = 0x000000010b6a8be0
[task 2019-09-20T04:26:16.633Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - 25 XUL!nsAppStartup::Run() [nsAppStartup.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 276 + 0xa]
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rbp = 0x00007ffeec591a50 rsp = 0x00007ffeec591a40
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rip = 0x000000010cba975e
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - 26 XUL!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 4736 + 0xdb0]
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rbp = 0x00007ffeec591bd0 rsp = 0x00007ffeec591a60
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rip = 0x000000010cd00e75
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - 27 XUL!mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) [Bootstrap.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 45 + 0xe0]
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rbp = 0x00007ffeec591d00 rsp = 0x00007ffeec591be0
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rip = 0x000000010cd0c2dc
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - 28 firefox!main [nsBrowserApp.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 300 + 0x21b]
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rbp = 0x00007ffeec592170 rsp = 0x00007ffeec591d10
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rip = 0x000000010366dfde
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - 29 libdyld.dylib!start + 0x1
[task 2019-09-20T04:26:16.634Z] 04:26:16 INFO - rbp = 0x00007ffeec592180 rsp = 0x00007ffeec592180
[task 2019-09-20T04:26:16.635Z] 04:26:16 INFO - rip = 0x00007fff7da3b3d5
[task 2019-09-20T04:26:16.635Z] 04:26:16 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:26:16.635Z] 04:26:16 INFO -

Another failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267578410&repo=autoland&lineNumber=14418

[task 2019-09-20T04:19:49.389Z] 04:19:49 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_file_url
[task 2019-09-20T04:19:49.397Z] 04:19:49 INFO - 1568953189371 Marionette DEBUG 494 -> [0,59,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-09-20T04:19:49.398Z] 04:19:49 INFO - 1568953189371 Marionette DEBUG 494 <- [1,59,null,["3"]]
[task 2019-09-20T04:19:49.398Z] 04:19:49 INFO - 1568953189372 Marionette DEBUG 494 -> [0,60,"WebDriver:GetWindowHandles",{}]
[task 2019-09-20T04:19:49.398Z] 04:19:49 INFO - 1568953189373 Marionette DEBUG 494 <- [1,60,null,["45"]]
[task 2019-09-20T04:19:49.398Z] 04:19:49 INFO - 1568953189375 Marionette DEBUG 494 -> [0,61,"WebDriver:DeleteSession",{}]
[task 2019-09-20T04:19:49.398Z] 04:19:49 INFO - 1568953189376 Marionette DEBUG 494 <- [1,61,null,{"value":null}]
[task 2019-09-20T04:19:49.398Z] 04:19:49 INFO - 1568953189388 Marionette DEBUG Closed connection 494
[task 2019-09-20T04:19:49.398Z] 04:19:49 INFO - 1568953189389 Marionette DEBUG Accepted connection 495 from 127.0.0.1:50895
[task 2019-09-20T04:19:49.398Z] 04:19:49 INFO - 1568953189392 Marionette DEBUG 495 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-09-20T04:19:49.399Z] 04:19:49 INFO - 1568953189393 Marionette TRACE [45] Frame script loaded
[task 2019-09-20T04:19:49.399Z] 04:19:49 INFO - 1568953189397 Marionette TRACE [45] Frame script registered
[task 2019-09-20T04:19:49.415Z] 04:19:49 INFO - 1568953189406 Marionette DEBUG 495 <- [1,1,null,{"sessionId":"d69299d2-937c-de40-993b-67631ee6df2c","capabilities":{"browserName":"firefox","browserVersion":"71.0a ... /T/tmpIScy7K.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-09-20T04:19:49.415Z] 04:19:49 INFO - 1568953189409 Marionette DEBUG 495 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-09-20T04:19:49.415Z] 04:19:49 INFO - 1568953189409 Marionette DEBUG 495 <- [1,2,null,{"value":null}]
[task 2019-09-20T04:19:49.438Z] 04:19:49 INFO - 1568953189422 Marionette DEBUG 495 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-09-20T04:19:49.438Z] 04:19:49 INFO - 1568953189422 Marionette DEBUG 495 <- [1,3,null,{"value":null}]
[task 2019-09-20T04:19:49.438Z] 04:19:49 INFO - 1568953189424 Marionette DEBUG 495 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2019-09-20T04:19:49.438Z] 04:19:49 INFO - 1568953189424 Marionette DEBUG 495 <- [1,4,null,{"value":null}]
[task 2019-09-20T04:19:49.439Z] 04:19:49 INFO - 1568953189435 Marionette DEBUG 495 -> [0,5,"WebDriver:GetChromeWindowHandle",{}]
[task 2019-09-20T04:19:49.439Z] 04:19:49 INFO - 1568953189436 Marionette DEBUG 495 <- [1,5,null,{"value":"3"}]
[task 2019-09-20T04:19:49.439Z] 04:19:49 INFO - 1568953189436 Marionette DEBUG 495 -> [0,6,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-09-20T04:19:49.439Z] 04:19:49 INFO - 1568953189437 Marionette DEBUG 495 <- [1,6,null,["3"]]
[task 2019-09-20T04:19:49.439Z] 04:19:49 INFO - 1568953189437 Marionette DEBUG 495 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2019-09-20T04:19:49.440Z] 04:19:49 INFO - 1568953189437 Marionette DEBUG 495 <- [1,7,null,{"value":"45"}]
[task 2019-09-20T04:19:49.440Z] 04:19:49 INFO - 1568953189438 Marionette DEBUG 495 -> [0,8,"WebDriver:GetWindowHandles",{}]
[task 2019-09-20T04:19:49.440Z] 04:19:49 INFO - 1568953189438 Marionette DEBUG 495 <- [1,8,null,["45"]]
[task 2019-09-20T04:19:49.451Z] 04:19:49 INFO - 1568953189440 Marionette DEBUG 495 -> [0,9,"WebDriver:GetWindowHandles",{}]
[task 2019-09-20T04:19:49.451Z] 04:19:49 INFO - 1568953189440 Marionette DEBUG 495 <- [1,9,null,["45"]]
[task 2019-09-20T04:19:49.465Z] 04:19:49 INFO - 1568953189454 Marionette DEBUG 495 -> [0,10,"WebDriver:NewWindow",{"type":"tab","focus":false}]
[task 2019-09-20T04:19:49.468Z] 04:19:49 INFO - 1568953189458 Marionette TRACE Received DOM event TabOpen for [object XULElement]
[task 2019-09-20T04:19:49.510Z] 04:19:49 INFO - 1568953189502 Marionette TRACE [191] Frame script loaded
[task 2019-09-20T04:19:49.510Z] 04:19:49 INFO - 1568953189503 Marionette TRACE [191] Frame script registered
[task 2019-09-20T04:19:49.552Z] 04:19:49 INFO - 1568953189522 Marionette DEBUG 495 <- [1,10,null,{"handle":"191","type":"tab"}]
[task 2019-09-20T04:19:49.552Z] 04:19:49 INFO - 1568953189529 Marionette DEBUG 495 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2019-09-20T04:19:49.552Z] 04:19:49 INFO - 1568953189529 Marionette DEBUG 495 <- [1,11,null,["45","191"]]
[task 2019-09-20T04:19:49.552Z] 04:19:49 INFO - 1568953189539 Marionette DEBUG 495 -> [0,12,"WebDriver:GetWindowHandles",{}]
[task 2019-09-20T04:19:49.552Z] 04:19:49 INFO - 1568953189539 Marionette DEBUG 495 <- [1,12,null,["45","191"]]
[task 2019-09-20T04:19:49.577Z] 04:19:49 INFO - 1568953189556 Marionette DEBUG 495 -> [0,13,"WebDriver:SwitchToWindow",{"handle":"191","focus":true,"name":"191"}]
[task 2019-09-20T04:19:49.578Z] 04:19:49 INFO - 1568953189566 Marionette TRACE Received DOM event TabSelect for [object XULElement]
[task 2019-09-20T04:19:49.598Z] 04:19:49 INFO - 1568953189581 Marionette DEBUG 495 <- [1,13,null,{"value":null}]
[task 2019-09-20T04:19:49.614Z] 04:19:49 INFO - 1568953189597 Marionette DEBUG 495 -> [0,14,"WebDriver:GetTimeouts",{}]
[task 2019-09-20T04:19:49.615Z] 04:19:49 INFO - 1568953189598 Marionette DEBUG 495 <- [1,14,null,{"implicit":0,"pageLoad":300000,"script":30000}]
[task 2019-09-20T04:19:49.615Z] 04:19:49 INFO - 1568953189600 Marionette DEBUG 495 -> [0,15,"WebDriver:ExecuteScript",{"script":"return window.history.length;","newSandbox":true,"args":[],"filename":"tests/testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py","sandbox":"default","line":73}]
[task 2019-09-20T04:19:49.615Z] 04:19:49 INFO - 1568953189607 Marionette DEBUG 495 <- [1,15,null,{"value":1}]
[task 2019-09-20T04:19:49.635Z] 04:19:49 INFO - 1568953189624 Marionette DEBUG 495 -> [0,16,"WebDriver:Navigate",{"url":"http://127.0.0.1:50109/test.html"}]
[task 2019-09-20T04:19:49.641Z] 04:19:49 INFO - 1568953189635 Marionette TRACE [191] Received DOM event beforeunload for about:blank
[task 2019-09-20T04:19:49.662Z] 04:19:49 INFO - 1568953189651 Marionette TRACE [191] Received DOM event pagehide for about:blank
[task 2019-09-20T04:19:49.711Z] 04:19:49 INFO - 1568953189705 Marionette TRACE [191] Received DOM event DOMContentLoaded for http://127.0.0.1:50109/test.html
[task 2019-09-20T04:19:49.722Z] 04:19:49 INFO - 1568953189714 Marionette TRACE [191] Received DOM event pageshow for http://127.0.0.1:50109/test.html
[task 2019-09-20T04:19:49.722Z] 04:19:49 INFO - 1568953189718 Marionette DEBUG 495 <- [1,16,null,{"value":null}]
[task 2019-09-20T04:19:49.734Z] 04:19:49 INFO - 1568953189721 Marionette DEBUG 495 -> [0,17,"WebDriver:GetCurrentURL",{}]
[task 2019-09-20T04:19:49.734Z] 04:19:49 INFO - 1568953189721 Marionette DEBUG 495 <- [1,17,null,{"value":"http://127.0.0.1:50109/test.html"}]
[task 2019-09-20T04:19:49.741Z] 04:19:49 INFO - 1568953189739 Marionette DEBUG 495 -> [0,18,"WebDriver:ExecuteScript",{"script":"return window.history.length;","newSandbox":true,"args":[],"filename":"tests/testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py","sandbox":"default","line":73}]
[task 2019-09-20T04:19:49.773Z] 04:19:49 INFO - 1568953189752 Marionette DEBUG 495 <- [1,18,null,{"value":1}]
[task 2019-09-20T04:19:49.773Z] 04:19:49 INFO - 1568953189753 Marionette DEBUG 495 -> [0,19,"WebDriver:Navigate",{"url":"file:////Users/cltbld/tasks/task_1568953020/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/data/test.html"}]
[task 2019-09-20T04:19:49.773Z] 04:19:49 INFO - 1568953189756 Marionette TRACE [191] Received DOM event beforeunload for http://127.0.0.1:50109/test.html
[task 2019-09-20T04:19:49.773Z] 04:19:49 INFO - 1568953189762 Marionette TRACE [191] Received DOM event pagehide for http://127.0.0.1:50109/test.html
[task 2019-09-20T04:19:49.774Z] 04:19:49 INFO - 1568953189763 Marionette TRACE [191] Received DOM event unload for http://127.0.0.1:50109/test.html
[task 2019-09-20T04:19:49.774Z] 04:19:49 INFO - 1568953189765 Marionette TRACE [191] Received observer notification outer-window-destroyed
[task 2019-09-20T04:19:50.356Z] 04:19:50 INFO - 1568953190350 Marionette TRACE [193] Frame script loaded
[task 2019-09-20T04:19:50.356Z] 04:19:50 INFO - 1568953190351 Marionette TRACE [193] Frame script registered
[task 2019-09-20T04:19:50.380Z] 04:19:50 INFO - 1568953190364 Marionette TRACE [193] Check readyState uninitialized for about:blank
[task 2019-09-20T04:19:50.441Z] 04:19:50 INFO - 1568953190412 Marionette TRACE [193] Received DOM event DOMContentLoaded for file:////Users/cltbld/tasks/task_1568953020/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/data/test.html
[task 2019-09-20T04:19:50.441Z] 04:19:50 INFO - 1568953190418 Marionette TRACE [193] Received DOM event pageshow for file:////Users/cltbld/tasks/task_1568953020/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/data/test.html
[task 2019-09-20T04:19:50.442Z] 04:19:50 INFO - 1568953190419 Marionette DEBUG 495 <- [1,19,null,{"value":null}]
[task 2019-09-20T04:19:50.442Z] 04:19:50 INFO - 1568953190421 Marionette DEBUG 495 -> [0,20,"WebDriver:GetCurrentURL",{}]
[task 2019-09-20T04:19:50.442Z] 04:19:50 INFO - 1568953190422 Marionette DEBUG 495 <- [1,20,null,{"value":"file:////Users/cltbld/tasks/task_1568953020/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/data/test.html"}]
[task 2019-09-20T04:19:50.442Z] 04:19:50 INFO - 1568953190428 Marionette DEBUG 495 -> [0,21,"WebDriver:ExecuteScript",{"script":"return window.history.length;","newSandbox":true,"args":[],"filename":"tests/testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py","sandbox":"default","line":73}]
[task 2019-09-20T04:19:50.442Z] 04:19:50 INFO - 1568953190433 Marionette DEBUG 495 <- [1,21,null,{"value":2}]
[task 2019-09-20T04:19:50.443Z] 04:19:50 INFO - 1568953190434 Marionette DEBUG 495 -> [0,22,"WebDriver:Navigate",{"url":"http://127.0.0.1:50109/test.html"}]
[task 2019-09-20T04:19:50.443Z] 04:19:50 INFO - 1568953190437 Marionette TRACE [193] Received DOM event beforeunload for file:////Users/cltbld/tasks/task_1568953020/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/data/test.html
[task 2019-09-20T04:19:50.464Z] 04:19:50 INFO - 1568953190453 Marionette TRACE [193] Received DOM event pagehide for file:////Users/cltbld/tasks/task_1568953020/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/data/test.html
[task 2019-09-20T04:19:50.464Z] 04:19:50 INFO - 1568953190454 Marionette TRACE [193] Received DOM event unload for file:////Users/cltbld/tasks/task_1568953020/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/data/test.html
[task 2019-09-20T04:19:50.464Z] 04:19:50 INFO - 1568953190461 Marionette TRACE [193] Received observer notification outer-window-destroyed
[task 2019-09-20T04:19:50.534Z] 04:19:50 INFO - 1568953190504 Marionette TRACE [195] Frame script loaded
[task 2019-09-20T04:19:50.534Z] 04:19:50 INFO - 1568953190504 Marionette TRACE [195] Frame script registered
[task 2019-09-20T04:19:50.534Z] 04:19:50 INFO - 1568953190515 Marionette TRACE [195] Check readyState uninitialized for about:blank
[task 2019-09-20T04:19:50.852Z] 04:19:50 INFO - Exiting due to channel error.
[task 2019-09-20T04:19:50.852Z] 04:19:50 INFO - [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2019-09-20T04:19:50.852Z] 04:19:50 INFO - Exiting due to channel error.
[task 2019-09-20T04:19:50.856Z] 04:19:50 INFO - Exiting due to channel error.
[task 2019-09-20T04:19:50.856Z] 04:19:50 INFO - Exiting due to channel error.
[task 2019-09-20T04:19:50.953Z] 04:19:50 INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/R0gaRRDzR72zgkv5PS4fcQ/artifacts/public/build/target.crashreporter-symbols.zip
[task 2019-09-20T04:20:37.439Z] 04:20:37 INFO - mozcrash Copy/paste: /Users/cltbld/tasks/task_1568953020/build/macosx64-minidump_stackwalk /var/folders/km/76_mcdh90_j2lhffkh723l2r000017/T/tmpIScy7K.mozrunner/minidumps/880B2F5D-E75C-40C4-AE99-AE092F647BE0.dmp /var/folders/km/76_mcdh90_j2lhffkh723l2r000017/T/tmpC1rRBQ
[task 2019-09-20T04:20:43.099Z] 04:20:43 INFO - mozcrash Saved minidump as /Users/cltbld/tasks/task_1568953020/build/blobber_upload_dir/880B2F5D-E75C-40C4-AE99-AE092F647BE0.dmp
[task 2019-09-20T04:20:43.099Z] 04:20:43 INFO - mozcrash Saved app info as /Users/cltbld/tasks/task_1568953020/build/blobber_upload_dir/880B2F5D-E75C-40C4-AE99-AE092F647BE0.extra
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - PROCESS-CRASH | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_file_url | application crashed [@ mozilla::net::HttpBackgroundChannelParent::OnStartRequestSent()]
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - Crash dump filename: /var/folders/km/76_mcdh90_j2lhffkh723l2r000017/T/tmpIScy7K.mozrunner/minidumps/880B2F5D-E75C-40C4-AE99-AE092F647BE0.dmp
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - Operating system: Mac OS X
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - 10.14.5 18F132
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - CPU: amd64
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - family 6 model 69 stepping 1
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - 4 CPUs
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO -
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - GPU: UNKNOWN
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO -
[task 2019-09-20T04:20:43.229Z] 04:20:43 INFO - Crash reason: EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - Crash address: 0x38
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - Process uptime: 67 seconds
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO -
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - Thread 0 (crashed)
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - 0 XUL!mozilla::net::HttpBackgroundChannelParent::OnStartRequestSent() [HttpBackgroundChannelParent.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 149 + 0x1b]
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rax = 0x0000000115aab950 rdx = 0x0000000000000000
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rcx = 0x0000000000000000 rbx = 0x0000000000000038
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rsi = 0x00000000000120a8 rdi = 0x0000000000000000
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rbp = 0x00007ffeec601810 rsp = 0x00007ffeec6017e0
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - r8 = 0x00000000000130a8 r9 = 0x0000000118cfb298
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - r10 = 0x0000000000000000 r11 = 0x0000000118cfb290
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - r12 = 0x0000000000000000 r13 = 0x000000010366e130
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - r14 = 0x00007ffeec601ae8 r15 = 0x0000000118cfb000
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rip = 0x000000010687dc9d
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - Found by: given as instruction pointer in context
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - 1 XUL!mozilla::net::HttpChannelParent::OnStartRequest(nsIRequest*) [HttpChannelParent.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 1535 + 0xc]
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rbp = 0x00007ffeec601b70 rsp = 0x00007ffeec601820
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rip = 0x00000001068b26f2
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - 2 XUL!mozilla::net::nsHttpChannel::CallOnStartRequest() [nsHttpChannel.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 1864 + 0xe]
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rbp = 0x00007ffeec601d50 rsp = 0x00007ffeec601b80
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rip = 0x00000001068fd700
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - 3 XUL!mozilla::net::nsHttpChannel::ContinueProcessNormal(nsresult) [nsHttpChannel.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 3033 + 0x8]
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rbp = 0x00007ffeec601de0 rsp = 0x00007ffeec601d60
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rip = 0x0000000106904b17
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - 4 XUL!mozilla::net::nsHttpChannel::ContinueProcessResponse3(nsresult) [nsHttpChannel.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 0 + 0x8]
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rbp = 0x00007ffeec602030 rsp = 0x00007ffeec601df0
[task 2019-09-20T04:20:43.230Z] 04:20:43 INFO - rip = 0x0000000106901cad
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - 5 XUL!mozilla::net::nsHttpChannel::OnRedirectVerifyCallback(nsresult) [nsHttpChannel.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 9109 + 0x1a]
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rbp = 0x00007ffeec602090 rsp = 0x00007ffeec602040
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rip = 0x00000001069257ba
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - 6 XUL!mozilla::MozPromise<bool, nsresult, false>::ThenValue<mozilla::net::HttpChannelParent::CrossProcessRedirectDone(nsresult const&, mozilla::Maybe<mozilla::net::LoadInfoArgs> const&)::$_26, mozilla::net::HttpChannelParent::CrossProcessRedirectDone(nsresult const&, mozilla::Maybe<mozilla::net::LoadInfoArgs> const&)::$_27>::DoResolveOrRejectInternal(mozilla::MozPromise<bool, nsresult, false>::ResolveOrRejectValue&) [MozPromise.h:af61675dd48876bfc97d932f3bcafd6a108fae5c : 0 + 0x5]
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rbp = 0x00007ffeec6020c0 rsp = 0x00007ffeec6020a0
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rip = 0x00000001068e8903
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - 7 XUL!mozilla::MozPromise<bool, nsresult, false>::ThenValueBase::ResolveOrRejectRunnable::Run() [MozPromise.h:af61675dd48876bfc97d932f3bcafd6a108fae5c : 402 + 0x1b]
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rbp = 0x00007ffeec6020e0 rsp = 0x00007ffeec6020d0
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rip = 0x00000001068e68f1
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - 8 XUL!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 1225 + 0x6]
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rbp = 0x00007ffeec6025e0 rsp = 0x00007ffeec6020f0
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rip = 0x00000001063782f1
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - 9 XUL!NS_ProcessPendingEvents(nsIThread*, unsigned int) [nsThreadUtils.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 434 + 0xe]
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rbp = 0x00007ffeec602630 rsp = 0x00007ffeec6025f0
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rip = 0x0000000106375d62
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - 10 XUL!nsBaseAppShell::NativeEventCallback() [nsBaseAppShell.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 87 + 0x14]
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rbp = 0x00007ffeec602660 rsp = 0x00007ffeec602640
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rip = 0x00000001097975c7
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - 11 XUL!nsAppShell::ProcessGeckoEvents(void*) [nsAppShell.mm:af61675dd48876bfc97d932f3bcafd6a108fae5c : 440 + 0x8]
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rbp = 0x00007ffeec6026b0 rsp = 0x00007ffeec602670
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - rip = 0x000000010981e3fc
[task 2019-09-20T04:20:43.231Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - 12 CoreFoundation!-[_CFXNotificationRegistrar match:object:observer:enumerator:] + 0x816
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rbp = 0x00007ffeec6026c0 rsp = 0x00007ffeec6026c0
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rip = 0x00007fff47e5d083
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - 13 CoreFoundation!-[_CFXNotificationRegistrar match:object:observer:enumerator:] + 0x7bc
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rbp = 0x00007ffeec6026f0 rsp = 0x00007ffeec6026d0
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rip = 0x00007fff47e5d029
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - 14 CoreFoundation!__CFStringDecodeByteStream3 + 0x84b
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rbp = 0x00007ffeec602760 rsp = 0x00007ffeec602700
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rip = 0x00007fff47e409eb
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - 15 CoreFoundation!+[NSDate allocWithZone:] + 0x21
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rbp = 0x00007ffeec603470 rsp = 0x00007ffeec602770
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rip = 0x00007fff47e3ffb5
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - 16 CoreFoundation!__CFRunLoopRun + 0xb37
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rbp = 0x00007ffeec603500 rsp = 0x00007ffeec603480
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rip = 0x00007fff47e3f8be
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - 17 HIToolbox!RunCurrentEventLoopInMode + 0x124
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rbp = 0x00007ffeec603550 rsp = 0x00007ffeec603510
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rip = 0x00007fff4712b96b
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - 18 HIToolbox!ReceiveNextEventCommon + 0x25b
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rbp = 0x00007ffeec6035d0 rsp = 0x00007ffeec603560
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - rip = 0x00007fff4712b6a5
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.232Z] 04:20:43 INFO - 19 HIToolbox!_BlockUntilNextEventMatchingListInModeWithFilter + 0x40
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rbp = 0x00007ffeec6035f0 rsp = 0x00007ffeec6035e0
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rip = 0x00007fff4712b436
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - 20 AppKit!_DPSNextEvent + 0x3c5
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rbp = 0x00007ffeec603a00 rsp = 0x00007ffeec603600
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rip = 0x00007fff454c5987
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - 21 AppKit!-[NSApplication(NSEvent) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] + 0x551
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rbp = 0x00007ffeec603c80 rsp = 0x00007ffeec603a10
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rip = 0x00007fff454c471f
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - 22 XUL!-[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [nsAppShell.mm:af61675dd48876bfc97d932f3bcafd6a108fae5c : 169 + 0x2c]
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rbp = 0x00007ffeec603cf0 rsp = 0x00007ffeec603c90
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rip = 0x000000010981d451
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - 23 AppKit!-[NSApplication run] + 0x2bb
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rbp = 0x00007ffeec603dc0 rsp = 0x00007ffeec603d00
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rip = 0x00007fff454be83c
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - 24 XUL!nsAppShell::Run() [nsAppShell.mm:af61675dd48876bfc97d932f3bcafd6a108fae5c : 703 + 0x1a]
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rbp = 0x00007ffeec603e00 rsp = 0x00007ffeec603dd0
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rip = 0x000000010981ebe0
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - 25 XUL!nsAppStartup::Run() [nsAppStartup.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 276 + 0xa]
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rbp = 0x00007ffeec603e20 rsp = 0x00007ffeec603e10
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rip = 0x000000010ad1f75e
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - 26 XUL!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 4736 + 0xdb0]
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rbp = 0x00007ffeec603fa0 rsp = 0x00007ffeec603e30
[task 2019-09-20T04:20:43.233Z] 04:20:43 INFO - rip = 0x000000010ae76e75
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - 27 XUL!mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) [Bootstrap.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 45 + 0xe0]
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - rbp = 0x00007ffeec6040d0 rsp = 0x00007ffeec603fb0
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - rip = 0x000000010ae822dc
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - 28 firefox!main [nsBrowserApp.cpp:af61675dd48876bfc97d932f3bcafd6a108fae5c : 300 + 0x21b]
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - rbp = 0x00007ffeec604540 rsp = 0x00007ffeec6040e0
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - rip = 0x00000001035fbfde
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - 29 libdyld.dylib!start + 0x1
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - rbp = 0x00007ffeec604550 rsp = 0x00007ffeec604550
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - rip = 0x00007fff73d6a3d5
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO - Found by: previous frame's frame pointer
[task 2019-09-20T04:20:43.234Z] 04:20:43 INFO -

Another failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267577432&repo=autoland&lineNumber=5543

[task 2019-09-20T04:23:09.877Z] 04:23:09 INFO - TEST-START | dom/html/test/browser_form_post_from_file_to_http.js
[task 2019-09-20T04:23:10.933Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.973Z] 04:23:10 INFO - GECKO(1088) | [Child 10604, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.973Z] 04:23:10 INFO - GECKO(1088) | [Child 10604, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.975Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.985Z] 04:23:10 INFO - GECKO(1088) | [GPU 10684, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.985Z] 04:23:10 INFO - GECKO(1088) | [GPU 10684, Chrome_ChildThrea
[task 2019-09-20T04:23:10.985Z] 04:23:10 INFO - GECKO(1088) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-09-20T04:23:10.986Z] 04:23:10 INFO - GECKO(1088) | d] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.986Z] 04:23:10 INFO - GECKO(1088) | [GPU 10684, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.986Z] 04:23:10 INFO - GECKO(1088) | [GPU 10684, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.987Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.987Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:23:10.987Z] 04:23:10 INFO - GECKO(1088) | [Parent 2708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-20T04:24:39.918Z] 04:24:39 INFO - TEST-INFO | started process screenshot
[task 2019-09-20T04:24:40.124Z] 04:24:40 INFO - TEST-INFO | screenshot: exit 0
[task 2019-09-20T04:24:40.125Z] 04:24:40 INFO - Buffered messages logged at 04:23:09
[task 2019-09-20T04:24:40.125Z] 04:24:40 INFO - Entering test bound
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - Buffered messages finished
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/browser_form_post_from_file_to_http.js | Test timed out -
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - GECKO(1088) | MEMORY STAT | vsize 19406102MB | vsizeMaxContiguous 68972474MB | residentFast 1144MB
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - TEST-OK | dom/html/test/browser_form_post_from_file_to_http.js | took 90087ms
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/browser_form_post_from_file_to_http.js | Found a tab after previous test timed out: about:blank -
[task 2019-09-20T04:24:40.126Z] 04:24:40 INFO - checking window state

Pushed by jyavenard@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/58a8ed82ef82
P1. Only perform process switch once we have completed all redirects. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/f3083fdea17c
P2. Add nsIProcessSwitchRequestor interface. r=mayhemer,nika
https://hg.mozilla.org/integration/autoland/rev/8cf4aecce18e
P3. Make ContentProcessIdPromise exclusive. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/1854d7cf1332
P4. Add MozPromise::FromDomPromise. r=bholley
https://hg.mozilla.org/integration/autoland/rev/e0b4ddb51578
P5. Implement process switch from DocumentChannelParent. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/e404bd8b47ff
P6. Remove unused nsIProcessSwitchRequestor implementation. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/2d175e2bf5b8
P7. Promisify SendCrossProcessRedirect to avoid needing PHttpChannel to get a response. r=mayhemer
https://hg.mozilla.org/integration/autoland/rev/772f7e11c7e5
P8. Remove no longer necessary interface. r=mayhemer
Regressions: 1583018
Flags: needinfo?(jyavenard)

Retroactively moving fixed bugs whose summaries mention "Fission" (or other Fission-related keywords) but are not assigned to a Fission Milestone to an appropriate Fission Milestone.

This will generate a lot of bugmail, so you can filter your bugmail for the following UUID and delete them en masse:

0ee3c76a-bc79-4eb2-8d12-05dc0b68e732

Fission Milestone: --- → M4
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: