Closed Bug 1576413 Opened 5 years ago Closed 5 years ago

Intermittent GECKO(2381) | Assertion failure: !mOOPChildrenLoading.Contains(aChild), at /builds/worker/workspace/build/src/obj-firefox/dist/include/nsDocLoader.h:150

Categories

(Core :: DOM: Content Processes, defect, P5)

Product:
Core
Component:
DOM: Content Processes
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla70
Tracking Flags:
Tracking Status
firefox-esr60 --- unaffected
firefox-esr68 --- unaffected
firefox69 --- unaffected
firefox70 --- fixed

People

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

References

(Blocks 1 open bug, Regression)

Details

(Keywords: intermittent-failure, regression)

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=263312649&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/H_m5u_jUT8-pYTUoFy_IOQ/runs/0/artifacts/public/logs/live_backing.log

[task 2019-08-24T10:41:15.951Z] 10:41:15 INFO - GECKO(2381) | Assertion failure: !mOOPChildrenLoading.Contains(aChild), at /builds/worker/workspace/build/src/obj-firefox/dist/include/nsDocLoader.h:150
[task 2019-08-24T10:41:36.690Z] 10:41:36 INFO - GECKO(2381) | #01: mozilla::dom::ContentChild::CreateBrowser(nsFrameLoader*, mozilla::dom::TabContext const&, nsTString<char16_t> const&, mozilla::dom::BrowsingContext*) [dom/ipc/ContentChild.cpp:0]
[task 2019-08-24T10:41:36.690Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.690Z] 10:41:36 INFO - GECKO(2381) | #02: nsFrameLoader::TryRemoteBrowserInternal() [dom/base/nsFrameLoader.cpp:2640]
[task 2019-08-24T10:41:36.691Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.691Z] 10:41:36 INFO - GECKO(2381) | #03: nsFrameLoader::TryRemoteBrowser() [dom/base/nsFrameLoader.cpp:2709]
[task 2019-08-24T10:41:36.691Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.691Z] 10:41:36 INFO - GECKO(2381) | #04: nsFrameLoader::ReallyStartLoadingInternal() [dom/base/nsFrameLoader.cpp:554]
[task 2019-08-24T10:41:36.692Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.692Z] 10:41:36 INFO - GECKO(2381) | #05: nsFrameLoader::ReallyStartLoading() [dom/base/nsFrameLoader.cpp:539]
[task 2019-08-24T10:41:36.692Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.692Z] 10:41:36 INFO - GECKO(2381) | #06: mozilla::dom::Document::MaybeInitializeFinalizeFrameLoaders() [dom/base/Document.cpp:8455]
[task 2019-08-24T10:41:36.692Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.693Z] 10:41:36 INFO - GECKO(2381) | #07: mozilla::detail::RunnableMethodImpl<mozilla::dom::Document*, void (mozilla::dom::Document::)(), true, (mozilla::RunnableKind)0>::Run() [xpcom/threads/nsThreadUtils.h:1176]
[task 2019-08-24T10:41:36.693Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.693Z] 10:41:36 INFO - GECKO(2381) | #08: nsContentUtils::AddScriptRunner(already_AddRefed<nsIRunnable>) [dom/base/nsContentUtils.cpp:0]
[task 2019-08-24T10:41:36.693Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.694Z] 10:41:36 INFO - GECKO(2381) | #09: nsContentUtils::AddScriptRunner(nsIRunnable) [dom/base/nsContentUtils.cpp:5251]
[task 2019-08-24T10:41:36.694Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.694Z] 10:41:36 INFO - GECKO(2381) | #10: mozilla::dom::Document::InitializeFrameLoader(nsFrameLoader*) [dom/base/Document.cpp:8401]
[task 2019-08-24T10:41:36.694Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.695Z] 10:41:36 INFO - GECKO(2381) | #11: nsFrameLoader::ResumeLoad(unsigned long) [dom/base/nsFrameLoader.cpp:529]
[task 2019-08-24T10:41:36.695Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.695Z] 10:41:36 INFO - GECKO(2381) | #12: nsFrameLoaderOwner::ChangeRemoteness(mozilla::dom::RemotenessOptions const&, mozilla::ErrorResult&) [dom/base/nsFrameLoaderOwner.cpp:94]
[task 2019-08-24T10:41:36.695Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.695Z] 10:41:36 INFO - GECKO(2381) | #13: mozilla::dom::WindowGlobalChild::RecvChangeFrameRemoteness(mozilla::dom::BrowsingContext*, nsTString<char16_t> const&, unsigned long, std::function<void (mozilla::Tuple<nsresult const&, mozilla::dom::PBrowserBridgeChild*>)>&&) [dom/ipc/WindowGlobalChild.cpp:309]
[task 2019-08-24T10:41:36.696Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.696Z] 10:41:36 INFO - GECKO(2381) | #14: mozilla::dom::PWindowGlobalChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:9596c625a6e87a91e3c8829fb882f453359ca17174888223411dac9608b43bd5d09694c7bf27b196a7d4c3c0550ff25a5e7968a8c1ab9597e61f7d8a87c6f3c8/ipc/ipdl/PWindowGlobalChild.cpp::394]
[task 2019-08-24T10:41:36.696Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.696Z] 10:41:36 INFO - GECKO(2381) | #15: mozilla::dom::PContentChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:06f7ac4bbfcce7984331741370ea377d012ade459d30b68329d3e0ef9fb2c1b4e1149f0f5a89882829b145f3c54b58d23368fcf177a74e98dfd3ecc66a1cac54/ipc/ipdl/PContentChild.cpp::7874]
[task 2019-08-24T10:41:36.696Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.697Z] 10:41:36 INFO - GECKO(2381) | #16: mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) [ipc/glue/MessageChannel.cpp:2185]
[task 2019-08-24T10:41:36.697Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.697Z] 10:41:36 INFO - GECKO(2381) | #17: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) [ipc/glue/MessageChannel.cpp:2111]
[task 2019-08-24T10:41:36.697Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.697Z] 10:41:36 INFO - GECKO(2381) | #18: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) [ipc/glue/MessageChannel.cpp:0]
[task 2019-08-24T10:41:36.698Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.698Z] 10:41:36 INFO - GECKO(2381) | #19: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:1988]
[task 2019-08-24T10:41:36.698Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.698Z] 10:41:36 INFO - GECKO(2381) | #20: mozilla::SchedulerGroup::Runnable::Run() [xpcom/threads/SchedulerGroup.cpp:295]
[task 2019-08-24T10:41:36.698Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.699Z] 10:41:36 INFO - GECKO(2381) | #21: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1214]
[task 2019-08-24T10:41:36.699Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.699Z] 10:41:36 INFO - GECKO(2381) | #22: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:486]
[task 2019-08-24T10:41:36.699Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.699Z] 10:41:36 INFO - GECKO(2381) | #23: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:0]
[task 2019-08-24T10:41:36.700Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.700Z] 10:41:36 INFO - GECKO(2381) | #24: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:315]
[task 2019-08-24T10:41:36.700Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.700Z] 10:41:36 INFO - GECKO(2381) | #25: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2019-08-24T10:41:36.700Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.701Z] 10:41:36 INFO - GECKO(2381) | [Child 2645, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-24T10:41:36.701Z] 10:41:36 INFO - GECKO(2381) | #26: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
[task 2019-08-24T10:41:36.701Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.701Z] 10:41:36 INFO - GECKO(2381) | #27: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:934]
[task 2019-08-24T10:41:36.701Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.702Z] 10:41:36 INFO - GECKO(2381) | #28: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:238]
[task 2019-08-24T10:41:36.702Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.702Z] 10:41:36 INFO - GECKO(2381) | #29: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:315]
[task 2019-08-24T10:41:36.702Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.702Z] 10:41:36 INFO - GECKO(2381) | #30: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2019-08-24T10:41:36.703Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.703Z] 10:41:36 INFO - GECKO(2381) | #31: XRE_InitChildProcess(int, char**, XREChildData const*) [toolkit/xre/nsEmbedFunctions.cpp:769]
[task 2019-08-24T10:41:36.703Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.888Z] 10:41:36 INFO - GECKO(2381) | #32: content_process_main(mozilla::Bootstrap*, int, char**) [ipc/contentproc/plugin-container.cpp:57]
[task 2019-08-24T10:41:36.888Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.889Z] 10:41:36 INFO - GECKO(2381) | #33: main [browser/app/nsBrowserApp.cpp:267]
[task 2019-08-24T10:41:36.889Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.889Z] 10:41:36 INFO - GECKO(2381) | #34: libc.so.6 + 0x20830
[task 2019-08-24T10:41:36.889Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.890Z] 10:41:36 INFO - GECKO(2381) | #35: _start
[task 2019-08-24T10:41:36.891Z] 10:41:36 INFO -
[task 2019-08-24T10:41:36.891Z] 10:41:36 INFO - GECKO(2381) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-08-24T10:41:36.892Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7f9af4eb9000 == 0 [pid = 2645] [id = {f3e3aa66-d9f7-458b-91eb-b2982e038169}] [url = https://example.com/tests/dom/security/test/csp/file_block_all_mixed_content_frame_navigation1.html]
[task 2019-08-24T10:41:36.896Z] 10:41:36 INFO - GECKO(2381) | [Parent 2381, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-08-24T10:41:36.896Z] 10:41:36 INFO - GECKO(2381) | [Parent 2381, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-08-24T10:41:36.897Z] 10:41:36 INFO - GECKO(2381) | ++DOCSHELL 0x7ff938217800 == 6 [pid = 2381] [id = {82793f64-55d8-4a89-8b2a-a27ff12767e6}]
[task 2019-08-24T10:41:36.897Z] 10:41:36 INFO - GECKO(2381) | ++DOMWINDOW == 11 (0x7ff93c62d880) [pid = 2381] [serial = 13] [outer = (nil)]
[task 2019-08-24T10:41:36.898Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 2 (0x7f9b0e2ecf20) [pid = 2645] [serial = 1] [outer = (nil)] [url = https://example.com/tests/dom/security/test/csp/file_block_all_mixed_content_frame_navigation1.html]
[task 2019-08-24T10:41:36.899Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 1 (0x7f9af577f400) [pid = 2645] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-08-24T10:41:36.899Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 0 (0x7f9af49ca800) [pid = 2645] [serial = 3] [outer = (nil)] [url = https://example.com/tests/dom/security/test/csp/file_block_all_mixed_content_frame_navigation1.html]
[task 2019-08-24T10:41:36.900Z] 10:41:36 INFO - GECKO(2381) | ++DOMWINDOW == 12 (0x7ff937332400) [pid = 2381] [serial = 14] [outer = 0x7ff93c62d880]
[task 2019-08-24T10:41:36.900Z] 10:41:36 INFO - GECKO(2381) | [Child 2645, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-08-24T10:41:36.900Z] 10:41:36 INFO - GECKO(2381) | [Child 2645, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T10:41:36.901Z] 10:41:36 INFO - GECKO(2381) | [Child 2645, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T10:41:36.901Z] 10:41:36 INFO - GECKO(2381) | nsStringStats
[task 2019-08-24T10:41:36.901Z] 10:41:36 INFO - GECKO(2381) | => mAllocCount: 6749
[task 2019-08-24T10:41:36.902Z] 10:41:36 INFO - GECKO(2381) | => mReallocCount: 0
[task 2019-08-24T10:41:36.902Z] 10:41:36 INFO - GECKO(2381) | => mFreeCount: 6749
[task 2019-08-24T10:41:36.902Z] 10:41:36 INFO - GECKO(2381) | => mShareCount: 4230
[task 2019-08-24T10:41:36.903Z] 10:41:36 INFO - GECKO(2381) | => mAdoptCount: 204
[task 2019-08-24T10:41:36.903Z] 10:41:36 INFO - GECKO(2381) | => mAdoptFreeCount: 210
[task 2019-08-24T10:41:36.903Z] 10:41:36 INFO - GECKO(2381) | => Process ID: 2645, Thread ID: 140303957604160
[task 2019-08-24T10:41:36.904Z] 10:41:36 ERROR - GECKO(2381) | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2019-08-24T10:41:36.904Z] 10:41:36 INFO - GECKO(2381) | [Parent 2381, Main Thread] WARNING: Cannot set transparency mode on non-popup windows.: file /builds/worker/workspace/build/src/widget/gtk/nsWindow.cpp, line 4456
[task 2019-08-24T10:41:36.904Z] 10:41:36 INFO - GECKO(2381) | ++DOMWINDOW == 13 (0x7ff9373f3800) [pid = 2381] [serial = 15] [outer = 0x7ff93c62d880]
[task 2019-08-24T10:41:36.905Z] 10:41:36 INFO - GECKO(2381) | [Parent 2381, Main Thread] WARNING: Cannot set transparency mode on non-popup windows.: file /builds/worker/workspace/build/src/widget/gtk/nsWindow.cpp, line 4456
[task 2019-08-24T10:41:36.905Z] 10:41:36 INFO - GECKO(2381) | [GPU 2414, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/workspace/build/src/gfx/layers/apz/src/APZUpdater.cpp, line 429
[task 2019-08-24T10:41:36.906Z] 10:41:36 INFO - GECKO(2381) | WARN 2019-08-24T10:41:16Z: xulstore::persist: tried to remove key that isn't in the store
[task 2019-08-24T10:41:36.906Z] 10:41:36 INFO - GECKO(2381) | WARN 2019-08-24T10:41:16Z: xulstore::persist: tried to remove key that isn't in the store
[task 2019-08-24T10:41:36.906Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7f941c825800 == 2 [pid = 2451] [id = {a2fbda0e-31ad-4499-89ff-00468ffd4a2c}] [url = moz-extension://25f93053-3d60-44e7-aca9-797cf49e5e61/_generated_background_page.html]
[task 2019-08-24T10:41:36.906Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7f941c829000 == 1 [pid = 2451] [id = {0e8caa0d-73ad-450f-9cc2-a0fcf75819ab}] [url = moz-extension://62eba33f-f1e1-42dc-9808-990bef88148b/_generated_background_page.html]
[task 2019-08-24T10:41:36.907Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7f941bfb6000 == 0 [pid = 2451] [id = {4e5b282c-cb9d-4c24-9bce-ef63ee1d7b30}] [url = moz-extension://0a8f9532-1167-4795-9b9f-4518cd5e0094/_generated_background_page.html]
[task 2019-08-24T10:41:36.907Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7ff93e8bf800 == 5 [pid = 2381] [id = {ddb67341-9048-4c72-ae7b-8e865b48d36a}] [url = about:blank]
[task 2019-08-24T10:41:36.908Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7ff93efd9800 == 4 [pid = 2381] [id = {a34d3ee8-497b-4201-b1d6-68f6027a21f2}] [url = about:blank]
[task 2019-08-24T10:41:36.908Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7ff93e278000 == 3 [pid = 2381] [id = {a3fa239c-74c9-4c69-a4d7-0bce6de124e9}] [url = chrome://extensions/content/dummy.xul]
[task 2019-08-24T10:41:36.909Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 5 (0x7f941cf85880) [pid = 2451] [serial = 3] [outer = (nil)] [url = moz-extension://25f93053-3d60-44e7-aca9-797cf49e5e61/_generated_background_page.html]
[task 2019-08-24T10:41:36.909Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 4 (0x7f941cf843e0) [pid = 2451] [serial = 5] [outer = (nil)] [url = moz-extension://62eba33f-f1e1-42dc-9808-990bef88148b/_generated_background_page.html]
[task 2019-08-24T10:41:36.909Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 3 (0x7f941cf854c0) [pid = 2451] [serial = 1] [outer = (nil)] [url = moz-extension://0a8f9532-1167-4795-9b9f-4518cd5e0094/_generated_background_page.html]
[task 2019-08-24T10:41:36.910Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 2 (0x7f941c09c000) [pid = 2451] [serial = 9] [outer = (nil)] [url = moz-extension://0a8f9532-1167-4795-9b9f-4518cd5e0094/_generated_background_page.html]
[task 2019-08-24T10:41:36.910Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 1 (0x7f941c0a2000) [pid = 2451] [serial = 11] [outer = (nil)] [url = moz-extension://62eba33f-f1e1-42dc-9808-990bef88148b/_generated_background_page.html]
[task 2019-08-24T10:41:36.911Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 0 (0x7f941c0a0400) [pid = 2451] [serial = 10] [outer = (nil)] [url = moz-extension://25f93053-3d60-44e7-aca9-797cf49e5e61/_generated_background_page.html]
[task 2019-08-24T10:41:36.912Z] 10:41:36 INFO - GECKO(2381) | [Child 2451, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T10:41:36.913Z] 10:41:36 INFO - GECKO(2381) | [Child 2451, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T10:41:36.913Z] 10:41:36 INFO - GECKO(2381) | nsStringStats
[task 2019-08-24T10:41:36.913Z] 10:41:36 INFO - GECKO(2381) | => mAllocCount: 14900
[task 2019-08-24T10:41:36.914Z] 10:41:36 INFO - GECKO(2381) | => mReallocCount: 0
[task 2019-08-24T10:41:36.914Z] 10:41:36 INFO - GECKO(2381) | => mFreeCount: 14900
[task 2019-08-24T10:41:36.915Z] 10:41:36 INFO - GECKO(2381) | => mShareCount: 8884
[task 2019-08-24T10:41:36.916Z] 10:41:36 INFO - GECKO(2381) | => mAdoptCount: 496
[task 2019-08-24T10:41:36.916Z] 10:41:36 INFO - GECKO(2381) | => mAdoptFreeCount: 520
[task 2019-08-24T10:41:36.917Z] 10:41:36 INFO - GECKO(2381) | => Process ID: 2451, Thread ID: 140274547820352
[task 2019-08-24T10:41:36.917Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 1 (0x7f20aa698a60) [pid = 2474] [serial = 4] [outer = (nil)] [url = about:newtab]
[task 2019-08-24T10:41:36.924Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7f20aa780800 == 0 [pid = 2474] [id = {267b2374-147c-4f41-9e5b-a5bd4f97f691}] [url = about:newtab]
[task 2019-08-24T10:41:36.925Z] 10:41:36 INFO - GECKO(2381) | --DOMWINDOW == 0 (0x7f20aa6ab400) [pid = 2474] [serial = 6] [outer = (nil)] [url = about:newtab]
[task 2019-08-24T10:41:36.925Z] 10:41:36 INFO - GECKO(2381) | [Child 2474, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T10:41:36.926Z] 10:41:36 INFO - GECKO(2381) | [Child 2474, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-08-24T10:41:36.926Z] 10:41:36 INFO - GECKO(2381) | nsStringStats
[task 2019-08-24T10:41:36.926Z] 10:41:36 INFO - GECKO(2381) | => mAllocCount: 12856
[task 2019-08-24T10:41:36.927Z] 10:41:36 INFO - GECKO(2381) | => mReallocCount: 0
[task 2019-08-24T10:41:36.927Z] 10:41:36 INFO - GECKO(2381) | => mFreeCount: 12856
[task 2019-08-24T10:41:36.928Z] 10:41:36 INFO - GECKO(2381) | => mShareCount: 15793
[task 2019-08-24T10:41:36.928Z] 10:41:36 INFO - GECKO(2381) | => mAdoptCount: 501
[task 2019-08-24T10:41:36.928Z] 10:41:36 INFO - GECKO(2381) | => mAdoptFreeCount: 505
[task 2019-08-24T10:41:36.929Z] 10:41:36 INFO - GECKO(2381) | => Process ID: 2474, Thread ID: 139778706134848
[task 2019-08-24T10:41:36.929Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7ff93e87e000 == 2 [pid = 2381] [id = {94262072-b65f-4443-925a-278733b0ab8d}] [url = resource://gre-resources/hiddenWindow.html]
[task 2019-08-24T10:41:36.930Z] 10:41:36 INFO - GECKO(2381) | --DOCSHELL 0x7ff94a3cd000 == 1 [pid = 2381] [id = {d075cf15-7e01-439b-8c0f-f23b28abb3a1}] [url = chrome://browser/content/browser.xhtml]
[task 2019-08-24T10:41:36.934Z] 10:41:36 INFO - GECKO(2381) | [Parent 2381, StreamTrans #2] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 371
[task 2019-08-24T10:41:36.936Z] 10:41:36 INFO - GECKO(2381) | [Parent 2381, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 994

So...the address of the newly constructed BrowserBridgeChild is already in mOOPChildrenLoading. I guess that means that we're failing to remove the pointer to a previously constructed BrowserBridgeChild that has subsequently been destroyed, before we end up allocating the new BrowserBridgeChild at the same address.

Took a look at the code for this. I think the issue is that the document the load is occurring in is being disconnected from its nsDocShell before it is destroyed. I'm guessing that the issue here is that the document gets disconnected when it enters the BFCache (e.g. https://searchfox.org/mozilla-central/rev/325c1a707819602feff736f129cb36055ba6d94f/layout/base/nsDocumentViewer.cpp#1849)

We can probably fix this issue by using the BrowsingContext tree to get the Parent() BC and pull the nsDocShell off of it rather than going through the Document object. I don't think that's as likely to be a broken link. Alternatively, we could also directly store the embedder nsDocShell on the BrowserBridgeChild, which would make sure we always do both the add and remove calls on the same DocShell.

This was fixed by the backout of the patch from bug 1559841 on Aug 28th.

Assignee: nobody → jwatt
Status: NEW → RESOLVED
Closed: 5 years ago
status-firefox70: --- → fixed
Flags: needinfo?(jwatt)
Resolution: --- → FIXED
Target Milestone: --- → mozilla70
Flags: needinfo?(jwatt)

To close the loop on this...

(In reply to :Nika Layzell (ni? for response) from comment #4)

Took a look at the code for this. I think the issue is that the document the load is occurring in is being disconnected from its nsDocShell before it is destroyed. I'm guessing that the issue here is that the document gets disconnected when it enters the BFCache (e.g. https://searchfox.org/mozilla-central/rev/325c1a707819602feff736f129cb36055ba6d94f/layout/base/nsDocumentViewer.cpp#1849)

We can probably fix this issue by using the BrowsingContext tree to get the Parent() BC and pull the nsDocShell off of it rather than going through the Document object. I don't think that's as likely to be a broken link.

That approach did get rid of this assertion:

https://treeherder.mozilla.org/#/jobs?repo=try&collapsedPushes=549957&searchStr=m-fis&revision=bf81f98ae6e643cf867e97d0307a71099ffe9b70

Alternatively, we could also directly store the embedder nsDocShell on the BrowserBridgeChild, which would make sure we always do both the add and remove calls on the same DocShell.

This approach also got rid of this assertion:

https://treeherder.mozilla.org/#/jobs?repo=try&collapsedPushes=549957&searchStr=m-fis&revision=b1d7bb0a99363f29d5a394330bec5f7265b13775&selectedJob=264603835

However, both approaches have had other problems and other failures. FWIW I'm currently pursuing the latter approach.

Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.