Closed Bug 1681646 Opened 4 years ago Closed 4 years ago

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:311:36 in operator!

Categories

(Toolkit :: Data Sanitization, defect, P5)

defect

Tracking

()

RESOLVED FIXED
86 Branch
Tracking Status
firefox86 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [retriggered])

Attachments

(1 file)

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


[task 2020-12-10T02:05:21.579Z] 02:05:21     INFO -  TEST-START | toolkit/components/cleardata/tests/unit/test_downloads.js
[task 2020-12-10T02:05:24.327Z] 02:05:24  WARNING -  TEST-UNEXPECTED-FAIL | toolkit/components/cleardata/tests/unit/test_downloads.js | xpcshell return code: -6
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  TEST-INFO took 2740ms
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  >>>>>>>
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  running event loop
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  toolkit/components/cleardata/tests/unit/test_downloads.js | Starting test_all_downloads
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  (xpcshell/head.js) | test test_all_downloads pending (2)
[task 2020-12-10T02:05:24.327Z] 02:05:24     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_all_downloads - [test_all_downloads : 50] Sanity check the temporary file doesn't exist. - true == true
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_all_downloads - [test_all_downloads : 51] true == true
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_all_downloads - [test_all_downloads : 68] 1 == 1
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_all_downloads - [test_all_downloads : 74] 0 == 0
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_all_downloads - [test_all_downloads : 83] 0 == 0
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test test_all_downloads finished (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  toolkit/components/cleardata/tests/unit/test_downloads.js | Starting test_range_downloads
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test test_range_downloads pending (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_range_downloads - [test_range_downloads : 50] Sanity check the temporary file doesn't exist. - true == true
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_range_downloads - [test_range_downloads : 94] true == true
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_range_downloads - [test_range_downloads : 102] 1 == 1
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  PID 14631 | (xpcshell:14631): GLib-GObject-CRITICAL **: 02:05:23.592: g_object_get: assertion 'G_IS_OBJECT (object)' failed
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_range_downloads - [test_range_downloads : 124] 0 == 0
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_range_downloads - [test_range_downloads : 133] 0 == 0
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test test_range_downloads finished (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  toolkit/components/cleardata/tests/unit/test_downloads.js | Starting test_principal_downloads
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test test_principal_downloads pending (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (2)
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 50] Sanity check the temporary file doesn't exist. - true == true
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 143] true == true
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 50] Sanity check the temporary file doesn't exist. - true == true
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 150] true == true
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 154] 2 == 2
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 181] 0 == 0
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 190] 1 == 1
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 208] 0 == 0
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  TEST-PASS | toolkit/components/cleardata/tests/unit/test_downloads.js | test_principal_downloads - [test_principal_downloads : 217] 0 == 0
[task 2020-12-10T02:05:24.328Z] 02:05:24     INFO -  (xpcshell/head.js) | test run_next_test 3 pending (2)
[task 2020-12-10T02:05:24.329Z] 02:05:24     INFO -  (xpcshell/head.js) | test test_principal_downloads finished (2)
[task 2020-12-10T02:05:24.330Z] 02:05:24     INFO -  (xpcshell/head.js) | test run_next_test 3 finished (1)[task 2020-12-10T02:05:24.330Z] 02:05:24     INFO -  exiting test
[task 2020-12-10T02:05:24.330Z] 02:05:24     INFO -  PID 14631 | error: address range table at offset 0x5bb0 has an invalid tuple (length = 0) at offset 0x5bd0
[task 2020-12-10T02:05:24.331Z] 02:05:24     INFO -  PID 14631 | ==================
[task 2020-12-10T02:05:24.331Z] 02:05:24     INFO -  PID 14631 | WARNING: ThreadSanitizer: data race (pid=14631)
[task 2020-12-10T02:05:24.332Z] 02:05:24     INFO -  PID 14631 |   Read of size 8 at 0x7b2c0002d568 by main thread:
[task 2020-12-10T02:05:24.332Z] 02:05:24     INFO -  PID 14631 |     #0 operator! /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:311:36 (libxul.so+0x6686410)
[task 2020-12-10T02:05:24.333Z] 02:05:24     INFO -  PID 14631 |     #1 IsDBOpened /builds/worker/checkouts/gecko/toolkit/components/url-classifier/nsUrlClassifierDBService.h:189:37 (libxul.so+0x6686410)
[task 2020-12-10T02:05:24.333Z] 02:05:24     INFO -  PID 14631 |     #2 nsUrlClassifierDBService::Shutdown() /builds/worker/checkouts/gecko/toolkit/components/url-classifier/nsUrlClassifierDBService.cpp:2334:16 (libxul.so+0x6686410)
[task 2020-12-10T02:05:24.333Z] 02:05:24     INFO -  PID 14631 |     #3 nsUrlClassifierDBService::Observe(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/toolkit/components/url-classifier/nsUrlClassifierDBService.cpp:2287:5 (libxul.so+0x6686098)
[task 2020-12-10T02:05:24.334Z] 02:05:24     INFO -  PID 14631 |     #4 non-virtual thunk to nsUrlClassifierDBService::Observe(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/toolkit/components/url-classifier/nsUrlClassifierDBService.cpp (libxul.so+0x6686a72)
[task 2020-12-10T02:05:24.334Z] 02:05:24     INFO -  PID 14631 |     #5 nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/xpcom/ds/nsObserverList.cpp:70:19 (libxul.so+0xaeabf3)
[task 2020-12-10T02:05:24.334Z] 02:05:24     INFO -  PID 14631 |     #6 nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/xpcom/ds/nsObserverService.cpp:287:19 (libxul.so+0xaed92a)
[task 2020-12-10T02:05:24.335Z] 02:05:24     INFO -  PID 14631 |     #7 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0xbabb95)
[task 2020-12-10T02:05:24.335Z] 02:05:24     INFO -  PID 14631 |     #8 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:925:10 (libxul.so+0x1a4e330)
[task 2020-12-10T02:05:24.335Z] 02:05:24     INFO -  PID 14631 |     #9 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:503:13 (libxul.so+0x6825f59)
[task 2020-12-10T02:05:24.336Z] 02:05:24     INFO -  PID 14631 |     #10 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:594:12 (libxul.so+0x6825f59)
[task 2020-12-10T02:05:24.336Z] 02:05:24     INFO -  PID 14631 |     #11 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x68271db)
[task 2020-12-10T02:05:24.337Z] 02:05:24     INFO -  PID 14631 |     #12 js::CallFromStack(JSContext*, JS::CallArgs const&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:651:10 (libxul.so+0x68271db)
[task 2020-12-10T02:05:24.337Z] 02:05:24     INFO -  PID 14631 |     #13 js::jit::DoCallFallback(JSContext*, js::jit::BaselineFrame*, js::jit::ICCall_Fallback*, unsigned int, JS::Value*, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jit/BaselineIC.cpp:1972:10 (libxul.so+0x70267bb)
[task 2020-12-10T02:05:24.337Z] 02:05:24     INFO -  PID 14631 |     #14 <null> <null> (0x7f0f83abfa68)
[task 2020-12-10T02:05:24.338Z] 02:05:24     INFO -  PID 14631 |     #15 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:2231:17 (libxul.so+0x680f022)
[task 2020-12-10T02:05:24.338Z] 02:05:24     INFO -  PID 14631 |     #16 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:473:13 (libxul.so+0x680df5c)
[task 2020-12-10T02:05:24.338Z] 02:05:24     INFO -  PID 14631 |     #17 js::ExecuteKernel(JSContext*, JS::Handle<JSScript*>, JS::Handle<JSObject*>, JS::Handle<JS::Value>, js::AbstractFramePtr, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:839:13 (libxul.so+0x68288fa)
[task 2020-12-10T02:05:24.339Z] 02:05:24     INFO -  PID 14631 |     #18 js::Execute(JSContext*, JS::Handle<JSScript*>, JS::Handle<JSObject*>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:871:10 (libxul.so+0x6828ae8)
[task 2020-12-10T02:05:24.339Z] 02:05:24     INFO -  PID 14631 |     #19 EvaluateSourceBuffer<mozilla::Utf8Unit> /builds/worker/checkouts/gecko/js/src/vm/CompilationAndEvaluation.cpp:525:10 (libxul.so+0x6968a84)
[task 2020-12-10T02:05:24.340Z] 02:05:24     INFO -  PID 14631 |     #20 JS::Evaluate(JSContext*, JS::ReadOnlyCompileOptions const&, JS::SourceText<mozilla::Utf8Unit>&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CompilationAndEvaluation.cpp:533:10 (libxul.so+0x6968a84)
[task 2020-12-10T02:05:24.340Z] 02:05:24     INFO -  PID 14631 |     #21 ProcessArgs(mozilla::dom::AutoJSAPI&, char**, int, XPCShellDirProvider*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCShellImpl.cpp:961:11 (libxul.so+0x1a3bc21)
[task 2020-12-10T02:05:24.340Z] 02:05:24     INFO -  PID 14631 |     #22 XRE_XPCShellMain(int, char**, char**, XREShellData const*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCShellImpl.cpp:1349:14 (libxul.so+0x1a3a65b)
[task 2020-12-10T02:05:24.341Z] 02:05:24     INFO -  PID 14631 |     #23 mozilla::BootstrapImpl::XRE_XPCShellMain(int, char**, char**, XREShellData const*) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:54:12 (libxul.so+0x66fc4cb)
[task 2020-12-10T02:05:24.341Z] 02:05:24     INFO -  PID 14631 |     #24 main /builds/worker/checkouts/gecko/js/xpconnect/shell/xpcshell.cpp:67:27 (xpcshell+0xc73f6)
[task 2020-12-10T02:05:24.341Z] 02:05:24     INFO -  PID 14631 |   Previous write of size 8 at 0x7b2c0002d568 by thread T19:
[task 2020-12-10T02:05:24.342Z] 02:05:24     INFO -  PID 14631 |     #0 assign_assuming_AddRef /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:67:13 (libxul.so+0x6678050)
[task 2020-12-10T02:05:24.342Z] 02:05:24     INFO -  PID 14631 |     #1 assign_with_AddRef /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:62:5 (libxul.so+0x6678050)
[task 2020-12-10T02:05:24.343Z] 02:05:24     INFO -  PID 14631 |     #2 operator= /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:175:5 (libxul.so+0x6678050)[task 2020-12-10T02:05:24.343Z] 02:05:24     INFO -  PID 14631 |     #3 nsUrlClassifierDBServiceWorker::OpenDb() /builds/worker/checkouts/gecko/toolkit/components/url-classifier/nsUrlClassifierDBService.cpp:1096:15 (libxul.so+0x6678050)
[task 2020-12-10T02:05:24.343Z] 02:05:24     INFO -  PID 14631 |     #4 applyImpl<nsUrlClassifierDBServiceWorker, nsresult (nsUrlClassifierDBServiceWorker::*)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1148:12 (libxul.so+0x6698296)
[task 2020-12-10T02:05:24.344Z] 02:05:24     INFO -  PID 14631 |     #5 apply<nsUrlClassifierDBServiceWorker, nsresult (nsUrlClassifierDBServiceWorker::*)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1154:12 (libxul.so+0x6698296)
[task 2020-12-10T02:05:24.344Z] 02:05:24     INFO -  PID 14631 |     #6 mozilla::detail::RunnableMethodImpl<RefPtr<nsUrlClassifierDBServiceWorker> const, nsresult (nsUrlClassifierDBServiceWorker::*)(), true, (mozilla::RunnableKind)0>::Run() /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1201:13 (libxul.so+0x6698296)
[task 2020-12-10T02:05:24.344Z] 02:05:24     INFO -  PID 14631 |     #7 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1200:14 (libxul.so+0xb8455c)
[task 2020-12-10T02:05:24.345Z] 02:05:24     INFO -  PID 14631 |     #8 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10 (libxul.so+0xb8a012)
[task 2020-12-10T02:05:24.345Z] 02:05:24     INFO -  PID 14631 |     #9 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:332:5 (libxul.so+0x147a888)
[task 2020-12-10T02:05:24.345Z] 02:05:24     INFO -  PID 14631 |     #10 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:334:10 (libxul.so+0x140541c)
[task 2020-12-10T02:05:24.345Z] 02:05:24     INFO -  PID 14631 |     #11 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:327:3 (libxul.so+0x140541c)
[task 2020-12-10T02:05:24.346Z] 02:05:24     INFO -  PID 14631 |     #12 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:309:3 (libxul.so+0x140541c)
[task 2020-12-10T02:05:24.352Z] 02:05:24     INFO -  PID 14631 |     #13 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:441:10 (libxul.so+0xb809b0)
[task 2020-12-10T02:05:24.353Z] 02:05:24     INFO -  PID 14631 |     #14 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x4500b)
[task 2020-12-10T02:05:24.353Z] 02:05:24     INFO -  PID 14631 |   Location is heap block of size 176 at 0x7b2c0002d550 allocated by main thread:
[task 2020-12-10T02:05:24.354Z] 02:05:24     INFO -  PID 14631 |     #0 malloc /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:652:5 (xpcshell+0x54dfc)
[task 2020-12-10T02:05:24.354Z] 02:05:24     INFO -  PID 14631 |     #1 operator new /builds/worker/workspace/obj-build/dist/include/mozilla/cxxalloc.h:38:10 (libxul.so+0x667f938)
[task 2020-12-10T02:05:24.354Z] 02:05:24     INFO -  PID 14631 |     #2 nsUrlClassifierDBService::Init() /builds/worker/checkouts/gecko/toolkit/components/url-classifier/nsUrlClassifierDBService.cpp:1663:13 (libxul.so+0x667f938)
[task 2020-12-10T02:05:24.355Z] 02:05:24     INFO -  PID 14631 |     #3 nsUrlClassifierDBService::GetInstance(nsresult*) /builds/worker/checkouts/gecko/toolkit/components/url-classifier/nsUrlClassifierDBService.cpp:1596:40 (libxul.so+0x667f707)
[task 2020-12-10T02:05:24.355Z] 02:05:24     INFO -  PID 14631 |     #4 nsUrlClassifierDBServiceConstructor(nsISupports*, nsID const&, void**) /builds/worker/checkouts/gecko/toolkit/components/build/nsToolkitCompsModule.cpp:31:7 (libxul.so+0x66cdf0e)
[task 2020-12-10T02:05:24.356Z] 02:05:24     INFO -  PID 14631 |     #5 mozilla::xpcom::CreateInstanceImpl(mozilla::xpcom::ModuleID, nsISupports*, nsID const&, void**) /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:10353:27 (libxul.so+0xb4e40d)
[task 2020-12-10T02:05:24.356Z] 02:05:24     INFO -  PID 14631 |     #6 mozilla::xpcom::StaticModule::CreateInstance(nsISupports*, nsID const&, void**) const /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:12253:10 (libxul.so+0xb468d0)
[task 2020-12-10T02:05:24.357Z] 02:05:24     INFO -  PID 14631 |     #7 CreateInstance /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:176:46 (libxul.so+0xb5d49e)
[task 2020-12-10T02:05:24.357Z] 02:05:24     INFO -  PID 14631 |     #8 nsComponentManagerImpl::GetServiceLocked(mozilla::Maybe<mozilla::MonitorAutoLock>&, (anonymous namespace)::EntryWrapper&, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1282:17 (libxul.so+0xb5d49e)
[task 2020-12-10T02:05:24.357Z] 02:05:24     INFO -  PID 14631 |     #9 nsComponentManagerImpl::GetService(mozilla::xpcom::ModuleID, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1372:10 (libxul.so+0xb5da74)
[task 2020-12-10T02:05:24.357Z] 02:05:24     INFO -  PID 14631 |     #10 mozilla::xpcom::GetServiceHelper::operator()(nsID const&, void**) const /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:12362:50 (libxul.so+0xb509c7)
[task 2020-12-10T02:05:24.358Z] 02:05:24     INFO -  PID 14631 |     #11 nsCOMPtr_base::assign_from_helper(nsCOMPtr_helper const&, nsID const&) /builds/worker/checkouts/gecko/xpcom/base/nsCOMPtr.cpp:109:7 (libxul.so+0xaa47ff)
[task 2020-12-10T02:05:24.358Z] 02:05:24     INFO -  PID 14631 |     #12 nsCOMPtr /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:652:5 (libxul.so+0x658fe15)
[task 2020-12-10T02:05:24.358Z] 02:05:24     INFO -  PID 14631 |     #13 PendingDBLookup::LookupSpecInternal(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/toolkit/components/reputationservice/ApplicationReputation.cpp:823:7 (libxul.so+0x658fe15)
[task 2020-12-10T02:05:24.359Z] 02:05:24     INFO -  PID 14631 |     #14 PendingDBLookup::LookupSpec(nsTSubstring<char> const&, LookupType const&) /builds/worker/checkouts/gecko/toolkit/components/reputationservice/ApplicationReputation.cpp:791:17 (libxul.so+0x658fb1a)
[task 2020-12-10T02:05:24.359Z] 02:05:24     INFO -  PID 14631 |     #15 PendingLookup::LookupNext() /builds/worker/checkouts/gecko/toolkit/components/reputationservice/ApplicationReputation.cpp:1013:20 (libxul.so+0x6590269)
[task 2020-12-10T02:05:24.359Z] 02:05:24     INFO -  PID 14631 |     #16 PendingLookup::DoLookupInternal() /builds/worker/checkouts/gecko/toolkit/components/reputationservice/ApplicationReputation.cpp:1458:10 (libxul.so+0x659522a)
[task 2020-12-10T02:05:24.359Z] 02:05:24     INFO -  PID 14631 |     #17 StartLookup /builds/worker/checkouts/gecko/toolkit/components/reputationservice/ApplicationReputation.cpp:1278:17 (libxul.so+0x6598568)
[task 2020-12-10T02:05:24.360Z] 02:05:24     INFO -  PID 14631 |     #18 ApplicationReputationService::QueryReputationInternal(nsIApplicationReputationQuery*, nsIApplicationReputationCallback*) /builds/worker/checkouts/gecko/toolkit/components/reputationservice/ApplicationReputation.cpp:1984:18 (libxul.so+0x6598568)
[task 2020-12-10T02:05:24.360Z] 02:05:24     INFO -  PID 14631 |     #19 ApplicationReputationService::QueryReputation(nsIApplicationReputationQuery*, nsIApplicationReputationCallback*) /builds/worker/checkouts/gecko/toolkit/components/reputationservice/ApplicationReputation.cpp:1940:17 (libxul.so+0x659837c)
[task 2020-12-10T02:05:24.360Z] 02:05:24     INFO -  PID 14631 |     #20 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0xbabb95)
[task 2020-12-10T02:05:24.361Z] 02:05:24     INFO -  PID 14631 |     #21 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:925:10 (libxul.so+0x1a4e330)
[task 2020-12-10T02:05:24.361Z] 02:05:24     INFO -  PID 14631 |     #22 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:503:13 (libxul.so+0x6825f59)
[task 2020-12-10T02:05:24.361Z] 02:05:24     INFO -  PID 14631 |     #23 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:594:12 (libxul.so+0x6825f59)
[task 2020-12-10T02:05:24.361Z] 02:05:24     INFO -  PID 14631 |     #24 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x681b60b)
[task 2020-12-10T02:05:24.362Z] 02:05:24     INFO -  PID 14631 |     #25 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:651:10 (libxul.so+0x681b60b)
[task 2020-12-10T02:05:24.362Z] 02:05:24     INFO -  PID 14631 |     #26 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3309:16 (libxul.so+0x681b60b)
[task 2020-12-10T02:05:24.362Z] 02:05:24     INFO -  PID 14631 |     #27 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:473:13 (libxul.so+0x680df5c)
[task 2020-12-10T02:05:24.362Z] 02:05:24     INFO -  PID 14631 |     #28 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:619:13 (libxul.so+0x68267f0)
[task 2020-12-10T02:05:24.363Z] 02:05:24     INFO -  PID 14631 |     #29 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x6827376)[task 2020-12-10T02:05:24.363Z] 02:05:24     INFO -  PID 14631 |     #30 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:664:8 (libxul.so+0x6827376)
[task 2020-12-10T02:05:24.363Z] 02:05:24     INFO -  PID 14631 |     #31 js::PromiseObject::create(JSContext*, JS::Handle<JSObject*>, JS::Handle<JSObject*>, bool) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:2446:15 (libxul.so+0x69b10b9)
[task 2020-12-10T02:05:24.364Z] 02:05:24     INFO -  PID 14631 |     #32 PromiseConstructor(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:2367:7 (libxul.so+0x69dd6af)
[task 2020-12-10T02:05:24.364Z] 02:05:24     INFO -  PID 14631 |     #33 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:503:13 (libxul.so+0x6827f6b)
[task 2020-12-10T02:05:24.365Z] 02:05:24     INFO -  PID 14631 |     #34 CallJSNativeConstructor /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:519:8 (libxul.so+0x6827f6b)
[task 2020-12-10T02:05:24.365Z] 02:05:24     INFO -  PID 14631 |     #35 InternalConstruct(JSContext*, js::AnyConstructArgs const&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:691:14 (libxul.so+0x6827f6b)
[task 2020-12-10T02:05:24.365Z] 02:05:24     INFO -  PID 14631 |     #36 ConstructFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:737:10 (libxul.so+0x681b54d)
[task 2020-12-10T02:05:24.366Z] 02:05:24     INFO -  PID 14631 |     #37 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3299:16 (libxul.so+0x681b54d)
[task 2020-12-10T02:05:24.366Z] 02:05:24     INFO -  PID 14631 |     #38 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:473:13 (libxul.so+0x680df5c)
[task 2020-12-10T02:05:24.366Z] 02:05:24     INFO -  PID 14631 |     #39 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:619:13 (libxul.so+0x68267f0)
[task 2020-12-10T02:05:24.367Z] 02:05:24     INFO -  PID 14631 |     #40 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x6827376)
[task 2020-12-10T02:05:24.367Z] 02:05:24     INFO -  PID 14631 |     #41 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:664:8 (libxul.so+0x6827376)
[task 2020-12-10T02:05:24.368Z] 02:05:24     INFO -  PID 14631 |     #42 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:1698:10 (libxul.so+0x6b35e25)
[task 2020-12-10T02:05:24.368Z] 02:05:24     INFO -  PID 14631 |     #43 js::jit::InterpretResume(JSContext*, JS::Handle<JSObject*>, JS::Value*, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jit/VMFunctions.cpp:1376:10 (libxul.so+0x70750b9)
[task 2020-12-10T02:05:24.369Z] 02:05:24     INFO -  PID 14631 |     #44 <null> <null> (0x7f0f83ac07b0)
[task 2020-12-10T02:05:24.369Z] 02:05:24     INFO -  PID 14631 |     #45 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:463:32 (libxul.so+0x680de8e)
[task 2020-12-10T02:05:24.370Z] 02:05:24     INFO -  PID 14631 |     #46 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:619:13 (libxul.so+0x68267f0)
[task 2020-12-10T02:05:24.370Z] 02:05:24     INFO -  PID 14631 |     #47 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x6827376)
[task 2020-12-10T02:05:24.370Z] 02:05:24     INFO -  PID 14631 |     #48 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:664:8 (libxul.so+0x6827376)
[task 2020-12-10T02:05:24.371Z] 02:05:24     INFO -  PID 14631 |     #49 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:1698:10 (libxul.so+0x6b35e25)
[task 2020-12-10T02:05:24.371Z] 02:05:24     INFO -  PID 14631 |     #50 AsyncFunctionResume(JSContext*, JS::Handle<js::AsyncFunctionGeneratorObject*>, ResumeKind, JS::Handle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/AsyncFunction.cpp:129:8 (libxul.so+0x6931ebb)
[task 2020-12-10T02:05:24.372Z] 02:05:24     INFO -  PID 14631 |     #51 js::AsyncFunctionAwaitedFulfilled(JSContext*, JS::Handle<js::AsyncFunctionGeneratorObject*>, JS::Handle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/AsyncFunction.cpp:160:10 (libxul.so+0x6931c37)
[task 2020-12-10T02:05:24.372Z] 02:05:24     INFO -  PID 14631 |     #52 AsyncFunctionPromiseReactionJob /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:1698:12 (libxul.so+0x69e3307)
[task 2020-12-10T02:05:24.373Z] 02:05:24     INFO -  PID 14631 |     #53 PromiseReactionJob(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:1854:12 (libxul.so+0x69e3307)
[task 2020-12-10T02:05:24.373Z] 02:05:24     INFO -  PID 14631 |     #54 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:503:13 (libxul.so+0x6825f59)
[task 2020-12-10T02:05:24.373Z] 02:05:24     INFO -  PID 14631 |     #55 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:594:12 (libxul.so+0x6825f59)
[task 2020-12-10T02:05:24.374Z] 02:05:24     INFO -  PID 14631 |     #56 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x6827376)
[task 2020-12-10T02:05:24.374Z] 02:05:24     INFO -  PID 14631 |     #57 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:664:8 (libxul.so+0x6827376)
[task 2020-12-10T02:05:24.375Z] 02:05:24     INFO -  PID 14631 |     #58 JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jsapi.cpp:2838:10 (libxul.so+0x6cec43e)
[task 2020-12-10T02:05:24.375Z] 02:05:24     INFO -  PID 14631 |     #59 mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/PromiseBinding.cpp:31:8 (libxul.so+0x2a9024b)
[task 2020-12-10T02:05:24.376Z] 02:05:24     INFO -  PID 14631 |     #60 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:90:12 (libxul.so+0xa926ee)
[task 2020-12-10T02:05:24.376Z] 02:05:24     INFO -  PID 14631 |     #61 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:103:12 (libxul.so+0xa926ee)
[task 2020-12-10T02:05:24.377Z] 02:05:24     INFO -  PID 14631 |     #62 mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:211:18 (libxul.so+0xa926ee)
[task 2020-12-10T02:05:24.377Z] 02:05:24     INFO -  PID 14631 |     #63 mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:644:17 (libxul.so+0xa7f0e1)
[task 2020-12-10T02:05:24.378Z] 02:05:24     INFO -  PID 14631 |     #64 mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:461:3 (libxul.so+0xa7fa67)
[task 2020-12-10T02:05:24.378Z] 02:05:24     INFO -  PID 14631 |     #65 XPCJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1466:28 (libxul.so+0x1a18135)
[task 2020-12-10T02:05:24.379Z] 02:05:24     INFO -  PID 14631 |     #66 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1236:24 (libxul.so+0xb84891)
[task 2020-12-10T02:05:24.379Z] 02:05:24     INFO -  PID 14631 |     #67 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10 (libxul.so+0xb8a012)
[task 2020-12-10T02:05:24.380Z] 02:05:24     INFO -  PID 14631 |     #68 SpinEventLoopUntil<mozilla::ProcessFailureBehavior::ReportToCaller, (lambda at /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:727:36)> /builds/worker/workspace/obj-build/dist/include/mozilla/SpinEventLoopUntil.h:93:25 (libxul.so+0xb89a92)
[task 2020-12-10T02:05:24.380Z] 02:05:24     INFO -  PID 14631 |     #69 nsThreadManager::SpinEventLoopUntilInternal(nsINestedEventLoopCondition*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:727:8 (libxul.so+0xb89a92)
[task 2020-12-10T02:05:24.381Z] 02:05:24     INFO -  PID 14631 |     #70 nsThreadManager::SpinEventLoopUntil(nsINestedEventLoopCondition*) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:706:10 (libxul.so+0xb89917)
[task 2020-12-10T02:05:24.381Z] 02:05:24     INFO -  PID 14631 |     #71 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0xbabb95)
[task 2020-12-10T02:05:24.382Z] 02:05:24     INFO -  PID 14631 |     #72 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:925:10 (libxul.so+0x1a4e330)
[task 2020-12-10T02:05:24.382Z] 02:05:24     INFO -  PID 14631 |     #73 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:503:13 (libxul.so+0x6825f59)[task 2020-12-10T02:05:24.424Z] 02:05:24     INFO -  PID 14631 |     #63 JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jsapi.cpp:2838:10 (libxul.so+0x6cec43e)
[task 2020-12-10T02:05:24.425Z] 02:05:24     INFO -  PID 14631 |     #64 mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/PromiseBinding.cpp:31:8 (libxul.so+0x2a9024b)
[task 2020-12-10T02:05:24.429Z] 02:05:24     INFO -  PID 14631 |     #65 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:90:12 (libxul.so+0xa926ee)
[task 2020-12-10T02:05:24.429Z] 02:05:24     INFO -  PID 14631 |     #66 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:103:12 (libxul.so+0xa926ee)
[task 2020-12-10T02:05:24.429Z] 02:05:24     INFO -  PID 14631 |     #67 mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:211:18 (libxul.so+0xa926ee)
[task 2020-12-10T02:05:24.429Z] 02:05:24     INFO -  PID 14631 |     #68 mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:644:17 (libxul.so+0xa7f0e1)
[task 2020-12-10T02:05:24.429Z] 02:05:24     INFO -  PID 14631 |     #69 mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:461:3 (libxul.so+0xa7fa67)
[task 2020-12-10T02:05:24.429Z] 02:05:24     INFO -  PID 14631 |     #70 XPCJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1466:28 (libxul.so+0x1a18135)
[task 2020-12-10T02:05:24.429Z] 02:05:24     INFO -  PID 14631 |     #71 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1236:24 (libxul.so+0xb84891)
[task 2020-12-10T02:05:24.430Z] 02:05:24     INFO -  PID 14631 |     #72 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10 (libxul.so+0xb8a012)
[task 2020-12-10T02:05:24.430Z] 02:05:24     INFO -  PID 14631 |     #73 SpinEventLoopUntil<mozilla::ProcessFailureBehavior::ReportToCaller, (lambda at /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:727:36)> /builds/worker/workspace/obj-build/dist/include/mozilla/SpinEventLoopUntil.h:93:25 (libxul.so+0xb89a92)
[task 2020-12-10T02:05:24.430Z] 02:05:24     INFO -  PID 14631 |     #74 nsThreadManager::SpinEventLoopUntilInternal(nsINestedEventLoopCondition*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:727:8 (libxul.so+0xb89a92)
[task 2020-12-10T02:05:24.430Z] 02:05:24     INFO -  PID 14631 |     #75 nsThreadManager::SpinEventLoopUntil(nsINestedEventLoopCondition*) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:706:10 (libxul.so+0xb89917)
[task 2020-12-10T02:05:24.430Z] 02:05:24     INFO -  PID 14631 |     #76 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0xbabb95)
[task 2020-12-10T02:05:24.431Z] 02:05:24     INFO -  PID 14631 |     #77 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:925:10 (libxul.so+0x1a4e330)
[task 2020-12-10T02:05:24.431Z] 02:05:24     INFO -  PID 14631 |     #78 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:503:13 (libxul.so+0x6825f59)
[task 2020-12-10T02:05:24.432Z] 02:05:24     INFO -  PID 14631 |     #79 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:594:12 (libxul.so+0x6825f59)
[task 2020-12-10T02:05:24.433Z] 02:05:24     INFO -  PID 14631 |     #80 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x681b60b)
[task 2020-12-10T02:05:24.433Z] 02:05:24     INFO -  PID 14631 |     #81 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:651:10 (libxul.so+0x681b60b)
[task 2020-12-10T02:05:24.433Z] 02:05:24     INFO -  PID 14631 |     #82 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3309:16 (libxul.so+0x681b60b)
[task 2020-12-10T02:05:24.434Z] 02:05:24     INFO -  PID 14631 |     #83 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:473:13 (libxul.so+0x680df5c)
[task 2020-12-10T02:05:24.434Z] 02:05:24     INFO -  PID 14631 |     #84 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:619:13 (libxul.so+0x68267f0)
[task 2020-12-10T02:05:24.434Z] 02:05:24     INFO -  PID 14631 |     #85 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x68271db)
[task 2020-12-10T02:05:24.434Z] 02:05:24     INFO -  PID 14631 |     #86 js::CallFromStack(JSContext*, JS::CallArgs const&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:651:10 (libxul.so+0x68271db)
[task 2020-12-10T02:05:24.434Z] 02:05:24     INFO -  PID 14631 |     #87 js::jit::DoCallFallback(JSContext*, js::jit::BaselineFrame*, js::jit::ICCall_Fallback*, unsigned int, JS::Value*, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jit/BaselineIC.cpp:1972:10 (libxul.so+0x70267bb)
[task 2020-12-10T02:05:24.435Z] 02:05:24     INFO -  PID 14631 |     #88 <null> <null> (0x7f0f83abfa68)
[task 2020-12-10T02:05:24.435Z] 02:05:24     INFO -  PID 14631 |     #89 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:2231:17 (libxul.so+0x680f022)
[task 2020-12-10T02:05:24.435Z] 02:05:24     INFO -  PID 14631 |     #90 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:473:13 (libxul.so+0x680df5c)
[task 2020-12-10T02:05:24.435Z] 02:05:24     INFO -  PID 14631 |     #91 js::ExecuteKernel(JSContext*, JS::Handle<JSScript*>, JS::Handle<JSObject*>, JS::Handle<JS::Value>, js::AbstractFramePtr, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:839:13 (libxul.so+0x68288fa)
[task 2020-12-10T02:05:24.436Z] 02:05:24     INFO -  PID 14631 |     #92 js::Execute(JSContext*, JS::Handle<JSScript*>, JS::Handle<JSObject*>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:871:10 (libxul.so+0x6828ae8)
[task 2020-12-10T02:05:24.436Z] 02:05:24     INFO -  PID 14631 |     #93 EvaluateSourceBuffer<mozilla::Utf8Unit> /builds/worker/checkouts/gecko/js/src/vm/CompilationAndEvaluation.cpp:525:10 (libxul.so+0x6968a84)
[task 2020-12-10T02:05:24.436Z] 02:05:24     INFO -  PID 14631 |     #94 JS::Evaluate(JSContext*, JS::ReadOnlyCompileOptions const&, JS::SourceText<mozilla::Utf8Unit>&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CompilationAndEvaluation.cpp:533:10 (libxul.so+0x6968a84)
[task 2020-12-10T02:05:24.437Z] 02:05:24     INFO -  PID 14631 |     #95 ProcessArgs(mozilla::dom::AutoJSAPI&, char**, int, XPCShellDirProvider*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCShellImpl.cpp:961:11 (libxul.so+0x1a3bc21)
[task 2020-12-10T02:05:24.437Z] 02:05:24     INFO -  PID 14631 |     #96 XRE_XPCShellMain(int, char**, char**, XREShellData const*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCShellImpl.cpp:1349:14 (libxul.so+0x1a3a65b)
[task 2020-12-10T02:05:24.437Z] 02:05:24     INFO -  PID 14631 |     #97 mozilla::BootstrapImpl::XRE_XPCShellMain(int, char**, char**, XREShellData const*) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:54:12 (libxul.so+0x66fc4cb)
[task 2020-12-10T02:05:24.438Z] 02:05:24     INFO -  PID 14631 |     #98 main /builds/worker/checkouts/gecko/js/xpconnect/shell/xpcshell.cpp:67:27 (xpcshell+0xc73f6)
[task 2020-12-10T02:05:24.438Z] 02:05:24     INFO -  PID 14631 | SUMMARY: ThreadSanitizer: data race /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:311:36 in operator!```

The code modified by https://hg.mozilla.org/integration/autoland/rev/1405ea3633c0730ca4b848acf0dfb239d7c959ca is entirely disabled (even not compiled) on the normal build. it's enabled only in SM(nu) and SM(smoosh) jobs.
so it shouldn't affect xpcshell test.

anyway, I'll look into the race itself.

Here are the relevant parts of the tsan report:

Read of size 8 at 0x7b2c0002d568 by main thread:
  ...
  #1 IsDBOpened toolkit/components/url-classifier/nsUrlClassifierDBService.h:189:37 (libxul.so+0x6686410)
  #2 nsUrlClassifierDBService::Shutdown() toolkit/components/url-classifier/nsUrlClassifierDBService.cpp:2334:16 (libxul.so+0x6686410)
  #3 nsUrlClassifierDBService::Observe(nsISupports*, char const*, char16_t const*) toolkit/components/url-classifier/nsUrlClassifierDBService.cpp:2287:5 (libxul.so+0x6686098)
  #4 non-virtual thunk to nsUrlClassifierDBService::Observe(nsISupports*, char const*, char16_t const*) toolkit/components/url-classifier/nsUrlClassifierDBService.cpp (libxul.so+0x6686a72)
  #5 nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) xpcom/ds/nsObserverList.cpp:70:19 (libxul.so+0xaeabf3)
  #6 nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) xpcom/ds/nsObserverService.cpp:287:19 (libxul.so+0xaed92a)
  ...
Previous write of size 8 at 0x7b2c0002d568 by thread T19 ('URL Classifier'):
  ...
  #3 nsUrlClassifierDBServiceWorker::OpenDb() toolkit/components/url-classifier/nsUrlClassifierDBService.cpp:1096:15 (libxul.so+0x6678050)
  #4 applyImpl<nsUrlClassifierDBServiceWorker, nsresult (nsUrlClassifierDBServiceWorker::*)()> obj-build/dist/include/nsThreadUtils.h:1148:12 (libxul.so+0x6698296)
  #5 apply<nsUrlClassifierDBServiceWorker, nsresult (nsUrlClassifierDBServiceWorker::*)()> obj-build/dist/include/nsThreadUtils.h:1154:12 (libxul.so+0x6698296)
  #6 mozilla::detail::RunnableMethodImpl<RefPtr<nsUrlClassifierDBServiceWorker> const, nsresult (nsUrlClassifierDBServiceWorker::*)(), true, (mozilla::RunnableKind)0>::Run() obj-build/dist/include/nsThreadUtils.h:1201:13 (libxul.so+0x6698296)
  #7 nsThread::ProcessNextEvent(bool, bool*) xpcom/threads/nsThread.cpp:1200:14 (libxul.so+0xb8455c)
  ...

Essentially, nsUrlClassifierDBServiceWorker::mClassifier is set on the URL Classifier thread but read on the main thread without any memory barriers, so this is a race. Bug 1677851 did not introduce this, although it may have exposed it to tsan by perturbing when particular events run. This looks like long-standing behavior.

Flags: needinfo?(dkeeler)

Before this patch, nsUrlClassifierDBService::Shutdown() would check if its
classifier had been set before dispatching an event to have it disable async
updates. However, this was racy, as the pointer being checked is set on a
background thread, and this ::Shutdown() runs on the main thread.
This race was introduced in bug 1363038 to avoid a deadlock. It seems the real
cause of the deadlock was addressed shortly after in bug 1394031, but this change
was never reverted until now.

Assignee: nobody → dkeeler
Status: NEW → ASSIGNED
Regressed by: 1363038
No longer regressed by: 1677851
Has Regression Range: --- → yes
Keywords: regression
Pushed by dkeeler@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/33ce6ad3bc5a unconditionally dispatch event to disable async updates in URL classifier at shutdown r=ckerschb
Status: ASSIGNED → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
Target Milestone: --- → 86 Branch

Looks like this might need a suppression on beta if the patch is not uplifted?

Flags: needinfo?(a.beingessner)

Aryx says they'd rather not uplift stuff to beta that they don't absolutely need to.

Flags: needinfo?(a.beingessner)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: