Closed Bug 1640066 Opened 2 years ago Closed 2 years ago

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/workspace/obj-build/xpcom/build/Services.cpp:465:8 in XPCOMService_GetThirdPartyUtil

Categories

(Core :: XPCOM, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

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


...
[task 2020-05-22T01:27:03.824Z] 01:27:03     INFO - GECKO(2425) |   Thread T20 'IPDL Background' (tid=2461, running) created by main thread at:
[task 2020-05-22T01:27:03.824Z] 01:27:03     INFO - GECKO(2425) |     #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors.cc:967:3 (firefox+0x584db)
[task 2020-05-22T01:27:03.824Z] 01:27:03     INFO - GECKO(2425) |     #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3bf93)
[task 2020-05-22T01:27:03.825Z] 01:27:03     INFO - GECKO(2425) |     #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x308c2)
[task 2020-05-22T01:27:03.825Z] 01:27:03     INFO - GECKO(2425) |     #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:652:8 (libxul.so+0xb0284b)
[task 2020-05-22T01:27:03.825Z] 01:27:03     INFO - GECKO(2425) |     #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, unsigned int, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:620:12 (libxul.so+0xb08edb)
[task 2020-05-22T01:27:03.825Z] 01:27:03     INFO - GECKO(2425) |     #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:157:57 (libxul.so+0xb0ec08)
[task 2020-05-22T01:27:03.826Z] 01:27:03     INFO - GECKO(2425) |     #6 NS_NewNamedThread<16> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:71:10 (libxul.so+0x13987e1)
[task 2020-05-22T01:27:03.826Z] 01:27:03     INFO - GECKO(2425) |     #7 (anonymous namespace)::ParentImpl::CreateBackgroundThread() /builds/worker/checkouts/gecko/ipc/glue/BackgroundImpl.cpp:1236:7 (libxul.so+0x13987e1)
[task 2020-05-22T01:27:03.826Z] 01:27:03     INFO - GECKO(2425) |     #8 RunOnMainThread /builds/worker/checkouts/gecko/ipc/glue/BackgroundImpl.cpp:1455:30 (libxul.so+0x139b13a)
[task 2020-05-22T01:27:03.826Z] 01:27:03     INFO - GECKO(2425) |     #9 (anonymous namespace)::ParentImpl::CreateActorHelper::Run() /builds/worker/checkouts/gecko/ipc/glue/BackgroundImpl.cpp:1474:17 (libxul.so+0x139b13a)
[task 2020-05-22T01:27:03.827Z] 01:27:03     INFO - GECKO(2425) |     #10 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1211:14 (libxul.so+0xb04d98)
[task 2020-05-22T01:27:03.827Z] 01:27:03     INFO - GECKO(2425) |     #11 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:501:10 (libxul.so+0xb09792)
[task 2020-05-22T01:27:03.827Z] 01:27:03     INFO - GECKO(2425) |     #12 SpinEventLoopUntil<mozilla::ProcessFailureBehavior::ReportToCaller, (lambda at /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:693:36)> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:359:25 (libxul.so+0xb09212)
[task 2020-05-22T01:27:03.827Z] 01:27:03     INFO - GECKO(2425) |     #13 nsThreadManager::SpinEventLoopUntilInternal(nsINestedEventLoopCondition*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:693:8 (libxul.so+0xb09212)
[task 2020-05-22T01:27:03.828Z] 01:27:03     INFO - GECKO(2425) |     #14 nsThreadManager::SpinEventLoopUntil(nsINestedEventLoopCondition*) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:672:10 (libxul.so+0xb09097)
[task 2020-05-22T01:27:03.828Z] 01:27:03     INFO - GECKO(2425) |     #15 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:106 (libxul.so+0xb237d5)
[task 2020-05-22T01:27:03.828Z] 01:27:03     INFO - GECKO(2425) |     #16 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:946:10 (libxul.so+0x1974671)
[task 2020-05-22T01:27:03.828Z] 01:27:03     INFO - GECKO(2425) |     #17 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:493:13 (libxul.so+0x6567949)
[task 2020-05-22T01:27:03.829Z] 01:27:03     INFO - GECKO(2425) |     #18 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:585:12 (libxul.so+0x6567949)
[task 2020-05-22T01:27:03.829Z] 01:27:03     INFO - GECKO(2425) |     #19 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x655ca7b)
[task 2020-05-22T01:27:03.829Z] 01:27:03     INFO - GECKO(2425) |     #20 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:652:10 (libxul.so+0x655ca7b)
[task 2020-05-22T01:27:03.829Z] 01:27:03     INFO - GECKO(2425) |     #21 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3312:16 (libxul.so+0x655ca7b)
[task 2020-05-22T01:27:03.830Z] 01:27:03     INFO - GECKO(2425) |     #22 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:465:10 (libxul.so+0x654f86f)
[task 2020-05-22T01:27:03.830Z] 01:27:03     INFO - GECKO(2425) |     #23 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:620:13 (libxul.so+0x65682f6)
[task 2020-05-22T01:27:03.830Z] 01:27:03     INFO - GECKO(2425) |     #24 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x6568d8e)
[task 2020-05-22T01:27:03.830Z] 01:27:03     INFO - GECKO(2425) |     #25 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:665:8 (libxul.so+0x6568d8e)
[task 2020-05-22T01:27:03.831Z] 01:27:03     INFO - GECKO(2425) |     #26 js::fun_apply(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/vm/JSFunction.cpp:1201:10 (libxul.so+0x67ca5ac)
[task 2020-05-22T01:27:03.831Z] 01:27:03     INFO - GECKO(2425) |     #27 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:493:13 (libxul.so+0x6567949)
[task 2020-05-22T01:27:03.831Z] 01:27:03     INFO - GECKO(2425) |     #28 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:585:12 (libxul.so+0x6567949)
[task 2020-05-22T01:27:03.831Z] 01:27:03     INFO - GECKO(2425) |     #29 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x655ca7b)
[task 2020-05-22T01:27:03.832Z] 01:27:03     INFO - GECKO(2425) |     #30 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:652:10 (libxul.so+0x655ca7b)
[task 2020-05-22T01:27:03.832Z] 01:27:03     INFO - GECKO(2425) |     #31 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3312:16 (libxul.so+0x655ca7b)
[task 2020-05-22T01:27:03.832Z] 01:27:03     INFO - GECKO(2425) |     #32 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:465:10 (libxul.so+0x654f86f)
[task 2020-05-22T01:27:03.833Z] 01:27:03     INFO - GECKO(2425) |     #33 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:620:13 (libxul.so+0x65682f6)
[task 2020-05-22T01:27:03.833Z] 01:27:03     INFO - GECKO(2425) |     #34 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x6568d8e)
[task 2020-05-22T01:27:03.833Z] 01:27:03     INFO - GECKO(2425) |     #35 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:665:8 (libxul.so+0x6568d8e)
[task 2020-05-22T01:27:03.833Z] 01:27:03     INFO - GECKO(2425) |     #36 JS_CallFunctionValue(JSContext*, JS::Handle<JSObject*>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jsapi.cpp:2777:10 (libxul.so+0x6622ebd)
[task 2020-05-22T01:27:03.834Z] 01:27:03     INFO - GECKO(2425) |     #37 nsXPCWrappedJS::CallMethod(unsigned short, nsXPTMethodInfo const*, nsXPTCMiniVariant*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedJSClass.cpp:964:17 (libxul.so+0x196b8da)
[task 2020-05-22T01:27:03.834Z] 01:27:03     INFO - GECKO(2425) |     #38 PrepareAndDispatch /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcstubs_x86_64_linux.cpp:125:37 (libxul.so+0xb24824)
[task 2020-05-22T01:27:03.834Z] 01:27:03     INFO - GECKO(2425) |     #39 SharedStub <null> (libxul.so+0xb23ad2)
[task 2020-05-22T01:27:03.835Z] 01:27:03     INFO - GECKO(2425) |     #40 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4416:16 (libxul.so+0x6437902)
[task 2020-05-22T01:27:03.835Z] 01:27:03     INFO - GECKO(2425) |     #41 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4771:8 (libxul.so+0x6438b33)
[task 2020-05-22T01:27:03.835Z] 01:27:03     INFO - GECKO(2425) |     #42 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4825:21 (libxul.so+0x6438e94)
[task 2020-05-22T01:27:03.837Z] 01:27:03     INFO - GECKO(2425) |     #43 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x6443a42)
[task 2020-05-22T01:27:03.837Z] 01:27:03     INFO - GECKO(2425) |     #44 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:217:22 (firefox+0xca433)
[task 2020-05-22T01:27:03.846Z] 01:27:03     INFO - GECKO(2425) |     #45 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:331:16 (firefox+0xca433)
[task 2020-05-22T01:27:03.846Z] 01:27:03     INFO - GECKO(2425) | SUMMARY: ThreadSanitizer: data race /builds/worker/workspace/obj-build/xpcom/build/Services.cpp:465:8 in XPCOMService_GetThirdPartyUtil
[task 2020-05-22T01:27:03.848Z] 01:27:03     INFO - GECKO(2425) | ==================
[task 2020-05-22T01:27:03.849Z] 01:27:03     INFO - GECKO(2425) | Exiting due to channel error.
[task 2020-05-22T01:27:03.850Z] 01:27:03     INFO - GECKO(2425) | Exiting due to channel error.
[task 2020-05-22T01:27:04.880Z] 01:27:04     INFO - GECKO(2425) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=7.14428) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=8.5818)
[task 2020-05-22T01:29:43.614Z] 01:29:43     INFO - runtests.py | Waiting for browser...
[task 2020-05-22T01:29:43.616Z] 01:29:43     INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2020-05-22T01:29:43.616Z] 01:29:43     INFO - Buffered messages finished
[task 2020-05-22T01:29:43.616Z] 01:29:43    ERROR - TEST-UNEXPECTED-FAIL | automation.py | application terminated with exit code -6
[task 2020-05-22T01:29:43.616Z] 01:29:43     INFO - runtests.py | Application ran for: 0:03:00.025014
[task 2020-05-22T01:29:43.617Z] 01:29:43     INFO - zombiecheck | Reading PID log: /tmp/tmp3v6Zmtpidlog
[task 2020-05-22T01:29:43.617Z] 01:29:43     INFO - ==> process 2425 launched child process 2440
[task 2020-05-22T01:29:43.617Z] 01:29:43     INFO - ==> process 2425 launched child process 2487
[task 2020-05-22T01:29:43.617Z] 01:29:43     INFO - ==> process 2425 launched child process 2530
[task 2020-05-22T01:29:43.618Z] 01:29:43     INFO - zombiecheck | Checking for orphan process with PID: 2440
[task 2020-05-22T01:29:43.618Z] 01:29:43     INFO - zombiecheck | Checking for orphan process with PID: 2530
[task 2020-05-22T01:29:43.618Z] 01:29:43     INFO - zombiecheck | Checking for orphan process with PID: 2487
[task 2020-05-22T01:29:43.618Z] 01:29:43     INFO -  Traceback (most recent call last):
[task 2020-05-22T01:29:43.619Z] 01:29:43     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2875, in doTests
[task 2020-05-22T01:29:43.619Z] 01:29:43     INFO -      e10s=options.e10s
[task 2020-05-22T01:29:43.619Z] 01:29:43     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2392, in runApp
[task 2020-05-22T01:29:43.619Z] 01:29:43     INFO -      raise exc(value).with_traceback(tb)
[task 2020-05-22T01:29:43.619Z] 01:29:43     INFO -  AttributeError: 'timeout' object has no attribute 'with_traceback'
Blocks: tsan
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.