Closed Bug 1750044 Opened 2 years ago Closed 2 years ago

Permafail SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:867:23 in SetAlreadyDead

Categories

(Core :: IPC, defect, P5)

defect

Tracking

()

RESOLVED FIXED
98 Branch
Tracking Status
firefox-esr91 --- unaffected
firefox96 --- unaffected
firefox97 --- unaffected
firefox98 --- fixed

People

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

References

(Blocks 1 open bug, Regression)

Details

(4 keywords, Whiteboard: [fixed by bug 1658474])

Attachments

(1 file)

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


[task 2022-01-13T16:52:59.469Z] 16:52:59     INFO - GECKO(3756) |   Thread T4 'IPC I/O Parent' (tid=3766, running) created by main thread at:
[task 2022-01-13T16:52:59.470Z] 16:52:59     INFO - GECKO(3756) |     #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:977:3 (firefox+0x5919d)
[task 2022-01-13T16:52:59.470Z] 16:52:59     INFO - GECKO(3756) |     #1 CreateThread /builds/worker/checkouts/gecko/ipc/chromium/src/base/platform_thread_posix.cc:123:14 (libxul.so+0x18992d7)
[task 2022-01-13T16:52:59.471Z] 16:52:59     INFO - GECKO(3756) |     #2 PlatformThread::Create(unsigned long, PlatformThread::Delegate*, unsigned long*) /builds/worker/checkouts/gecko/ipc/chromium/src/base/platform_thread_posix.cc:134:10 (libxul.so+0x18992d7)
[task 2022-01-13T16:52:59.471Z] 16:52:59     INFO - GECKO(3756) |     #3 base::Thread::StartWithOptions(base::Thread::Options const&) /builds/worker/checkouts/gecko/ipc/chromium/src/base/thread.cc:93:8 (libxul.so+0x18a24e1)
[task 2022-01-13T16:52:59.472Z] 16:52:59     INFO - GECKO(3756) |     #4 NS_InitXPCOM /builds/worker/checkouts/gecko/xpcom/build/XPCOMInit.cpp:335:9 (libxul.so+0xfda9bc)
[task 2022-01-13T16:52:59.472Z] 16:52:59     INFO - GECKO(3756) |     #5 ScopedXPCOMStartup::Initialize(bool) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:1709:8 (libxul.so+0x70e6124)
[task 2022-01-13T16:52:59.473Z] 16:52:59     INFO - GECKO(3756) |     #6 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5529:22 (libxul.so+0x70f0dfe)
[task 2022-01-13T16:52:59.473Z] 16:52:59     INFO - GECKO(3756) |     #7 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5592:21 (libxul.so+0x70f13e4)
[task 2022-01-13T16:52:59.474Z] 16:52:59     INFO - GECKO(3756) |     #8 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x70fbd32)
[task 2022-01-13T16:52:59.474Z] 16:52:59     INFO - GECKO(3756) |     #9 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:225:22 (firefox+0xcf494)
[task 2022-01-13T16:52:59.474Z] 16:52:59     INFO - GECKO(3756) |     #10 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:395:16 (firefox+0xcf494)
[task 2022-01-13T16:52:59.475Z] 16:52:59     INFO - GECKO(3756) |   Thread T55 'GMPThread' (tid=3827, running) created by main thread at:
[task 2022-01-13T16:52:59.475Z] 16:52:59     INFO - GECKO(3756) |     #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:977:3 (firefox+0x5919d)
[task 2022-01-13T16:52:59.476Z] 16:52:59     INFO - GECKO(3756) |     #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3c1a5)
[task 2022-01-13T16:52:59.476Z] 16:52:59     INFO - GECKO(3756) |     #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x30cf5)
[task 2022-01-13T16:52:59.477Z] 16:52:59     INFO - GECKO(3756) |     #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:627:18 (libxul.so+0xf99845)
[task 2022-01-13T16:52:59.477Z] 16:52:59     INFO - GECKO(3756) |     #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, unsigned int, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:581:12 (libxul.so+0xfa1c98)
[task 2022-01-13T16:52:59.477Z] 16:52:59     INFO - GECKO(3756) |     #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:163:57 (libxul.so+0xfa9a54)
[task 2022-01-13T16:52:59.478Z] 16:52:59     INFO - GECKO(3756) |     #6 NS_NewNamedThread<10UL> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:85:10 (libxul.so+0x432c69f)
[task 2022-01-13T16:52:59.479Z] 16:52:59     INFO - GECKO(3756) |     #7 GetThreadLocked /builds/worker/checkouts/gecko/dom/media/gmp/GMPService.cpp:394:19 (libxul.so+0x432c69f)
[task 2022-01-13T16:52:59.479Z] 16:52:59     INFO - GECKO(3756) |     #8 mozilla::gmp::GeckoMediaPluginService::GetThread(nsIThread**) /builds/worker/checkouts/gecko/dom/media/gmp/GMPService.cpp:379:10 (libxul.so+0x432c69f)
[task 2022-01-13T16:52:59.480Z] 16:52:59     INFO - GECKO(3756) |     #9 Init /builds/worker/checkouts/gecko/dom/media/gmp/GMPService.cpp:207:10 (libxul.so+0x4333449)
[task 2022-01-13T16:52:59.480Z] 16:52:59     INFO - GECKO(3756) |     #10 mozilla::gmp::GeckoMediaPluginServiceParent::InitStorage() /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:224:35 (libxul.so+0x4333449)
[task 2022-01-13T16:52:59.481Z] 16:52:59     INFO - GECKO(3756) |     #11 mozilla::gmp::GeckoMediaPluginServiceParent::Init() /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:120:17 (libxul.so+0x4332d73)
[task 2022-01-13T16:52:59.481Z] 16:52:59     INFO - GECKO(3756) |     #12 mozilla::gmp::GMPServiceCreateHelper::GetOrCreateOnMainThread() /builds/worker/checkouts/gecko/dom/media/gmp/GMPService.cpp:89:18 (libxul.so+0x434e0df)
[task 2022-01-13T16:52:59.482Z] 16:52:59     INFO - GECKO(3756) |     #13 mozilla::gmp::GMPServiceCreateHelper::GetOrCreate() /builds/worker/checkouts/gecko/dom/media/gmp/GMPService.cpp:63:17 (libxul.so+0x432a93c)
[task 2022-01-13T16:52:59.482Z] 16:52:59     INFO - GECKO(3756) |     #14 mozilla::gmp::GeckoMediaPluginService::GetGeckoMediaPluginService() /builds/worker/checkouts/gecko/dom/media/gmp/GMPService.cpp:120:10 (libxul.so+0x432a90f)
[task 2022-01-13T16:52:59.483Z] 16:52:59     INFO - GECKO(3756) |     #15 mozilla::xpcom::CreateInstanceImpl(mozilla::xpcom::ModuleID, nsISupports*, nsID const&, void**) /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:9990:60 (libxul.so+0xf5c6df)
[task 2022-01-13T16:52:59.483Z] 16:52:59     INFO - GECKO(3756) |     #16 mozilla::xpcom::StaticModule::CreateInstance(nsISupports*, nsID const&, void**) const /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:12151:10 (libxul.so+0xf59813)
[task 2022-01-13T16:52:59.484Z] 16:52:59     INFO - GECKO(3756) |     #17 CreateInstance /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:181:46 (libxul.so+0xf70816)
[task 2022-01-13T16:52:59.485Z] 16:52:59     INFO - GECKO(3756) |     #18 nsComponentManagerImpl::GetServiceLocked(mozilla::Maybe<mozilla::MonitorAutoLock>&, (anonymous namespace)::EntryWrapper&, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1288:17 (libxul.so+0xf70816)
[task 2022-01-13T16:52:59.485Z] 16:52:59     INFO - GECKO(3756) |     #19 nsComponentManagerImpl::GetServiceByContractID(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1477:10 (libxul.so+0xf7151f)
[task 2022-01-13T16:52:59.486Z] 16:52:59     INFO - GECKO(3756) |     #20 CallGetService(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManagerUtils.cpp:61:43 (libxul.so+0xf73f0d)
[task 2022-01-13T16:52:59.486Z] 16:52:59     INFO - GECKO(3756) |     #21 xpc::CIGSHelper(JSContext*, unsigned int, JS::Value*, bool) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSID.cpp:586:10 (libxul.so+0x1f7b0c1)
[task 2022-01-13T16:52:59.487Z] 16:52:59     INFO - GECKO(3756) |     #22 xpc::CID_GetService(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSID.cpp:611:10 (libxul.so+0x1f7ad67)
[task 2022-01-13T16:52:59.487Z] 16:52:59     INFO - GECKO(3756) |     #23 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:425:13 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.488Z] 16:52:59     INFO - GECKO(3756) |     #24 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:512:12 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.489Z] 16:52:59     INFO - GECKO(3756) |     #25 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.489Z] 16:52:59     INFO - GECKO(3756) |     #26 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:576:10 (libxul.so+0x7225903)
[task 2022-01-13T16:52:59.490Z] 16:52:59     INFO - GECKO(3756) |     #27 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3309:16 (libxul.so+0x7225903)
[task 2022-01-13T16:52:59.490Z] 16:52:59     INFO - GECKO(3756) |     #28 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:394:13 (libxul.so+0x72191c6)
[task 2022-01-13T16:52:59.491Z] 16:52:59     INFO - GECKO(3756) |     #29 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:544:13 (libxul.so+0x722fb79)
[task 2022-01-13T16:52:59.491Z] 16:52:59     INFO - GECKO(3756) |     #30 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.492Z] 16:52:59     INFO - GECKO(3756) |     #31 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:589:8 (libxul.so+0x7230b0a)
[task 2022-01-13T16:52:59.492Z] 16:52:59     INFO - GECKO(3756) |     #32 js::fun_call(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/vm/JSFunction.cpp:962:10 (libxul.so+0x73faab6)
[task 2022-01-13T16:52:59.493Z] 16:52:59     INFO - GECKO(3756) |     #33 js::fun_apply(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/vm/JSFunction.cpp:982:12 (libxul.so+0x73faf00)
[task 2022-01-13T16:52:59.493Z] 16:52:59     INFO - GECKO(3756) |     #34 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:425:13 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.494Z] 16:52:59     INFO - GECKO(3756) |     #35 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:512:12 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.494Z] 16:52:59     INFO - GECKO(3756) |     #36 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.495Z] 16:52:59     INFO - GECKO(3756) |     #37 js::CallFromStack(JSContext*, JS::CallArgs const&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:576:10 (libxul.so+0x7230903)
[task 2022-01-13T16:52:59.495Z] 16:52:59     INFO - GECKO(3756) |     #38 js::jit::DoCallFallback(JSContext*, js::jit::BaselineFrame*, js::jit::ICFallbackStub*, unsigned int, JS::Value*, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jit/BaselineIC.cpp:1595:10 (libxul.so+0x79dd761)
[task 2022-01-13T16:52:59.496Z] 16:52:59     INFO - GECKO(3756) |     #39 <null> <null> (0x7f8230ba5e18)
[task 2022-01-13T16:52:59.496Z] 16:52:59     INFO - GECKO(3756) |     #40 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:384:32 (libxul.so+0x72190d5)
[task 2022-01-13T16:52:59.497Z] 16:52:59     INFO - GECKO(3756) |     #41 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:544:13 (libxul.so+0x722fb79)
[task 2022-01-13T16:52:59.498Z] 16:52:59     INFO - GECKO(3756) |     #42 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.498Z] 16:52:59     INFO - GECKO(3756) |     #43 Call /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:589:8 (libxul.so+0x7231af5)
[task 2022-01-13T16:52:59.499Z] 16:52:59     INFO - GECKO(3756) |     #44 js::CallGetter(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:715:10 (libxul.so+0x7231af5)
[task 2022-01-13T16:52:59.499Z] 16:52:59     INFO - GECKO(3756) |     #45 CallGetter /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:1970:12 (libxul.so+0x74c1766)
[task 2022-01-13T16:52:59.500Z] 16:52:59     INFO - GECKO(3756) |     #46 GetExistingProperty<js::CanGC> /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:1998:12 (libxul.so+0x74c1766)
[task 2022-01-13T16:52:59.500Z] 16:52:59     INFO - GECKO(3756) |     #47 js::NativeGetExistingProperty(JSContext*, JS::Handle<JSObject*>, JS::Handle<js::NativeObject*>, JS::Handle<JS::PropertyKey>, js::PropertyInfoBase<unsigned int>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2006:10 (libxul.so+0x74c1766)
[task 2022-01-13T16:52:59.501Z] 16:52:59     INFO - GECKO(3756) |     #48 bool js::FetchName<(js::GetNameMode)0>(JSContext*, JS::Handle<JSObject*>, JS::Handle<JSObject*>, JS::Handle<js::PropertyName*>, js::PropertyResult const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter-inl.h:139:12 (libxul.so+0x7218992)
[task 2022-01-13T16:52:59.502Z] 16:52:59     INFO - GECKO(3756) |     #49 bool js::GetEnvironmentName<(js::GetNameMode)0>(JSContext*, JS::Handle<JSObject*>, JS::Handle<js::PropertyName*>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter-inl.h:190:10 (libxul.so+0x7242197)
[task 2022-01-13T16:52:59.502Z] 16:52:59     INFO - GECKO(3756) |     #50 GetNameOperation /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:218:10 (libxul.so+0x7226aba)
[task 2022-01-13T16:52:59.503Z] 16:52:59     INFO - GECKO(3756) |     #51 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3443:12 (libxul.so+0x7226aba)
[task 2022-01-13T16:52:59.503Z] 16:52:59     INFO - GECKO(3756) |     #52 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:394:13 (libxul.so+0x72191c6)
[task 2022-01-13T16:52:59.504Z] 16:52:59     INFO - GECKO(3756) |     #53 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:544:13 (libxul.so+0x722fb79)
[task 2022-01-13T16:52:59.504Z] 16:52:59     INFO - GECKO(3756) |     #54 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.505Z] 16:52:59     INFO - GECKO(3756) |     #55 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:589:8 (libxul.so+0x7230b0a)
[task 2022-01-13T16:52:59.505Z] 16:52:59     INFO - GECKO(3756) |     #56 js::fun_apply(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/vm/JSFunction.cpp:1014:10 (libxul.so+0x73fb07f)
[task 2022-01-13T16:52:59.506Z] 16:52:59     INFO - GECKO(3756) |     #57 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:425:13 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.507Z] 16:52:59     INFO - GECKO(3756) |     #58 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:512:12 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.507Z] 16:52:59     INFO - GECKO(3756) |     #59 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.508Z] 16:52:59     INFO - GECKO(3756) |     #60 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:576:10 (libxul.so+0x7225903)
[task 2022-01-13T16:52:59.508Z] 16:52:59     INFO - GECKO(3756) |     #61 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3309:16 (libxul.so+0x7225903)
[task 2022-01-13T16:52:59.509Z] 16:52:59     INFO - GECKO(3756) |     #62 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:394:13 (libxul.so+0x72191c6)
[task 2022-01-13T16:52:59.509Z] 16:52:59     INFO - GECKO(3756) |     #63 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:544:13 (libxul.so+0x722fb79)
[task 2022-01-13T16:52:59.510Z] 16:52:59     INFO - GECKO(3756) |     #64 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.510Z] 16:52:59     INFO - GECKO(3756) |     #65 js::CallFromStack(JSContext*, JS::CallArgs const&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:576:10 (libxul.so+0x7230903)
[task 2022-01-13T16:52:59.511Z] 16:52:59     INFO - GECKO(3756) |     #66 js::jit::DoCallFallback(JSContext*, js::jit::BaselineFrame*, js::jit::ICFallbackStub*, unsigned int, JS::Value*, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jit/BaselineIC.cpp:1595:10 (libxul.so+0x79dd761)
[task 2022-01-13T16:52:59.511Z] 16:52:59     INFO - GECKO(3756) |     #67 <null> <null> (0x7f8230ba5e18)
[task 2022-01-13T16:52:59.512Z] 16:52:59     INFO - GECKO(3756) |     #68 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3348:40 (libxul.so+0x7225d4f)
[task 2022-01-13T16:52:59.512Z] 16:52:59     INFO - GECKO(3756) |     #69 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:394:13 (libxul.so+0x72191c6)
[task 2022-01-13T16:52:59.513Z] 16:52:59     INFO - GECKO(3756) |     #70 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:544:13 (libxul.so+0x722fb79)
[task 2022-01-13T16:52:59.514Z] 16:52:59     INFO - GECKO(3756) |     #71 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.514Z] 16:52:59     INFO - GECKO(3756) |     #72 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:589:8 (libxul.so+0x7230b0a)
[task 2022-01-13T16:52:59.515Z] 16:52:59     INFO - GECKO(3756) |     #73 JS_CallFunctionValue(JSContext*, JS::Handle<JSObject*>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CallAndConstruct.cpp:53:10 (libxul.so+0x73630b1)
[task 2022-01-13T16:52:59.515Z] 16:52:59     INFO - GECKO(3756) |     #74 nsXPCWrappedJS::CallMethod(unsigned short, nsXPTMethodInfo const*, nsXPTCMiniVariant*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedJSClass.cpp:973:17 (libxul.so+0x1f9289a)
[task 2022-01-13T16:52:59.516Z] 16:52:59     INFO - GECKO(3756) |     #75 PrepareAndDispatch /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcstubs_x86_64_linux.cpp:115:37 (libxul.so+0xfcaa23)
[task 2022-01-13T16:52:59.517Z] 16:52:59     INFO - GECKO(3756) |     #76 SharedStub xptcstubs_x86_64_linux.cpp (libxul.so+0xfc9d62)
[task 2022-01-13T16:52:59.517Z] 16:52:59     INFO - GECKO(3756) |     #77 nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/xpcom/ds/nsObserverService.cpp:292:19 (libxul.so+0xefabe6)
[task 2022-01-13T16:52:59.518Z] 16:52:59     INFO - GECKO(3756) |     #78 mozilla::dom::ContentParent::LaunchSubprocessResolve(bool, mozilla::hal::ProcessPriority) /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:2603:10 (libxul.so+0x4bf1443)
[task 2022-01-13T16:52:59.518Z] 16:52:59     INFO - GECKO(3756) |     #79 mozilla::dom::ContentParent::WaitForLaunchSync(mozilla::hal::ProcessPriority) /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1184:7 (libxul.so+0x4bf0d4f)
[task 2022-01-13T16:52:59.519Z] 16:52:59     INFO - GECKO(3756) |     #80 GetNewOrUsedBrowserProcess /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1126:41 (libxul.so+0x4bf222a)
[task 2022-01-13T16:52:59.520Z] 16:52:59     INFO - GECKO(3756) |     #81 mozilla::dom::ContentParent::CreateBrowser(mozilla::dom::TabContext const&, mozilla::dom::Element*, nsTSubstring<char> const&, mozilla::dom::BrowsingContext*, mozilla::dom::ContentParent*) /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1490:27 (libxul.so+0x4bf222a)
[task 2022-01-13T16:52:59.520Z] 16:52:59     INFO - GECKO(3756) |     #82 nsFrameLoader::TryRemoteBrowserInternal() /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2732:9 (libxul.so+0x2a95b43)
[task 2022-01-13T16:52:59.521Z] 16:52:59     INFO - GECKO(3756) |     #83 TryRemoteBrowser /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2796:7 (libxul.so+0x2a8c64e)
[task 2022-01-13T16:52:59.521Z] 16:52:59     INFO - GECKO(3756) |     #84 EnsureRemoteBrowser /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2560:28 (libxul.so+0x2a8c64e)
[task 2022-01-13T16:52:59.522Z] 16:52:59     INFO - GECKO(3756) |     #85 nsFrameLoader::ShowRemoteFrame(mozilla::gfx::IntSizeTyped<mozilla::ScreenPixel> const&, nsSubDocumentFrame*) /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:1104:8 (libxul.so+0x2a8c64e)
[task 2022-01-13T16:52:59.522Z] 16:52:59     INFO - GECKO(3756) |     #86 nsFrameLoader::Show(nsSubDocumentFrame*) /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:981:12 (libxul.so+0x2a8dbd6)
[task 2022-01-13T16:52:59.523Z] 16:52:59     INFO - GECKO(3756) |     #87 nsSubDocumentFrame::ShowViewer() /builds/worker/checkouts/gecko/layout/generic/nsSubDocumentFrame.cpp:195:38 (libxul.so+0x56212ab)
[task 2022-01-13T16:52:59.524Z] 16:52:59     INFO - GECKO(3756) |     #88 AsyncFrameInit::Run() /builds/worker/checkouts/gecko/layout/generic/nsSubDocumentFrame.cpp:95:60 (libxul.so+0x5652b62)
[task 2022-01-13T16:52:59.524Z] 16:52:59     INFO - GECKO(3756) |     #89 nsContentUtils::RemoveScriptBlocker() /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:5705:17 (libxul.so+0x27c2a53)
[task 2022-01-13T16:52:59.525Z] 16:52:59     INFO - GECKO(3756) |     #90 ~nsAutoScriptBlocker /builds/worker/checkouts/gecko/dom/base/nsContentUtils.h:3526:28 (libxul.so+0x53d9563)
[task 2022-01-13T16:52:59.525Z] 16:52:59     INFO - GECKO(3756) |     #91 mozilla::PresShell::DoFlushPendingNotifications(mozilla::ChangesToFlush) /builds/worker/checkouts/gecko/layout/base/PresShell.cpp:4259:5 (libxul.so+0x53d9563)
[task 2022-01-13T16:52:59.526Z] 16:52:59     INFO - GECKO(3756) |     #92 FlushPendingNotifications /builds/worker/workspace/obj-build/dist/include/mozilla/PresShell.h:1452:5 (libxul.so+0x29355f9)
[task 2022-01-13T16:52:59.526Z] 16:52:59     INFO - GECKO(3756) |     #93 mozilla::dom::Document::FlushPendingNotifications(mozilla::ChangesToFlush) /builds/worker/checkouts/gecko/dom/base/Document.cpp:10746:16 (libxul.so+0x29355f9)
[task 2022-01-13T16:52:59.527Z] 16:52:59     INFO - GECKO(3756) |     #94 mozilla::dom::Document::FlushPendingNotifications(mozilla::FlushType) /builds/worker/checkouts/gecko/dom/base/Document.cpp:10667:3 (libxul.so+0x291b1ec)
[task 2022-01-13T16:52:59.528Z] 16:52:59     INFO - GECKO(3756) |     #95 nsFrameLoader::TryRemoteBrowserInternal() /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2594:10 (libxul.so+0x2a9546e)
[task 2022-01-13T16:52:59.528Z] 16:52:59     INFO - GECKO(3756) |     #96 TryRemoteBrowser /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2796:7 (libxul.so+0x2a8c04c)
[task 2022-01-13T16:52:59.529Z] 16:52:59     INFO - GECKO(3756) |     #97 EnsureRemoteBrowser /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2560:28 (libxul.so+0x2a8c04c)
[task 2022-01-13T16:52:59.529Z] 16:52:59     INFO - GECKO(3756) |     #98 nsFrameLoader::ReallyStartLoadingInternal() /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:732:10 (libxul.so+0x2a8c04c)
[task 2022-01-13T16:52:59.530Z] 16:52:59     INFO - GECKO(3756) |     #99 nsFrameLoader::ReallyStartLoading() /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:653:17 (libxul.so+0x2a8b93a)
[task 2022-01-13T16:52:59.530Z] 16:52:59     INFO - GECKO(3756) |     #100 mozilla::dom::Document::MaybeInitializeFinalizeFrameLoaders() /builds/worker/checkouts/gecko/dom/base/Document.cpp:9240:13 (libxul.so+0x292e617)
[task 2022-01-13T16:52:59.531Z] 16:52:59     INFO - GECKO(3756) |     #101 applyImpl<mozilla::dom::Document, void (mozilla::dom::Document::*)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1147:12 (libxul.so+0x2998126)
[task 2022-01-13T16:52:59.531Z] 16:52:59     INFO - GECKO(3756) |     #102 apply<mozilla::dom::Document, void (mozilla::dom::Document::*)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1153:12 (libxul.so+0x2998126)
[task 2022-01-13T16:52:59.532Z] 16:52:59     INFO - GECKO(3756) |     #103 mozilla::detail::RunnableMethodImpl<mozilla::dom::Document*, void (mozilla::dom::Document::*)(), true, (mozilla::RunnableKind)0>::Run() /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1200:13 (libxul.so+0x2998126)
[task 2022-01-13T16:52:59.533Z] 16:52:59     INFO - GECKO(3756) |     #104 nsContentUtils::RemoveScriptBlocker() /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:5705:17 (libxul.so+0x27c2a53)
[task 2022-01-13T16:52:59.533Z] 16:52:59     INFO - GECKO(3756) |     #105 mozilla::dom::Document::EndUpdate() /builds/worker/checkouts/gecko/dom/base/Document.cpp:7806:3 (libxul.so+0x29277bb)
[task 2022-01-13T16:52:59.534Z] 16:52:59     INFO - GECKO(3756) |     #106 ~mozAutoDocUpdate /builds/worker/checkouts/gecko/dom/base/mozAutoDocUpdate.h:34:18 (libxul.so+0x2aba1b6)
[task 2022-01-13T16:52:59.535Z] 16:52:59     INFO - GECKO(3756) |     #107 nsINode::ReplaceOrInsertBefore(bool, nsINode*, nsINode*, mozilla::ErrorResult&) /builds/worker/checkouts/gecko/dom/base/nsINode.cpp:2783:1 (libxul.so+0x2aba1b6)
[task 2022-01-13T16:52:59.539Z] 16:52:59     INFO - GECKO(3756) |     #108 InsertBefore /builds/worker/checkouts/gecko/dom/base/nsINode.h:2042:12 (libxul.so+0x2e6dfa7)
[task 2022-01-13T16:52:59.539Z] 16:52:59     INFO - GECKO(3756) |     #109 AppendChild /builds/worker/checkouts/gecko/dom/base/nsINode.h:2049:12 (libxul.so+0x2e6dfa7)
[task 2022-01-13T16:52:59.540Z] 16:52:59     INFO - GECKO(3756) |     #110 mozilla::dom::Node_Binding::appendChild(JSContext*, JS::Handle<JSObject*>, void*, JSJitMethodCallArgs const&) /builds/worker/workspace/obj-build/dom/bindings/NodeBinding.cpp:996:60 (libxul.so+0x2e6dfa7)
[task 2022-01-13T16:52:59.540Z] 16:52:59     INFO - GECKO(3756) |     #111 bool mozilla::dom::binding_detail::GenericMethod<mozilla::dom::binding_detail::NormalThisPolicy, mozilla::dom::binding_detail::ThrowExceptions>(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/dom/bindings/BindingUtils.cpp:3306:13 (libxul.so+0x3982b1e)
[task 2022-01-13T16:52:59.540Z] 16:52:59     INFO - GECKO(3756) |     #112 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:425:13 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.540Z] 16:52:59     INFO - GECKO(3756) |     #113 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:512:12 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.540Z] 16:52:59     INFO - GECKO(3756) |     #114 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.541Z] 16:52:59     INFO - GECKO(3756) |     #115 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:576:10 (libxul.so+0x7225903)
[task 2022-01-13T16:52:59.541Z] 16:52:59     INFO - GECKO(3756) |     #116 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3309:16 (libxul.so+0x7225903)
[task 2022-01-13T16:52:59.543Z] 16:52:59     INFO - GECKO(3756) |     #117 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:394:13 (libxul.so+0x72191c6)
[task 2022-01-13T16:52:59.544Z] 16:52:59     INFO - GECKO(3756) |     #118 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:544:13 (libxul.so+0x722fb79)
[task 2022-01-13T16:52:59.545Z] 16:52:59     INFO - GECKO(3756) |     #119 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.545Z] 16:52:59     INFO - GECKO(3756) |     #120 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:589:8 (libxul.so+0x7230b0a)
[task 2022-01-13T16:52:59.546Z] 16:52:59     INFO - GECKO(3756) |     #121 js::CallSelfHostedFunction(JSContext*, JS::Handle<js::PropertyName*>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/SelfHosting.cpp:1539:10 (libxul.so+0x7517aa5)
[task 2022-01-13T16:52:59.546Z] 16:52:59     INFO - GECKO(3756) |     #122 js::jit::InterpretResume(JSContext*, JS::Handle<JSObject*>, JS::Value*, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jit/VMFunctions.cpp:1093:10 (libxul.so+0x7a58ede)
[task 2022-01-13T16:52:59.547Z] 16:52:59     INFO - GECKO(3756) |     #123 <null> <null> (0x7f8230ba69d0)
[task 2022-01-13T16:52:59.547Z] 16:52:59     INFO - GECKO(3756) |     #124 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:384:32 (libxul.so+0x72190d5)
[task 2022-01-13T16:52:59.548Z] 16:52:59     INFO - GECKO(3756) |     #125 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:544:13 (libxul.so+0x722fb79)
[task 2022-01-13T16:52:59.549Z] 16:52:59     INFO - GECKO(3756) |     #126 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.549Z] 16:52:59     INFO - GECKO(3756) |     #127 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:589:8 (libxul.so+0x7230b0a)
[task 2022-01-13T16:52:59.550Z] 16:52:59     INFO - GECKO(3756) |     #128 js::CallSelfHostedFunction(JSContext*, JS::Handle<js::PropertyName*>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/SelfHosting.cpp:1539:10 (libxul.so+0x7517aa5)
[task 2022-01-13T16:52:59.550Z] 16:52:59     INFO - GECKO(3756) |     #129 AsyncFunctionResume(JSContext*, JS::Handle<js::AsyncFunctionGeneratorObject*>, ResumeKind, JS::Handle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/AsyncFunction.cpp:152:8 (libxul.so+0x7347b3a)
[task 2022-01-13T16:52:59.551Z] 16:52:59     INFO - GECKO(3756) |     #130 js::AsyncFunctionAwaitedFulfilled(JSContext*, JS::Handle<js::AsyncFunctionGeneratorObject*>, JS::Handle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/AsyncFunction.cpp:193:10 (libxul.so+0x73478b7)
[task 2022-01-13T16:52:59.551Z] 16:52:59     INFO - GECKO(3756) |     #131 AsyncFunctionPromiseReactionJob /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:1949:12 (libxul.so+0x743c041)
[task 2022-01-13T16:52:59.552Z] 16:52:59     INFO - GECKO(3756) |     #132 PromiseReactionJob(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:2012:12 (libxul.so+0x743c041)
[task 2022-01-13T16:52:59.552Z] 16:52:59     INFO - GECKO(3756) |     #133 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:425:13 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.553Z] 16:52:59     INFO - GECKO(3756) |     #134 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:512:12 (libxul.so+0x722f9f2)
[task 2022-01-13T16:52:59.553Z] 16:52:59     INFO - GECKO(3756) |     #135 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:572:10 (libxul.so+0x7230a2e)
[task 2022-01-13T16:52:59.554Z] 16:52:59     INFO - GECKO(3756) |     #136 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:589:8 (libxul.so+0x7230b0a)
[task 2022-01-13T16:52:59.555Z] 16:52:59     INFO - GECKO(3756) |     #137 JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CallAndConstruct.cpp:117:10 (libxul.so+0x7363b98)
[task 2022-01-13T16:52:59.555Z] 16:52:59     INFO - GECKO(3756) |     #138 mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/PromiseBinding.cpp:35:8 (libxul.so+0x2f2518e)
[task 2022-01-13T16:52:59.556Z] 16:52:59     INFO - GECKO(3756) |     #139 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:89:12 (libxul.so+0xe99ede)
[task 2022-01-13T16:52:59.556Z] 16:52:59     INFO - GECKO(3756) |     #140 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:102:12 (libxul.so+0xe99ede)
[task 2022-01-13T16:52:59.557Z] 16:52:59     INFO - GECKO(3756) |     #141 mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:213:18 (libxul.so+0xe99ede)
[task 2022-01-13T16:52:59.557Z] 16:52:59     INFO - GECKO(3756) |     #142 mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:674:17 (libxul.so+0xe877b4)
[task 2022-01-13T16:52:59.558Z] 16:52:59     INFO - GECKO(3756) |     #143 LeaveMicroTask /builds/worker/workspace/obj-build/dist/include/mozilla/CycleCollectedJSContext.h:243:7 (libxul.so+0x3e1741a)
[task 2022-01-13T16:52:59.558Z] 16:52:59     INFO - GECKO(3756) |     #144 ~nsAutoMicroTask /builds/worker/workspace/obj-build/dist/include/mozilla/CycleCollectedJSContext.h:395:13 (libxul.so+0x3e1741a)
[task 2022-01-13T16:52:59.559Z] 16:52:59     INFO - GECKO(3756) |     #145 mozilla::EventListenerManager::HandleEventSubType(mozilla::EventListenerManager::Listener*, mozilla::dom::Event*, mozilla::dom::EventTarget*) /builds/worker/checkouts/gecko/dom/events/EventListenerManager.cpp:1311:3 (libxul.so+0x3e1741a)
[task 2022-01-13T16:52:59.560Z] 16:52:59     INFO - GECKO(3756) |     #146 mozilla::EventListenerManager::HandleEventInternal(nsPresContext*, mozilla::WidgetEvent*, mozilla::dom::Event**, mozilla::dom::EventTarget*, nsEventStatus*, bool) /builds/worker/checkouts/gecko/dom/events/EventListenerManager.cpp:1500:17 (libxul.so+0x3e17ebb)
[task 2022-01-13T16:52:59.560Z] 16:52:59     INFO - GECKO(3756) |     #147 HandleEvent /builds/worker/checkouts/gecko/dom/events/EventListenerManager.h:395:5 (libxul.so+0x3e0dfbe)
[task 2022-01-13T16:52:59.561Z] 16:52:59     INFO - GECKO(3756) |     #148 mozilla::EventTargetChainItem::HandleEvent(mozilla::EventChainPostVisitor&, mozilla::ELMCreationDetector&) /builds/worker/checkouts/gecko/dom/events/EventDispatcher.cpp:348:17 (libxul.so+0x3e0dfbe)
[task 2022-01-13T16:52:59.561Z] 16:52:59     INFO - GECKO(3756) |     #149 mozilla::EventTargetChainItem::HandleEventTargetChain(nsTArray<mozilla::EventTargetChainItem>&, mozilla::EventChainPostVisitor&, mozilla::EventDispatchingCallback*, mozilla::ELMCreationDetector&) /builds/worker/checkouts/gecko/dom/events/EventDispatcher.cpp:550:16 (libxul.so+0x3e0d349)
[task 2022-01-13T16:52:59.562Z] 16:52:59     INFO - GECKO(3756) |     #150 mozilla::EventDispatcher::Dispatch(nsISupports*, nsPresContext*, mozilla::WidgetEvent*, mozilla::dom::Event*, nsEventStatus*, mozilla::EventDispatchingCallback*, nsTArray<mozilla::dom::EventTarget*>*) /builds/worker/checkouts/gecko/dom/events/EventDispatcher.cpp:1085:11 (libxul.so+0x3e10105)
[task 2022-01-13T16:52:59.562Z] 16:52:59     INFO - GECKO(3756) |     #151 nsDocumentViewer::LoadComplete(nsresult) /builds/worker/checkouts/gecko/layout/base/nsDocumentViewer.cpp:1086:7 (libxul.so+0x5449a87)
[task 2022-01-13T16:52:59.563Z] 16:52:59     INFO - GECKO(3756) |     #152 nsDocShell::EndPageLoad(nsIWebProgress*, nsIChannel*, nsresult) /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp:6278:20 (libxul.so+0x6b5b228)
[task 2022-01-13T16:52:59.564Z] 16:52:59     INFO - GECKO(3756) |     #153 nsDocShell::OnStateChange(nsIWebProgress*, nsIRequest*, unsigned int, nsresult) /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp:5667:7 (libxul.so+0x6b5ab1a)
[task 2022-01-13T16:52:59.564Z] 16:52:59     INFO - GECKO(3756) |     #154 non-virtual thunk to nsDocShell::OnStateChange(nsIWebProgress*, nsIRequest*, unsigned int, nsresult) /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp (libxul.so+0x6b5badb)
[task 2022-01-13T16:52:59.565Z] 16:52:59     INFO - GECKO(3756) |     #155 nsDocLoader::DoFireOnStateChange(nsIWebProgress*, nsIRequest*, int&, nsresult) /builds/worker/checkouts/gecko/uriloader/base/nsDocLoader.cpp:1377:3 (libxul.so+0x20851ee)
[task 2022-01-13T16:52:59.565Z] 16:52:59     INFO - GECKO(3756) |     #156 nsDocLoader::doStopDocumentLoad(nsIRequest*, nsresult) /builds/worker/checkouts/gecko/uriloader/base/nsDocLoader.cpp:975:14 (libxul.so+0x2084b27)
[task 2022-01-13T16:52:59.566Z] 16:52:59     INFO - GECKO(3756) |     #157 nsDocLoader::DocLoaderIsEmpty(bool, mozilla::Maybe<nsresult> const&) /builds/worker/checkouts/gecko/uriloader/base/nsDocLoader.cpp:794:9 (libxul.so+0x2082fb1)
[task 2022-01-13T16:52:59.566Z] 16:52:59     INFO - GECKO(3756) |     #158 nsDocLoader::OnStopRequest(nsIRequest*, nsresult) /builds/worker/checkouts/gecko/uriloader/base/nsDocLoader.cpp:677:5 (libxul.so+0x20841f4)
[task 2022-01-13T16:52:59.567Z] 16:52:59     INFO - GECKO(3756) |     #159 nsDocShell::OnStopRequest(nsIRequest*, nsresult) /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp:13540:23 (libxul.so+0x6b7611c)
[task 2022-01-13T16:52:59.567Z] 16:52:59     INFO - GECKO(3756) |     #160 non-virtual thunk to nsDocShell::OnStopRequest(nsIRequest*, nsresult) /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp (libxul.so+0x6b76318)
[task 2022-01-13T16:52:59.568Z] 16:52:59     INFO - GECKO(3756) |     #161 mozilla::net::nsLoadGroup::NotifyRemovalObservers(nsIRequest*, nsresult) /builds/worker/checkouts/gecko/netwerk/base/nsLoadGroup.cpp:614:22 (libxul.so+0x114b16c)
[task 2022-01-13T16:52:59.568Z] 16:52:59     INFO - GECKO(3756) |     #162 mozilla::net::nsLoadGroup::RemoveRequest(nsIRequest*, nsISupports*, nsresult) /builds/worker/checkouts/gecko/netwerk/base/nsLoadGroup.cpp:518:10 (libxul.so+0x114c5a2)
[task 2022-01-13T16:52:59.569Z] 16:52:59     INFO - GECKO(3756) |     #163 nsJARChannel::OnStopRequest(nsIRequest*, nsresult) /builds/worker/checkouts/gecko/modules/libjar/nsJARChannel.cpp:1234:31 (libxul.so+0x1fc20f0)
[task 2022-01-13T16:52:59.569Z] 16:52:59     INFO - GECKO(3756) |     #164 non-virtual thunk to nsJARChannel::OnStopRequest(nsIRequest*, nsresult) /builds/worker/checkouts/gecko/modules/libjar/nsJARChannel.cpp (libxul.so+0x1fc4db2)
[task 2022-01-13T16:52:59.570Z] 16:52:59     INFO - GECKO(3756) |     #165 nsInputStreamPump::OnStateStop() /builds/worker/checkouts/gecko/netwerk/base/nsInputStreamPump.cpp:636:16 (libxul.so+0x1148fa7)
[task 2022-01-13T16:52:59.571Z] 16:52:59     INFO - GECKO(3756) |     #166 nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream*) /builds/worker/checkouts/gecko/netwerk/base/nsInputStreamPump.cpp:381:21 (libxul.so+0x1147ff2)
[task 2022-01-13T16:52:59.571Z] 16:52:59     INFO - GECKO(3756) |     #167 non-virtual thunk to nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream*) /builds/worker/checkouts/gecko/netwerk/base/nsInputStreamPump.cpp (libxul.so+0x1149269)
[task 2022-01-13T16:52:59.572Z] 16:52:59     INFO - GECKO(3756) |     #168 nsInputStreamReadyEvent::Run() /builds/worker/checkouts/gecko/xpcom/io/nsStreamUtils.cpp:94:20 (libxul.so+0xf536bc)
[task 2022-01-13T16:52:59.572Z] 16:52:59     INFO - GECKO(3756) |     #169 mozilla::RunnableTask::Run() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:468:16 (libxul.so+0xfae0b7)
[task 2022-01-13T16:52:59.573Z] 16:52:59     INFO - GECKO(3756) |     #170 mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:771:26 (libxul.so+0xf894fd)
[task 2022-01-13T16:52:59.573Z] 16:52:59     INFO - GECKO(3756) |     #171 mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:607:15 (libxul.so+0xf87bd6)
[task 2022-01-13T16:52:59.574Z] 16:52:59     INFO - GECKO(3756) |     #172 mozilla::TaskController::ProcessPendingMTTask(bool) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:391:36 (libxul.so+0xf87eb2)
[task 2022-01-13T16:52:59.574Z] 16:52:59     INFO - GECKO(3756) |     #173 operator() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:124:37 (libxul.so+0xfb10c7)
[task 2022-01-13T16:52:59.575Z] 16:52:59     INFO - GECKO(3756) |     #174 mozilla::detail::RunnableFunction<mozilla::TaskController::InitializeInternal()::$_0>::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.h:531:5 (libxul.so+0xfb10c7)
[task 2022-01-13T16:52:59.575Z] 16:52:59     INFO - GECKO(3756) |     #175 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1195:16 (libxul.so+0xf9bee3)
[task 2022-01-13T16:52:59.576Z] 16:52:59     INFO - GECKO(3756) |     #176 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:467:10 (libxul.so+0xfa2b22)
[task 2022-01-13T16:52:59.576Z] 16:52:59     INFO - GECKO(3756) |     #177 mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:85:21 (libxul.so+0x1914c3d)
[task 2022-01-13T16:52:59.577Z] 16:52:59     INFO - GECKO(3756) |     #178 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:331:10 (libxul.so+0x189155c)
[task 2022-01-13T16:52:59.577Z] 16:52:59     INFO - GECKO(3756) |     #179 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:324:3 (libxul.so+0x189155c)
[task 2022-01-13T16:52:59.578Z] 16:52:59     INFO - GECKO(3756) |     #180 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:306:3 (libxul.so+0x189155c)
[task 2022-01-13T16:52:59.578Z] 16:52:59     INFO - GECKO(3756) |     #181 nsBaseAppShell::Run() /builds/worker/checkouts/gecko/widget/nsBaseAppShell.cpp:137:27 (libxul.so+0x50913b6)
[task 2022-01-13T16:52:59.579Z] 16:52:59     INFO - GECKO(3756) |     #182 nsAppStartup::Run() /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:295:30 (libxul.so+0x6fd71b8)
[task 2022-01-13T16:52:59.579Z] 16:52:59     INFO - GECKO(3756) |     #183 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5348:22 (libxul.so+0x70effab)
[task 2022-01-13T16:52:59.580Z] 16:52:59     INFO - GECKO(3756) |     #184 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5533:8 (libxul.so+0x70f0e19)
[task 2022-01-13T16:52:59.580Z] 16:52:59     INFO - GECKO(3756) |     #185 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5592:21 (libxul.so+0x70f13e4)
[task 2022-01-13T16:52:59.581Z] 16:52:59     INFO - GECKO(3756) |     #186 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x70fbd32)
[task 2022-01-13T16:52:59.581Z] 16:52:59     INFO - GECKO(3756) |     #187 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:225:22 (firefox+0xcf494)
[task 2022-01-13T16:52:59.582Z] 16:52:59     INFO - GECKO(3756) |     #188 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:395:16 (firefox+0xcf494)
[task 2022-01-13T16:52:59.582Z] 16:52:59     INFO - GECKO(3756) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:867:23 in SetAlreadyDead
[task 2022-01-13T16:52:59.583Z] 16:52:59     INFO - GECKO(3756) | ==================
[task 2022-01-13T16:52:59.583Z] 16:52:59     INFO - GECKO(3756) | Exiting due to channel error.
[task 2022-01-13T16:52:59.584Z] 16:52:59     INFO - GECKO(3756) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=19.3064) Exiting due to channel error.
[task 2022-01-13T16:52:59.584Z] 16:52:59     INFO - GECKO(3756) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=10.3616) Exiting due to channel error.
[task 2022-01-13T16:52:59.585Z] 16:52:59     INFO - GECKO(3756) | Exiting due to channel error.
[task 2022-01-13T16:52:59.585Z] 16:52:59     INFO - GECKO(3756) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=5.2584) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=3.20681) Exiting due to channel error.
[task 2022-01-13T16:53:00.568Z] 16:53:00     INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2022-01-13T16:53:00.569Z] 16:53:00     INFO - Buffered messages logged at 16:52:57
[task 2022-01-13T16:53:00.570Z] 16:53:00     INFO - Entering test bound 
[task 2022-01-13T16:53:00.570Z] 16:53:00     INFO - Opening http://example.org/browser/toolkit/components/glean/tests/browser/small-shot.ogg in a new tab to trigger the creation of the RDD process
[task 2022-01-13T16:53:00.570Z] 16:53:00     INFO - Buffered messages finished
[task 2022-01-13T16:53:00.571Z] 16:53:00    ERROR - TEST-UNEXPECTED-FAIL | toolkit/components/glean/tests/browser/browser_fog_rdd.js | application terminated with exit code -6
[task 2022-01-13T16:53:00.571Z] 16:53:00     INFO - runtests.py | Application ran for: 0:00:28.837769

It looks like a runnable is calling GMPProcessParent::DoDelete()

#0 SetAlreadyDead /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:867:23 (libxul.so+0x18e5508)
#1 mozilla::ipc::GeckoChildProcessHost::Join() /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:858:3 (libxul.so+0x18e5508)
#2 mozilla::gmp::GMPProcessParent::DoDelete() /builds/worker/checkouts/gecko/dom/media/gmp/GMPProcessParent.cpp:165:3 (libxul.so+0x432a84b)
#3 applyImpl<mozilla::gmp::GMPProcessParent, void (mozilla::gmp::GMPProcessParent::)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1147:12 (libxul.so+0x434fa46)
#4 apply<mozilla::gmp::GMPProcessParent, void (mozilla::gmp::GMPProcessParent::
)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1153:12 (libxul.so+0x434fa46)
#5 mozilla::detail::RunnableMethodImpl<mozilla::gmp::GMPProcessParent*, void (mozilla::gmp::GMPProcessParent::*)(), false, (mozilla::RunnableKind)0>::Run() /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1200:13 (libxul.so+0x434fa46)

There's also a "As if synchronized via sleep" stack, which I don't think I've seen before.

Blocks: tsan
Group: core-security → dom-core-security
Keywords: csectype-race

Fixed by backout of bug 1745511.

Group: dom-core-security → core-security-release
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 98 Branch
Has Regression Range: --- → yes
Keywords: regression
Regressed by: 1745511
Component: IPC → Audio/Video

Andrew, my understanding from reading the TSan report here is that there's a permanent failure when running the new test I was trying to land, but I don't see anything in the stacks reported here that look like the platform code I modified. Is it possible that my test exposed an existing issue in code that didn't have this edge case covered? If so, would it be a reasonable way forward to reland the test (after fixing the other issues that caused the backout) with a skip-if = tsan, and reopen this bug for future investigations?

Flags: needinfo?(continuation)

It seems like a bad idea to disable a TSan test for some very thread-heavy code. It isn't so clear to me that this is necessarily unrelated to your changes. GeckoMediaPluginServiceParent::SendFlushFOGData() could change the lifetime of some GMP stuff.

That said, it looked like your patch got backed out for some thread-related assertions, so it is possible that your fixes for that issue also fixed the TSan issue. Have you pushed the latest version to try to see if the TSan issue is still occurring?

I was going to suggest that you ask Bryce for their thoughts, but they appear to be away for at least a few more weeks.

Flags: needinfo?(continuation)

(In reply to Andrew McCreight [:mccr8] from comment #5)

It seems like a bad idea to disable a TSan test for some very thread-heavy code. It isn't so clear to me that this is necessarily unrelated to your changes.

To verify this, I just pushed to try my test without any of the platform changes: https://treeherder.mozilla.org/jobs?repo=try&tier=1%2C2%2C3&revision=a1b19e97428f85079d1aadec1f299bc09252007b

I see the same failure, so I'm now pretty confident the issue existed before my patch.

(In reply to Andrew McCreight [:mccr8] from comment #2)

There's also a "As if synchronized via sleep" stack, which I don't think I've seen before.

There's an explanation on the sanitizer wiki, which I don't entirely understand, but from the way it's explained, if that sleep were removed — which I'm planning to do in bug 1658072 — we'd have the same TSan error without the sleep annotation. But I could try pushing to Try with the patch from comment #6 and my work-in-progress for the ProcessWatcher/KillProcess changes to see if anything interesting happens.

Actually, my changes prevent the failure entirely, which makes sense given that the offending write is from GeckoChildProcessHost::Join and I removed that function. That part belongs to bug 1658474, which I believe I can land separately.

But I don't think that actually fixes the bug here: the thread safety situation around SetAlreadyDead and accessing mChildProcessHandle doesn't look good. This needs more investigation.

Status: RESOLVED → REOPENED
Component: Audio/Video → IPC
Resolution: FIXED → ---

(In reply to Florian Quèze [:florian] from comment #6)

I see the same failure, so I'm now pretty confident the issue existed before my patch.

I guess it is fine to land it disabled with TSan then. Thanks for checking.

Keywords: sec-moderate
See Also: → 1658474, 1658072

Clarifying that this appears to fail reliably; on Treeherder, out of the 11 runs between the first landing of bug 1745511 and the push linked at the top of this bug, all 11 failed.

Summary: Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:867:23 in SetAlreadyDead → Permafail SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:867:23 in SetAlreadyDead

Resolved via bug 1658474. But this raises a question: should I try to do something about uplift? Publicly that patch is just cleanup + a minor responsiveness improvement, so wouldn't be uplifted.

For what it's worth, this bug seems hard to exploit: technically the race is UB but I think the worst practical case is something using a handle to some other process or object as if it were the dead GMP process, but GMP is most sandboxed / least trusted process type, so I don't think there's anything interesting there.

Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Flags: needinfo?(dveditz)
Resolution: --- → FIXED
Assignee: nobody → jld
Depends on: 1658474

The status flags are wrong about old branches being "unaffected" then? This specific crash didn't come up in prior branches--I suppose because the bug 1745511 patch made an unlikely race into a sure thing--but the underlying problem fixed in bug 1658474 still existed? The patch doesn't look all that hard to backport. On the other hand this is not directly accessible to child content.

should I try to do something about uplift?

This is fixed on 98 so it's already on the beta channel. The only place left to uplift would be ESR-91. Probably safer to leave it alone there.

Flags: needinfo?(dveditz)
Whiteboard: [fixed by bug 1658474]
QA Whiteboard: [post-critsmash-triage]
Flags: qe-verify-
Group: core-security-release
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: