Closed Bug 1582568 Opened 5 years ago Closed 4 years ago

Intermittent GECKO(1150) | Assertion failure: !JS_IsDeadWrapper(js::UncheckedUnwrap(&obj.toObject())), at /builds/worker/workspace/build/src/js/xpconnect/src/XPCComponents.cpp:1911

Categories

(Core :: DOM: Window and Location, defect, P3)

defect

Tracking

()

RESOLVED FIXED
Tracking Status
firefox-esr68 --- unaffected
firefox74 --- wontfix
firefox75 --- wontfix
firefox76 --- fixed

People

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

References

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell disabled] [fission-] [stockwell needswork:owner])

Attachments

(2 files)

Filed by: rgurzau [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=267478297&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/RV7-SpHdRIGquOtSQU0btw/runs/0/artifacts/public/logs/live_backing.log


[task 2019-09-19T19:31:17.416Z] 19:31:17 INFO - TEST-START | dom/tests/browser/browser_windowProxy_transplant.js
[task 2019-09-19T19:31:17.416Z] 19:31:17 INFO - GECKO(1150) | Chrome file doesn't exist: /builds/worker/workspace/build/tests/mochitest/browser/dom/tests/browser/head.js
[task 2019-09-19T19:31:17.431Z] 19:31:17 INFO - GECKO(1150) | ++DOCSHELL 0x7f83e87b7800 == 22 [pid = 1150] [id = {a09cd184-2064-4d8f-9c9c-84b8ad342aaa}]
[task 2019-09-19T19:31:17.433Z] 19:31:17 INFO - GECKO(1150) | ++DOMWINDOW == 221 (0x7f840c126f20) [pid = 1150] [serial = 603] [outer = (nil)]
[task 2019-09-19T19:31:17.434Z] 19:31:17 INFO - GECKO(1150) | ++DOMWINDOW == 222 (0x7f83ea23cc00) [pid = 1150] [serial = 604] [outer = 0x7f840c126f20]
[task 2019-09-19T19:31:17.450Z] 19:31:17 INFO - GECKO(1150) | [Parent 1150, Main Thread] WARNING: Attempting to get a displayport from a content with no primary frame!: file /builds/worker/workspace/build/src/layout/base/nsLayoutUtils.cpp, line 767
[task 2019-09-19T19:31:17.450Z] 19:31:17 INFO - GECKO(1150) | [Parent 1150, 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-19T19:31:17.551Z] 19:31:17 INFO - GECKO(1150) | ++DOCSHELL 0x7f83e9f14800 == 23 [pid = 1150] [id = {64a1da29-2022-4f23-95b4-8fe55925e3e4}]
[task 2019-09-19T19:31:17.551Z] 19:31:17 INFO - GECKO(1150) | ++DOMWINDOW == 223 (0x7f8410f02200) [pid = 1150] [serial = 605] [outer = (nil)]
[task 2019-09-19T19:31:17.612Z] 19:31:17 INFO - GECKO(1150) | ++DOCSHELL 0x7f83f1259000 == 24 [pid = 1150] [id = {f42ded01-820f-45aa-a6b2-02f0adf28bb6}]
[task 2019-09-19T19:31:17.613Z] 19:31:17 INFO - GECKO(1150) | ++DOMWINDOW == 224 (0x7f8410f02b60) [pid = 1150] [serial = 606] [outer = (nil)]
[task 2019-09-19T19:31:17.716Z] 19:31:17 INFO - GECKO(1150) | ++DOCSHELL 0x7f6ed04a2000 == 4 [pid = 1705] [id = {26e4c4f6-1839-4c77-ae0c-a86072d92dfa}]
[task 2019-09-19T19:31:17.717Z] 19:31:17 INFO - GECKO(1150) | ++DOMWINDOW == 8 (0x7f6ecfe492e0) [pid = 1705] [serial = 345] [outer = (nil)]
[task 2019-09-19T19:31:17.717Z] 19:31:17 INFO - GECKO(1150) | ++DOMWINDOW == 9 (0x7f6ecfe4fc00) [pid = 1705] [serial = 346] [outer = 0x7f6ecfe492e0]
[task 2019-09-19T19:31:17.857Z] 19:31:17 INFO - GECKO(1150) | ++DOMWINDOW == 225 (0x7f83f11b3400) [pid = 1150] [serial = 607] [outer = 0x7f8410f02200]
[task 2019-09-19T19:31:17.861Z] 19:31:17 INFO - GECKO(1150) | ++DOMWINDOW == 226 (0x7f83f11b6000) [pid = 1150] [serial = 608] [outer = 0x7f8410f02b60]
[task 2019-09-19T19:31:18.026Z] 19:31:18 INFO - GECKO(1150) | ++DOMWINDOW == 10 (0x7f6ecfe93c00) [pid = 1705] [serial = 347] [outer = 0x7f6ecfe492e0]
[task 2019-09-19T19:31:18.089Z] 19:31:18 INFO - GECKO(1150) | [Parent 1150, Main Thread] WARNING: Ignoring duplicate observer.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2696
[task 2019-09-19T19:31:18.693Z] 19:31:18 INFO - GECKO(1150) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpzjetN9.mozrunner/runtests_leaks_tab_pid1925.log
[task 2019-09-19T19:31:18.841Z] 19:31:18 INFO - GECKO(1150) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-09-19T19:31:18.949Z] 19:31:18 INFO - GECKO(1150) | --DOCSHELL 0x7f83e73b3000 == 23 [pid = 1150] [id = {701f2b68-b385-45ab-94c5-133340140f4b}] [url = about:blank]
[task 2019-09-19T19:31:19.009Z] 19:31:19 INFO - GECKO(1150) | [2019-09-19T19:31:19Z WARN audio_thread_priority::rt_linux] Could not make thread real-time.
[task 2019-09-19T19:31:19.010Z] 19:31:19 INFO - GECKO(1150) | [2019-09-19T19:31:19Z WARN audioipc_client] Could not promote audio threads to real-time during initialization.
[task 2019-09-19T19:31:19.025Z] 19:31:19 INFO - GECKO(1150) | ++DOCSHELL 0x7ffb121b8000 == 1 [pid = 1925] [id = {4823e8e3-b9e3-4a79-9e44-5d45d5f07e11}]
[task 2019-09-19T19:31:19.086Z] 19:31:19 INFO - GECKO(1150) | ++DOMWINDOW == 1 (0x7ffb130c5f20) [pid = 1925] [serial = 1] [outer = (nil)]
[task 2019-09-19T19:31:19.086Z] 19:31:19 INFO - GECKO(1150) | ++DOMWINDOW == 2 (0x7ffb12124800) [pid = 1925] [serial = 2] [outer = 0x7ffb130c5f20]
[task 2019-09-19T19:31:19.307Z] 19:31:19 INFO - GECKO(1150) | ++DOMWINDOW == 3 (0x7ffb1300c000) [pid = 1925] [serial = 3] [outer = 0x7ffb130c5f20]
[task 2019-09-19T19:31:19.348Z] 19:31:19 INFO - GECKO(1150) | Content running top level script http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-09-19T19:31:19.368Z] 19:31:19 INFO - GECKO(1150) | Content got load of http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-09-19T19:31:19.384Z] 19:31:19 INFO - GECKO(1150) | Content got event http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html {"event":"load","location":"http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html"}
[task 2019-09-19T19:31:19.460Z] 19:31:19 INFO - GECKO(1150) | ++DOCSHELL 0x7ffb1302d000 == 2 [pid = 1925] [id = {89716013-e709-4430-acd0-abbc5646873f}]
[task 2019-09-19T19:31:19.461Z] 19:31:19 INFO - GECKO(1150) | ++DOMWINDOW == 4 (0x7ffb130c6100) [pid = 1925] [serial = 4] [outer = (nil)]
[task 2019-09-19T19:31:19.462Z] 19:31:19 INFO - GECKO(1150) | ++DOMWINDOW == 5 (0x7ffb13006400) [pid = 1925] [serial = 5] [outer = 0x7ffb130c6100]
[task 2019-09-19T19:31:19.666Z] 19:31:19 INFO - GECKO(1150) | Content running top level script http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-09-19T19:31:19.681Z] 19:31:19 INFO - GECKO(1150) | Content got load of http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-09-19T19:31:19.866Z] 19:31:19 INFO - GECKO(1150) | Content got event http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html {"event":"load","location":"http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html"}
[task 2019-09-19T19:31:19.867Z] 19:31:19 INFO - GECKO(1150) | Content got event http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html {"action":"navigate","location":"http://example.com/browser/dom/tests/browser/file_postMessage_parent.html"}
[task 2019-09-19T19:31:20.039Z] 19:31:20 INFO - GECKO(1150) | [Child 1925, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-09-19T19:31:20.160Z] 19:31:20 INFO - GECKO(1150) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpzjetN9.mozrunner/runtests_leaks_tab_pid1951.log
[task 2019-09-19T19:31:20.301Z] 19:31:20 INFO - GECKO(1150) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-09-19T19:31:20.459Z] 19:31:20 INFO - GECKO(1150) | [2019-09-19T19:31:20Z WARN audio_thread_priority::rt_linux] Could not make thread real-time.
[task 2019-09-19T19:31:20.459Z] 19:31:20 INFO - GECKO(1150) | [2019-09-19T19:31:20Z WARN audioipc_client] Could not promote audio threads to real-time during initialization.
[task 2019-09-19T19:31:20.479Z] 19:31:20 INFO - GECKO(1150) | ++DOCSHELL 0x7ff158cb7000 == 1 [pid = 1951] [id = {68be3213-6fd3-426b-9929-86cf82a02900}]
[task 2019-09-19T19:31:20.535Z] 19:31:20 INFO - GECKO(1150) | ++DOMWINDOW == 1 (0x7ff159dc2f20) [pid = 1951] [serial = 1] [outer = (nil)]
[task 2019-09-19T19:31:20.537Z] 19:31:20 INFO - GECKO(1150) | ++DOMWINDOW == 2 (0x7ff158c23000) [pid = 1951] [serial = 2] [outer = 0x7ff159dc2f20]
[task 2019-09-19T19:31:20.626Z] 19:31:20 INFO - GECKO(1150) | ++DOMWINDOW == 3 (0x7ff1594b1800) [pid = 1951] [serial = 3] [outer = 0x7ff159dc2f20]
[task 2019-09-19T19:31:20.654Z] 19:31:20 INFO - GECKO(1150) | Content running top level script http://example.com/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-09-19T19:31:20.669Z] 19:31:20 INFO - GECKO(1150) | Content got load of http://example.com/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-09-19T19:31:20.747Z] 19:31:20 INFO - GECKO(1150) | Assertion failure: !JS_IsDeadWrapper(js::UncheckedUnwrap(&obj.toObject())), at /builds/worker/workspace/build/src/js/xpconnect/src/XPCComponents.cpp:1911
[task 2019-09-19T19:31:20.747Z] 19:31:20 INFO - GECKO(1150) | #01: NS_InvokeByIndex
[task 2019-09-19T19:31:20.748Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.748Z] 19:31:20 INFO - GECKO(1150) | #02: CallMethodHelper::Call() [js/xpconnect/src/XPCWrappedNative.cpp:1187]
[task 2019-09-19T19:31:20.748Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.748Z] 19:31:20 INFO - GECKO(1150) | #03: XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) [js/xpconnect/src/XPCWrappedNative.cpp:1149]
[task 2019-09-19T19:31:20.749Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.749Z] 19:31:20 INFO - GECKO(1150) | #04: XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) [js/xpconnect/src/XPCWrappedNativeJSOps.cpp:943]
[task 2019-09-19T19:31:20.750Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.751Z] 19:31:20 INFO - GECKO(1150) | #05: CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [js/src/vm/Interpreter.cpp:458]
[task 2019-09-19T19:31:20.751Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.751Z] 19:31:20 INFO - GECKO(1150) | #06: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:551]
[task 2019-09-19T19:31:20.752Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.752Z] 19:31:20 INFO - GECKO(1150) | #07: Interpret(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:0]
[task 2019-09-19T19:31:20.753Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.753Z] 19:31:20 INFO - GECKO(1150) | #08: js::RunScript(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:424]
[task 2019-09-19T19:31:20.753Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.754Z] 19:31:20 INFO - GECKO(1150) | #09: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:592]
[task 2019-09-19T19:31:20.754Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.773Z] 19:31:20 INFO - GECKO(1150) | #10: <name omitted> [js/src/vm/Interpreter.cpp:637]
[task 2019-09-19T19:31:20.773Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.773Z] 19:31:20 INFO - GECKO(1150) | #11: js::CallSelfHostedFunction(JSContext*, JS::Handle<js::PropertyName*>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>) [js/src/vm/SelfHosting.cpp:1659]
[task 2019-09-19T19:31:20.773Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.774Z] 19:31:20 INFO - GECKO(1150) | #12: AsyncFunctionResume(JSContext*, JS::Handle<js::AsyncFunctionGeneratorObject*>, ResumeKind, JS::Handle<JS::Value>) [js/src/vm/AsyncFunction.cpp:116]
[task 2019-09-19T19:31:20.774Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.774Z] 19:31:20 INFO - GECKO(1150) | #13: PromiseReactionJob(JSContext*, unsigned int, JS::Value*) [js/src/builtin/Promise.cpp:1655]
[task 2019-09-19T19:31:20.774Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.774Z] 19:31:20 INFO - GECKO(1150) | #14: CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [js/src/vm/Interpreter.cpp:458]
[task 2019-09-19T19:31:20.774Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO - GECKO(1150) | #15: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:551]
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO - GECKO(1150) | #16: <name omitted> [js/src/vm/Interpreter.cpp:637]
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO - GECKO(1150) | #17: JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) [js/src/jsapi.cpp:2723]
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO - GECKO(1150) | #18: mozilla::dom::PromiseJobCallback::Call(JSContext*, JS::Handle<JS::Value>, mozilla::ErrorResult&) [s3:gecko-generated-sources:459bd68f053d4775f5b0b7c60fdedb096097672d24585038b4bb3482166c8e296d4679eb046b8f15653c228c4c1978ecacfffce20fced70b78964dafea2eee2d/dom/bindings/PromiseBinding.cpp::26]
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO -
[task 2019-09-19T19:31:20.775Z] 19:31:20 INFO - GECKO(1150) | #19: mozilla::dom::PromiseJobCa

Component: DOM: Core & HTML → XPConnect

The priority flag is not set for this bug.
:peterv, could you have a look please?

For more information, please visit auto_nag documentation.

Flags: needinfo?(peterv)
Flags: needinfo?(peterv)
Priority: -- → P3

There are 30 total failures in the last 7 days and 95 total failures in the last 30 on linux64 and macosx1014-64 debug.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=280018046&repo=autoland&lineNumber=21280

[task 2019-12-06T14:56:47.018Z] 14:56:47 INFO - TEST-START | dom/tests/browser/browser_windowProxy_transplant.js
[task 2019-12-06T14:56:47.019Z] 14:56:47 INFO - GECKO(4898) | Chrome file doesn't exist: /builds/worker/workspace/build/tests/mochitest/browser/dom/tests/browser/head.js
[task 2019-12-06T14:56:47.055Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd38406d800 == 18 [pid = 4898] [id = {2f7bc6b7-53fc-4b81-8302-50a0b0348fae}]
[task 2019-12-06T14:56:47.056Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 215 (0x7fd38bd96980) [pid = 4898] [serial = 413] [outer = (nil)]
[task 2019-12-06T14:56:47.056Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 216 (0x7fd384c58400) [pid = 4898] [serial = 414] [outer = 0x7fd38bd96980]
[task 2019-12-06T14:56:47.072Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898, Main Thread] WARNING: Attempting to get a displayport from a content with no primary frame!: file /builds/worker/workspace/build/src/layout/base/nsLayoutUtils.cpp, line 790
[task 2019-12-06T14:56:47.233Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd384e22000 == 19 [pid = 4898] [id = {e8f357ea-8f48-407a-b693-1b2a0759e28f}]
[task 2019-12-06T14:56:47.234Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 217 (0x7fd38dcbcf20) [pid = 4898] [serial = 415] [outer = (nil)]
[task 2019-12-06T14:56:47.353Z] 14:56:47 INFO - GECKO(4898) | [Child 5057: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f26a9731000 == 4 [pid = 5057] [id = {1490a76b-b839-4c71-b0e8-b1e90826796a}]
[task 2019-12-06T14:56:47.353Z] 14:56:47 INFO - GECKO(4898) | [Child 5057: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x7f26a98967a0) [pid = 5057] [serial = 104] [outer = (nil)]
[task 2019-12-06T14:56:47.353Z] 14:56:47 INFO - GECKO(4898) | [Child 5057: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0x7f26a97b5800) [pid = 5057] [serial = 105] [outer = 0x7f26a98967a0]
[task 2019-12-06T14:56:47.413Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 218 (0x7fd38ddd1000) [pid = 4898] [serial = 416] [outer = 0x7fd38dcbcf20]
[task 2019-12-06T14:56:47.429Z] 14:56:47 INFO - GECKO(4898) | [Child 5742: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fcd6d804800) [pid = 5742] [serial = 52] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.430Z] 14:56:47 INFO - GECKO(4898) | [Child 5742: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7fcd6d807800) [pid = 5742] [serial = 58] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.446Z] 14:56:47 INFO - GECKO(4898) | [Child 5742: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fcd6e29c800 == 1 [pid = 5742] [id = {2f60f461-d122-473b-ad9b-cef180176f7c}] [url = about:blank]
[task 2019-12-06T14:56:47.527Z] 14:56:47 INFO - GECKO(4898) | [Child 5057: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0x7f26a9c82400) [pid = 5057] [serial = 106] [outer = 0x7f26a98967a0]
[task 2019-12-06T14:56:47.590Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898, Main Thread] WARNING: Ignoring duplicate observer.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2692
[task 2019-12-06T14:56:47.641Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 217 (0x7fd39f453e20) [pid = 4898] [serial = 381] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.641Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 216 (0x7fd38dcbdc40) [pid = 4898] [serial = 377] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.642Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 215 (0x7fd38dcbd4c0) [pid = 4898] [serial = 373] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.642Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 214 (0x7fd38dcbcb60) [pid = 4898] [serial = 369] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.643Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 213 (0x7fd3b19c6100) [pid = 4898] [serial = 365] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.643Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 212 (0x7fd3aac366a0) [pid = 4898] [serial = 361] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.644Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 211 (0x7fd39f4525c0) [pid = 4898] [serial = 337] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.644Z] 14:56:47 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 210 (0x7fd3e37f9200) [pid = 4898] [serial = 385] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.659Z] 14:56:47 INFO - GECKO(4898) | [Child 5742: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7fcd6e202b60) [pid = 5742] [serial = 55] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.663Z] 14:56:47 INFO - GECKO(4898) | [Child 5742: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7fcd6e2027a0) [pid = 5742] [serial = 53] [outer = (nil)] [url = about:blank]
[task 2019-12-06T14:56:47.810Z] 14:56:47 INFO - GECKO(4898) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpPbwPZw.mozrunner/runtests_leaks_tab_pid8125.log
[task 2019-12-06T14:56:47.838Z] 14:56:47 INFO - GECKO(4898) | [Child 8125, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 727
[task 2019-12-06T14:56:47.919Z] 14:56:47 INFO - GECKO(4898) | [Child 5057: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f26a9734000 == 5 [pid = 5057] [id = {bfe12a9b-f4ec-4f99-9c1c-e664c23c954a}]
[task 2019-12-06T14:56:47.920Z] 14:56:47 INFO - GECKO(4898) | [Child 5057: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7f26a9896980) [pid = 5057] [serial = 107] [outer = (nil)]
[task 2019-12-06T14:56:47.921Z] 14:56:47 INFO - GECKO(4898) | [Child 5057: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7f26a97b7000) [pid = 5057] [serial = 108] [outer = 0x7f26a9896980]
[task 2019-12-06T14:56:47.998Z] 14:56:47 INFO - GECKO(4898) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-12-06T14:56:48.000Z] 14:56:48 INFO - GECKO(4898) | [Child 5057: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (0x7f26a9c89800) [pid = 5057] [serial = 109] [outer = 0x7f26a9896980]
[task 2019-12-06T14:56:48.121Z] 14:56:48 INFO - GECKO(4898) | [Child 8125, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1793
[task 2019-12-06T14:56:48.137Z] 14:56:48 INFO - GECKO(4898) | [Child 8125: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fe4bc7d2000 == 1 [pid = 8125] [id = {1490a76b-b839-4c71-b0e8-b1e90826796a}]
[task 2019-12-06T14:56:48.185Z] 14:56:48 INFO - GECKO(4898) | [Child 8125: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7fe4bd7daf20) [pid = 8125] [serial = 1] [outer = (nil)]
[task 2019-12-06T14:56:48.186Z] 14:56:48 INFO - GECKO(4898) | [Child 8125, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 847
[task 2019-12-06T14:56:48.186Z] 14:56:48 INFO - GECKO(4898) | [Child 8125: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7fe4bc36e000) [pid = 8125] [serial = 2] [outer = 0x7fe4bd7daf20]
[task 2019-12-06T14:56:48.307Z] 14:56:48 INFO - GECKO(4898) | [Parent 4898, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-06T14:56:48.335Z] 14:56:48 INFO - GECKO(4898) | [Child 8125: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fe4bc379000) [pid = 8125] [serial = 3] [outer = 0x7fe4bd7daf20]
[task 2019-12-06T14:56:48.355Z] 14:56:48 INFO - GECKO(4898) | Content running top level script http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-12-06T14:56:48.378Z] 14:56:48 INFO - GECKO(4898) | Content got load of http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-12-06T14:56:48.394Z] 14:56:48 INFO - GECKO(4898) | Content got event http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html {"event":"load","location":"http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html"}
[task 2019-12-06T14:56:48.430Z] 14:56:48 INFO - GECKO(4898) | [Child 8125: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fe4bc3ba000 == 2 [pid = 8125] [id = {fee0a7a8-bb1f-4b7b-b6d5-be202fb5566f}]
[task 2019-12-06T14:56:48.430Z] 14:56:48 INFO - GECKO(4898) | [Child 8125: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7fe4bd7da020) [pid = 8125] [serial = 4] [outer = (nil)]
[task 2019-12-06T14:56:48.430Z] 14:56:48 INFO - GECKO(4898) | [Child 8125: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7fe4bd4d5400) [pid = 8125] [serial = 5] [outer = 0x7fe4bd7da020]
[task 2019-12-06T14:56:48.486Z] 14:56:48 INFO - GECKO(4898) | [Parent 4898, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-06T14:56:48.487Z] 14:56:48 INFO - GECKO(4898) | Content running top level script http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-12-06T14:56:48.508Z] 14:56:48 INFO - GECKO(4898) | Content got load of http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-12-06T14:56:48.624Z] 14:56:48 INFO - GECKO(4898) | Content got event http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html {"event":"load","location":"http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html"}
[task 2019-12-06T14:56:48.625Z] 14:56:48 INFO - GECKO(4898) | Content got event http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html {"action":"navigate","location":"http://example.com/browser/dom/tests/browser/file_postMessage_parent.html"}
[task 2019-12-06T14:56:48.783Z] 14:56:48 INFO - GECKO(4898) | [Child 8125, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-06T14:56:48.784Z] 14:56:48 INFO - GECKO(4898) | [Child 8125, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-06T14:56:48.784Z] 14:56:48 INFO - GECKO(4898) | [Child 8125, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 574
[task 2019-12-06T14:56:48.851Z] 14:56:48 INFO - GECKO(4898) | [Parent 4898, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-06T14:56:48.875Z] 14:56:48 INFO - GECKO(4898) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpPbwPZw.mozrunner/runtests_leaks_tab_pid8155.log
[task 2019-12-06T14:56:48.895Z] 14:56:48 INFO - GECKO(4898) | [Child 8155, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 727
[task 2019-12-06T14:56:49.036Z] 14:56:49 INFO - GECKO(4898) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-12-06T14:56:49.273Z] 14:56:49 INFO - GECKO(4898) | [Child 8155, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1793
[task 2019-12-06T14:56:49.281Z] 14:56:49 INFO - GECKO(4898) | [Child 8155: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f3c7a8d2000 == 1 [pid = 8155] [id = {fee0a7a8-bb1f-4b7b-b6d5-be202fb5566f}]
[task 2019-12-06T14:56:49.337Z] 14:56:49 INFO - GECKO(4898) | [Child 8155: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f3c7b7f0f20) [pid = 8155] [serial = 1] [outer = (nil)]
[task 2019-12-06T14:56:49.338Z] 14:56:49 INFO - GECKO(4898) | [Child 8155, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 847
[task 2019-12-06T14:56:49.339Z] 14:56:49 INFO - GECKO(4898) | [Child 8155: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f3c7a875c00) [pid = 8155] [serial = 2] [outer = 0x7f3c7b7f0f20]
[task 2019-12-06T14:56:49.394Z] 14:56:49 INFO - GECKO(4898) | [Child 8155: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f3c7a87e000) [pid = 8155] [serial = 3] [outer = 0x7f3c7b7f0f20]
[task 2019-12-06T14:56:49.415Z] 14:56:49 INFO - GECKO(4898) | Content running top level script http://example.com/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-12-06T14:56:49.435Z] 14:56:49 INFO - GECKO(4898) | Content got load of http://example.com/browser/dom/tests/browser/file_postMessage_parent.html
[task 2019-12-06T14:56:49.437Z] 14:56:49 INFO - GECKO(4898) | Content got event http://mochi.test:8888/browser/dom/tests/browser/file_postMessage_parent.html {"event":"load","location":"http://example.com/browser/dom/tests/browser/file_postMessage_parent.html"}
[task 2019-12-06T14:56:49.453Z] 14:56:49 INFO - GECKO(4898) | Assertion failure: !JS_IsDeadWrapper(js::UncheckedUnwrap(&obj.toObject())), at /builds/worker/workspace/build/src/js/xpconnect/src/XPCComponents.cpp:1946
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #01: NS_InvokeByIndex
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #02: CallMethodHelper::Call() [js/xpconnect/src/XPCWrappedNative.cpp:1183]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #03: XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) [js/xpconnect/src/XPCWrappedNative.cpp:1149]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #04: XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) [js/xpconnect/src/XPCWrappedNativeJSOps.cpp:946]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #05: CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [js/src/vm/Interpreter.cpp:457]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #06: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:549]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #07: Interpret(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:0]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #08: js::RunScript(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:424]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #09: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:590]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #10: <name omitted> [js/src/vm/Interpreter.cpp:635]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO - GECKO(4898) | #11: js::CallSelfHostedFunction(JSContext*, JS::Handle<js::PropertyName*>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>) [js/src/vm/SelfHosting.cpp:1694]
[task 2019-12-06T14:56:49.454Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.462Z] 14:56:49 INFO - GECKO(4898) | #12: AsyncFunctionResume(JSContext*, JS::Handle<js::AsyncFunctionGeneratorObject*>, ResumeKind, JS::Handle<JS::Value>) [js/src/vm/AsyncFunction.cpp:125]
[task 2019-12-06T14:56:49.462Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.463Z] 14:56:49 INFO - GECKO(4898) | #13: PromiseReactionJob(JSContext*, unsigned int, JS::Value*) [js/src/builtin/Promise.cpp:1765]
[task 2019-12-06T14:56:49.463Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.463Z] 14:56:49 INFO - GECKO(4898) | #14: CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), js::CallReason, JS::CallArgs const&) [js/src/vm/Interpreter.cpp:457]
[task 2019-12-06T14:56:49.463Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.463Z] 14:56:49 INFO - GECKO(4898) | #15: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:549]
[task 2019-12-06T14:56:49.463Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.464Z] 14:56:49 INFO - GECKO(4898) | #16: <name omitted> [js/src/vm/Interpreter.cpp:635]
[task 2019-12-06T14:56:49.464Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.464Z] 14:56:49 INFO - GECKO(4898) | #17: JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) [js/src/jsapi.cpp:2756]
[task 2019-12-06T14:56:49.464Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.464Z] 14:56:49 INFO - GECKO(4898) | #18: mozilla::dom::PromiseJobCallback::Call(JSContext*, JS::Handle<JS::Value>, mozilla::ErrorResult&) [s3:gecko-generated-sources:459bd68f053d4775f5b0b7c60fdedb096097672d24585038b4bb3482166c8e296d4679eb046b8f15653c228c4c1978ecacfffce20fced70b78964dafea2eee2d/dom/bindings/PromiseBinding.cpp::26]
[task 2019-12-06T14:56:49.464Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.473Z] 14:56:49 INFO - GECKO(4898) | #19: mozilla::dom::PromiseJobCallback::Call(mozilla::ErrorResult&, char const*, mozilla::dom::CallbackObject::ExceptionHandling, JS::Realm*) [s3:gecko-generated-sources:8ff597fee16137a41c5c768d4731e63150ba2f32aaccb2a1985be307f0fad868e61bb63f08de085a377133bcfcb41787e072b5d1f0cda75a29d00492f676fce1/dist/include/mozilla/dom/PromiseBinding.h::91]
[task 2019-12-06T14:56:49.473Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.473Z] 14:56:49 INFO - GECKO(4898) | #20: mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) [xpcom/base/CycleCollectedJSContext.cpp:208]
[task 2019-12-06T14:56:49.473Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.473Z] 14:56:49 INFO - GECKO(4898) | #21: mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) [xpcom/base/CycleCollectedJSContext.cpp:626]
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO - GECKO(4898) | #22: mozilla::EventListenerManager::HandleEventSubType(mozilla::EventListenerManager::Listener*, mozilla::dom::Event*, mozilla::dom::EventTarget*) [dom/events/EventListenerManager.cpp:0]
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO - GECKO(4898) | #23: mozilla::EventListenerManager::HandleEventInternal(nsPresContext*, mozilla::WidgetEvent*, mozilla::dom::Event**, mozilla::dom::EventTarget*, nsEventStatus*, bool) [dom/events/EventListenerManager.cpp:1267]
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO - GECKO(4898) | #24: mozilla::EventTargetChainItem::HandleEvent(mozilla::EventChainPostVisitor&, mozilla::ELMCreationDetector&) [dom/events/EventDispatcher.cpp:359]
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO - GECKO(4898) | #25: mozilla::EventTargetChainItem::HandleEventTargetChain(nsTArray<mozilla::EventTargetChainItem>&, mozilla::EventChainPostVisitor&, mozilla::EventDispatchingCallback*, mozilla::ELMCreationDetector&) [dom/events/EventDispatcher.cpp:560]
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO - GECKO(4898) | #26: mozilla::EventDispatcher::Dispatch(nsISupports*, nsPresContext*, mozilla::WidgetEvent*, mozilla::dom::Event*, nsEventStatus*, mozilla::EventDispatchingCallback*, nsTArray<mozilla::dom::EventTarget*>) [dom/events/EventDispatcher.cpp:1056]
[task 2019-12-06T14:56:49.474Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.477Z] 14:56:49 INFO - GECKO(4898) | #27: mozilla::dom::BrowserBridgeChild::RecvMaybeFireEmbedderLoadEvents(bool, bool) [dom/ipc/BrowserBridgeChild.cpp:186]
[task 2019-12-06T14:56:49.477Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.480Z] 14:56:49 INFO - GECKO(4898) | #28: mozilla::dom::PBrowserBridgeChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:2b6c8bf409af3a0e85049b7afd3f4061de9a9c56d7443dce76a2e95185f4d43d763f4a8286e29afb0356753abe02d5da222c9fc54d2e38332eac8027561c827a/ipc/ipdl/PBrowserBridgeChild.cpp::618]
[task 2019-12-06T14:56:49.483Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.484Z] 14:56:49 INFO - GECKO(4898) | #29: mozilla::dom::PContentChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:ca29dfd07dfb05ea1012caeb36021064de1e1577c877a6a8a2c725a9a91638014a6f81b4ee69206685ca3ed3e42acded7801d07ba69d94da7b023b554e2984d1/ipc/ipdl/PContentChild.cpp::8173]
[task 2019-12-06T14:56:49.484Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.485Z] 14:56:49 INFO - GECKO(4898) | #30: mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy
, IPC::Message const&) [ipc/glue/MessageChannel.cpp:2210]
[task 2019-12-06T14:56:49.485Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.486Z] 14:56:49 INFO - GECKO(4898) | #31: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) [ipc/glue/MessageChannel.cpp:2134]
[task 2019-12-06T14:56:49.487Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.488Z] 14:56:49 INFO - GECKO(4898) | #32: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) [ipc/glue/MessageChannel.cpp:0]
[task 2019-12-06T14:56:49.488Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.489Z] 14:56:49 INFO - GECKO(4898) | #33: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:2006]
[task 2019-12-06T14:56:49.489Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.490Z] 14:56:49 INFO - GECKO(4898) | #34: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1250]
[task 2019-12-06T14:56:49.490Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.490Z] 14:56:49 INFO - GECKO(4898) | #35: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:486]
[task 2019-12-06T14:56:49.490Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.491Z] 14:56:49 INFO - GECKO(4898) | #36: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:87]
[task 2019-12-06T14:56:49.491Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.491Z] 14:56:49 INFO - GECKO(4898) | #37: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2019-12-06T14:56:49.491Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.492Z] 14:56:49 INFO - GECKO(4898) | #38: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
[task 2019-12-06T14:56:49.492Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.492Z] 14:56:49 INFO - GECKO(4898) | #39: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:941]
[task 2019-12-06T14:56:49.493Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.493Z] 14:56:49 INFO - GECKO(4898) | #40: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:237]
[task 2019-12-06T14:56:49.493Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.493Z] 14:56:49 INFO - GECKO(4898) | #41: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2019-12-06T14:56:49.494Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.494Z] 14:56:49 INFO - GECKO(4898) | #42: XRE_InitChildProcess(int, char**, XREChildData const*) [toolkit/xre/nsEmbedFunctions.cpp:776]
[task 2019-12-06T14:56:49.494Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.495Z] 14:56:49 INFO - GECKO(4898) | #43: content_process_main(mozilla::Bootstrap*, int, char**) [ipc/contentproc/plugin-container.cpp:57]
[task 2019-12-06T14:56:49.495Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.495Z] 14:56:49 INFO - GECKO(4898) | #44: main [browser/app/nsBrowserApp.cpp:302]
[task 2019-12-06T14:56:49.495Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.496Z] 14:56:49 INFO - GECKO(4898) | #45: libc.so.6 + 0x20830
[task 2019-12-06T14:56:49.496Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.496Z] 14:56:49 INFO - GECKO(4898) | #46: _start
[task 2019-12-06T14:56:49.496Z] 14:56:49 INFO -
[task 2019-12-06T14:56:49.564Z] 14:56:49 INFO - GECKO(4898) | [Parent 4898, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-12-06T14:56:49.565Z] 14:56:49 INFO - GECKO(4898) | [Parent 4898, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-12-06T14:56:49.607Z] 14:56:49 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd38406b000 == 20 [pid = 4898] [id = {339458fb-05b1-4830-9b75-698ac6cc2f38}]
[task 2019-12-06T14:56:49.608Z] 14:56:49 INFO - GECKO(4898) | [Parent 4898: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 211 (0x7fd38dcbdc40) [pid = 4898] [serial = 417] [outer = (nil)]

Peter can you assign someone to take a look?

Flags: needinfo?(peterv)
Whiteboard: [stockwell unknown] → [stockwell needswork:owner]

I've tried to reproduce this (also on try: https://treeherder.mozilla.org/#/jobs?repo=try&revision=9e6b7d5ac687400b4db760dfb900aaeb54ae19bb&selectedJob=280825329), but no luck so far. There's not much we can do, unless we get a recording or we can a reliable way to reproduce.

Assignee: nobody → peterv
Flags: needinfo?(peterv)
Flags: needinfo?(peterv)

Peter,
This has a high frequency failure rate, also seeing it in beta simulations, could you please take a look?

Flags: needinfo?(peterv)
Flags: needinfo?(peterv)

This is what entire history looks like: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-09-08&endday=2020-01-06&tree=trunk&bug=1582568

and last 30 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-12-07&endday=2020-01-06&tree=trunk&bug=1582568

Failure are on debug builds, macosx1014-64 and linux64.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=283567906&repo=autoland&lineNumber=22894

Assertion fails under dom/tests/browser/browser_windowProxy_transplant.js

Joel, based on the fact that there are 154 total failures in the last 30 days, shouldn't this have the disable-recommended tag?

Flags: needinfo?(jmaher)

I am confused here, the failure messages are:
00:38:08 INFO - GECKO(2077) | Assertion failure: !JS_IsDeadWrapper(js::UncheckedUnwrap(&obj.toObject())), at /builds/worker/workspace/build/src/js/xpconnect/src/XPCComponents.cpp:1947
23006 00:38:08 INFO - TEST-UNEXPECTED-FAIL | dom/tests/browser/browser_windowProxy_transplant.js | Uncaught exception - [Exception... "(null)" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "<unknown>" data: no]
23640 00:39:04 ERROR - TEST-UNEXPECTED-FAIL | dom/tests/browser/browser_windowProxy_transplant.js | leaked 1 window(s) until shutdown [url = about:blank]
23641 00:39:04 ERROR - TEST-UNEXPECTED-FAIL | dom/tests/browser/browser_windowProxy_transplant.js | leaked 1 window(s) until shutdown [url = chrome://browser/content/browser.xhtml]

but the title indicates just the assertion and this is in the wrong bugzilla component:
$ ./mach file-info bugzilla-component dom/tests/browser/browser_windowProxy_transplant.js
Core :: DOM: Core & HTML
dom/tests/browser/browser_windowProxy_transplant.js

given the failure history lets disable this specific test case, but we need to have the right component and needinfo flags in bugzilla first.

Flags: needinfo?(jmaher)

(In reply to Joel Maher ( :jmaher ) (UTC-4) from comment #30)

I am confused here, the failure messages are:
00:38:08 INFO - GECKO(2077) | Assertion failure: !JS_IsDeadWrapper(js::UncheckedUnwrap(&obj.toObject())), at /builds/worker/workspace/build/src/js/xpconnect/src/XPCComponents.cpp:1947
23006 00:38:08 INFO - TEST-UNEXPECTED-FAIL | dom/tests/browser/browser_windowProxy_transplant.js | Uncaught exception - [Exception... "(null)" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "<unknown>" data: no]
23640 00:39:04 ERROR - TEST-UNEXPECTED-FAIL | dom/tests/browser/browser_windowProxy_transplant.js | leaked 1 window(s) until shutdown [url = about:blank]
23641 00:39:04 ERROR - TEST-UNEXPECTED-FAIL | dom/tests/browser/browser_windowProxy_transplant.js | leaked 1 window(s) until shutdown [url = chrome://browser/content/browser.xhtml]

but the title indicates just the assertion and this is in the wrong bugzilla component:
$ ./mach file-info bugzilla-component dom/tests/browser/browser_windowProxy_transplant.js
Core :: DOM: Core & HTML
dom/tests/browser/browser_windowProxy_transplant.js

given the failure history lets disable this specific test case, but we need to have the right component and needinfo flags in bugzilla first.

We were informed when dealing with assertions to search for the component using the file in the assertion, meaning XPCComponents.cpp, that's why it's in this component. Also, the presumption was that the TEST-UNEXPECTED-FAIL were triggered by the assertion failure, reason why there isn't a separate bug for them or they are not mentioned.

ok, I do remember a bit of discussion about that, thanks for mentioning it. Can you needinfo the "Core :: DOM: Core & HTML" as well and then disable the test?

(In reply to Joel Maher ( :jmaher ) (UTC-4) from comment #32)

ok, I do remember a bit of discussion about that, thanks for mentioning it. Can you needinfo the "Core :: DOM: Core & HTML" as well and then disable the test?

Yes, thank you for your help here.

Component: XPConnect → DOM: Core & HTML

Hsin-Yi to put it shortly, the assertion here fails under dom/tests/browser/browser_windowProxy_transplant.js, test which is going to be disabled on macosx1014-64 and linux64 debug builds.

Can you please take a look or assign someone to take a look at this and re-enable when there is a fix?

Thank you.

Flags: needinfo?(peterv) → needinfo?(htsai)
Pushed by rmaries@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/c9161bb6f4ff
Disabled browser_windowProxy_transplant.js on Linux and Mac x64. r=jmaher
Keywords: leave-open
Whiteboard: [stockwell needswork:owner] → [stockwell disabled]

Hi Nike, this seems to be fall at your wheelhouse.

Component: DOM: Core & HTML → DOM: Window and Location
Flags: needinfo?(htsai) → needinfo?(nika)
Assignee: peterv → continuation
Flags: needinfo?(nika)
Depends on: 1609791
See Also: → 1581862

Andrew since you are the assignee here, are there any updates?

Flags: needinfo?(continuation)
Whiteboard: [stockwell disabled] → [stockwell disabled] -fission, [stockwell needswork:owner]

There are no updates. I've been looking at another intermittent failure.

Flags: needinfo?(continuation)

The good news here is that I can easily reproduce this locally on OSX with ./mach mochitest --verify --enable-fission --headless browser_windowProxy_transplant.js.

I think this is happening when we are checking "chromeWin1 shouldn't be a dead wrapper after navigation" by evaluating !Cu.isDeadWrapper(chromeWin1). The assertion is saying that if chromeWin1 is a CCW, then the thing it wraps shouldn't be a dead wrapper. I'll look at this more tomorrow.

Looks like it is actually chromeWin1x that it crashes on, which is Cu.waiveXrays(iframe.contentWindow);.

Whiteboard: [stockwell disabled] -fission, [stockwell needswork:owner] → [stockwell disabled] [fission-] [stockwell needswork:owner]

If my logging is correct, it looks like chromeWin1x starts out as CCW --> xray waiver --> window proxy, which is not really what I was expecting. I would have thought it would be xray waiver --> CCW --> window proxy. I guess I'll have to read over waiveXrays.

In the case where the assertion fires, it ends up as CCW --> DeadProxyObject. In the case where it doesn't, it ends up as DeadProxyObject. It looks like the initial state in both cases is the same, which makes sense. There must be some difference in how we nuke stuff. Maybe there's a nuking from the window close, and also from the transplant, and they can end up in different orders?

Boris, does having a CCW around an xray waiver make sense? It seems weird to me, but I don't know how else Cu.waiveXrays() could possibly work. Thanks.

Flags: needinfo?(bzbarsky)

Boris, does having a CCW around an xray waiver make sense?

Yes, that is exactly how it works. The idea is described in https://searchfox.org/mozilla-central/rev/d5b34cc8872177d3ee071e06f787c2a14268595b/js/xpconnect/wrappers/WrapperFactory.cpp#32-38 but in brief the XrayWaiver lives in the content compartment. When we go to create a cross-compartment wrapper, if our target is an XrayWaiver we use a transparent CCW, not an Xray. And when you get a prop off a waiver it wraps it in a waiver (which then, when going back into the system compartment, also gets a transparent CCW). If the waiver were on the other side of the compartment membrane, then a property get would return an Xray to the waiver. I suppose we could at that point manually unwrap, manually create a transparent CCW, etc, but that would be pretty complicated. And it would break the invariant that there is precisely one wrapper for a given object in a given compartment (because we'd need to have both Xray and CCW to the same target hanging around, right?).

Flags: needinfo?(bzbarsky)

I'm also a little confused about why this test was disabled across the board, not just in fission tests....

(In reply to Boris Zbarsky [:bzbarsky, bz on IRC] from comment #56)

I'm also a little confused about why this test was disabled across the board, not just in fission tests....

Please see Comment 29 and Comment 30 to get that clarified. Thanks.

(In reply to Boris Zbarsky [:bzbarsky, bz on IRC] from comment #56)

I'm also a little confused about why this test was disabled across the board, not just in fission tests....

This test is unusual in that it is testing Fission even when Fission isn't enabled. (It opens a window with Fission enabled.) I don't know if that still makes sense, but when it was initially landed we didn't have Fission-enabled tests running in automation yet.

NukeXrayWaiver(), which we call on the window proxy when we're navigating it to remote, looks up the xray waiver of the proxy and nukes it. So, it just makes sense that we end up in a bad state if the xray waiver has a CCW.

The question is, why does it mostly work? It looks like the difference is that in the failing case we don't run the WindowDestroyedEvent nuking (I'm not 100% sure why that is, but it involves a number of steps of runnables so I could imagine there's some kind of race in there). And, if I comment that nuking out, the test just always hits that assert. I suppose the dead object ends up in the same realm as the outer window, so when we nuke the realm then the CCW to the (former) xray waiver gets turned into a dead object.

I'll see if I can think of a way around this problem that doesn't require iterating over every compartment. Maybe it can get folded into TransplantObject.

Blocks: 1570487
Blocks: 1608600

ray waivers for remote window proxies don't make much sense, so we nuke any
waiver when a window proxy navigates from local to remote. However, this xray
waiver can be the target of a CCW, and dead wrappers are not supposed to the
target of a CCW, so we need to find and nuke any CCWs targetting the waiver.

This requires fixing js::RemapWrapper so that it can be used to fix CCWs to
dead wrappers.

Hopefully this will fix a bunch of intermittent failures in
browser_windowProxy_transplant.js.

Pushed by amccreight@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/3f7d9d65533a
Nuke CCWs for waivers for remote window proxies. r=peterv
Status: NEW → RESOLVED
Closed: 4 years ago
Keywords: leave-open
Resolution: --- → FIXED

It turned out this was just my fault.

No longer depends on: 1609791
Blocks: 1581862
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: