Closed Bug 1627487 Opened 2 years ago Closed 2 years ago

Intermittent security/manager/ssl/tests/unit/test_nss_shutdown.js | xpcshell return code: -6 | ThreadSanitizer: use of an invalid mutex (e.g. uninitialized or destroyed) /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptsynch.c:176:10 in PR_Lock

Categories

(Core :: Security: PSM, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

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


[task 2020-04-04T22:38:32.678Z] 22:38:32 INFO - TEST-START | security/manager/ssl/tests/unit/test_nss_shutdown.js
[task 2020-04-04T22:38:34.645Z] 22:38:34 WARNING - TEST-UNEXPECTED-FAIL | security/manager/ssl/tests/unit/test_nss_shutdown.js | xpcshell return code: -6
[task 2020-04-04T22:38:34.646Z] 22:38:34 INFO - TEST-INFO took 1961ms
[task 2020-04-04T22:38:34.646Z] 22:38:34 INFO - >>>>>>>
[task 2020-04-04T22:38:34.646Z] 22:38:34 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-04-04T22:38:34.646Z] 22:38:34 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-04-04T22:38:34.646Z] 22:38:34 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-04-04T22:38:34.646Z] 22:38:34 INFO - running event loop
[task 2020-04-04T22:38:34.646Z] 22:38:34 INFO - security/manager/ssl/tests/unit/test_nss_shutdown.js | Starting
[task 2020-04-04T22:38:34.647Z] 22:38:34 INFO - (xpcshell/head.js) | test pending (2)
[task 2020-04-04T22:38:34.647Z] 22:38:34 INFO - PID 14763 | ==================
[task 2020-04-04T22:38:34.647Z] 22:38:34 INFO - PID 14763 | WARNING: ThreadSanitizer: use of an invalid mutex (e.g. uninitialized or destroyed) (pid=14763)
[task 2020-04-04T22:38:34.647Z] 22:38:34 INFO - PID 14763 | #0 pthread_mutex_lock /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/../sanitizer_common/sanitizer_common_interceptors.inc:4139:3 (xpcshell+0x75246)
[task 2020-04-04T22:38:34.647Z] 22:38:34 INFO - PID 14763 | #1 PR_Lock /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptsynch.c:176:10 (libnspr4.so+0x2a2f8)
[task 2020-04-04T22:38:34.648Z] 22:38:34 INFO - PID 14763 | #2 SECMOD_DestroyModule /builds/worker/checkouts/gecko/security/nss/lib/pk11wrap/pk11util.c:890:5 (libnss3.so+0xa0189)
[task 2020-04-04T22:38:34.648Z] 22:38:34 INFO - PID 14763 | #3 SECMOD_DestroyModuleListElement /builds/worker/checkouts/gecko/security/nss/lib/pk11wrap/pk11util.c:968:9 (libnss3.so+0xa0e13)
[task 2020-04-04T22:38:34.649Z] 22:38:34 INFO - PID 14763 | #4 SECMOD_DeleteModuleEx /builds/worker/checkouts/gecko/security/nss/lib/pk11wrap/pk11util.c:434:9 (libnss3.so+0xa0e13)
[task 2020-04-04T22:38:34.650Z] 22:38:34 INFO - PID 14763 | #5 SECMOD_UnloadUserModule /builds/worker/checkouts/gecko/security/nss/lib/pk11wrap/pk11pars.c:1971:12 (libnss3.so+0x8b458)
[task 2020-04-04T22:38:34.650Z] 22:38:34 INFO - PID 14763 | #6 mozilla::psm::UnloadUserModules() /builds/worker/checkouts/gecko/security/certverifier/NSSCertDBTrustDomain.cpp:1544:5 (libxul.so+0x9a10e5)
[task 2020-04-04T22:38:34.651Z] 22:38:34 INFO - PID 14763 | #7 nsNSSComponent::ShutdownNSS() /builds/worker/checkouts/gecko/security/manager/ssl/nsNSSComponent.cpp:2056:3 (libxul.so+0x60513d8)
[task 2020-04-04T22:38:34.651Z] 22:38:34 INFO - PID 14763 | #8 nsNSSComponent::Observe(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/security/manager/ssl/nsNSSComponent.cpp:2110:5 (libxul.so+0x605711c)
[task 2020-04-04T22:38:34.651Z] 22:38:34 INFO - PID 14763 | #9 non-virtual thunk to nsNSSComponent::Observe(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/security/manager/ssl/nsNSSComponent.cpp (libxul.so+0x6057619)
[task 2020-04-04T22:38:34.652Z] 22:38:34 INFO - PID 14763 | #10 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:106 (libxul.so+0xae8505)
[task 2020-04-04T22:38:34.652Z] 22:38:34 INFO - PID 14763 | #11 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:947:10 (libxul.so+0x18fb921)
[task 2020-04-04T22:38:34.652Z] 22:38:34 INFO - PID 14763 | #12 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:489:13 (libxul.so+0x6466809)
[task 2020-04-04T22:38:34.652Z] 22:38:34 INFO - PID 14763 | #13 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:581:12 (libxul.so+0x6466809)
[task 2020-04-04T22:38:34.653Z] 22:38:34 INFO - PID 14763 | #14 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:644:10 (libxul.so+0x6467aca)
[task 2020-04-04T22:38:34.653Z] 22:38:34 INFO - PID 14763 | #15 js::CallFromStack(JSContext*, JS::CallArgs const&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x6467aca)
[task 2020-04-04T22:38:34.653Z] 22:38:34 INFO - PID 14763 | #16 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:2926:10 (libxul.so+0x6c8753d)
[task 2020-04-04T22:38:34.654Z] 22:38:34 INFO - PID 14763 | #17 <null> <null> (0x7f014df24e28)
[task 2020-04-04T22:38:34.654Z] 22:38:34 INFO - PID 14763 | #18 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:1940:17 (libxul.so+0x644f613)
[task 2020-04-04T22:38:34.654Z] 22:38:34 INFO - PID 14763 | #19 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:461:10 (libxul.so+0x644e509)
[task 2020-04-04T22:38:34.654Z] 22:38:34 INFO - PID 14763 | #20 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:616:13 (libxul.so+0x64671b6)
[task 2020-04-04T22:38:34.655Z] 22:38:34 INFO - PID 14763 | #21 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:644:10 (libxul.so+0x6467c4e)
[task 2020-04-04T22:38:34.655Z] 22:38:34 INFO - PID 14763 | #22 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:661:8 (libxul.so+0x6467c4e)
[task 2020-04-04T22:38:34.656Z] 22:38:34 INFO - PID 14763 | #23 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:2735:10 (libxul.so+0x651dfcd)
[task 2020-04-04T22:38:34.656Z] 22:38:34 INFO - PID 14763 | #24 nsXPCWrappedJS::CallMethod(unsigned short, nsXPTMethodInfo const*, nsXPTCMiniVariant*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedJSClass.cpp:964:17 (libxul.so+0x18f2c3a)
[task 2020-04-04T22:38:34.656Z] 22:38:34 INFO - PID 14763 | #25 PrepareAndDispatch /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcstubs_x86_64_linux.cpp:125:37 (libxul.so+0xae9554)
[task 2020-04-04T22:38:34.657Z] 22:38:34 INFO - PID 14763 | #26 SharedStub <null> (libxul.so+0xae8802)
[task 2020-04-04T22:38:34.657Z] 22:38:34 INFO - PID 14763 | #27 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:481:10 (libxul.so+0xacd3d2)
[task 2020-04-04T22:38:34.657Z] 22:38:34 INFO - PID 14763 | #28 SpinEventLoopUntil<mozilla::ProcessFailureBehavior::ReportToCaller, (lambda at /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:694:36)> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:342:25 (libxul.so+0xacce52)
[task 2020-04-04T22:38:34.658Z] 22:38:34 INFO - PID 14763 | #29 nsThreadManager::SpinEventLoopUntilInternal(nsINestedEventLoopCondition*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:694:8 (libxul.so+0xacce52)
[task 2020-04-04T22:38:34.658Z] 22:38:34 INFO - PID 14763 | #30 nsThreadManager::SpinEventLoopUntil(nsINestedEventLoopCondition*) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:673:10 (libxul.so+0xacccd7)
[task 2020-04-04T22:38:34.658Z] 22:38:34 INFO - PID 14763 | #31 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:106 (libxul.so+0xae8505)
[task 2020-04-04T22:38:34.659Z] 22:38:34 INFO - PID 14763 | #32 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:947:10 (libxul.so+0x18fb921)
[task 2020-04-04T22:38:34.659Z] 22:38:34 INFO - PID 14763 | #33 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:489:13 (libxul.so+0x6466809)
[task 2020-04-04T22:38:34.660Z] 22:38:34 INFO - PID 14763 | #34 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:581:12 (libxul.so+0x6466809)
[task 2020-04-04T22:38:34.661Z] 22:38:34 INFO - PID 14763 | #35 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:644:10 (libxul.so+0x645ba2b)
[task 2020-04-04T22:38:34.661Z] 22:38:34 INFO - PID 14763 | #36 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x645ba2b)
[task 2020-04-04T22:38:34.661Z] 22:38:34 INFO - PID 14763 | #37 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3040:16 (libxul.so+0x645ba2b)
[task 2020-04-04T22:38:34.662Z] 22:38:34 INFO - PID 14763 | #38 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:461:10 (libxul.so+0x644e509)
[task 2020-04-04T22:38:34.662Z] 22:38:34 INFO - PID 14763 | #39 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:616:13 (libxul.so+0x64671b6)
[task 2020-04-04T22:38:34.662Z] 22:38:34 INFO - PID 14763 | #40 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:644:10 (libxul.so+0x6467aca)
[task 2020-04-04T22:38:34.663Z] 22:38:34 INFO - PID 14763 | #41 js::CallFromStack(JSContext*, JS::CallArgs const&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x6467aca)
[task 2020-04-04T22:38:34.663Z] 22:38:34 INFO - PID 14763 | #42 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:2926:10 (libxul.so+0x6c8753d)
[task 2020-04-04T22:38:34.664Z] 22:38:34 INFO - PID 14763 | #43 <null> <null> (0x7f014df24e28)
[task 2020-04-04T22:38:34.664Z] 22:38:34 INFO - PID 14763 | #44 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:1940:17 (libxul.so+0x644f613)
[task 2020-04-04T22:38:34.665Z] 22:38:34 INFO - PID 14763 | #45 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:461:10 (libxul.so+0x644e509)
[task 2020-04-04T22:38:34.665Z] 22:38:34 INFO - PID 14763 | #46 js::ExecuteKernel(JSContext*, JS::Handle<JSScript*>, JSObject&, JS::Value const&, js::AbstractFramePtr, JS::Value*) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:837:13 (libxul.so+0x64692e7)
[task 2020-04-04T22:38:34.665Z] 22:38:34 INFO - PID 14763 | #47 js::Execute(JSContext*, JS::Handle<JSScript*>, JSObject&, JS::Value*) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:870:10 (libxul.so+0x6469657)
[task 2020-04-04T22:38:34.666Z] 22:38:34 INFO - PID 14763 | #48 EvaluateSourceBuffer<mozilla::Utf8Unit> /builds/worker/checkouts/gecko/js/src/vm/CompilationAndEvaluation.cpp:493:10 (libxul.so+0x65d81ad)
[task 2020-04-04T22:38:34.666Z] 22:38:34 INFO - PID 14763 | #49 JS::Evaluate(JSContext*, JS::ReadOnlyCompileOptions const&, JS::SourceText<mozilla::Utf8Unit>&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CompilationAndEvaluation.cpp:502:10 (libxul.so+0x65d81ad)
[task 2020-04-04T22:38:34.667Z] 22:38:34 INFO - PID 14763 | #50 ProcessArgs /builds/worker/checkouts/gecko/js/xpconnect/src/XPCShellImpl.cpp:958:11 (libxul.so+0x18e82d2)
[task 2020-04-04T22:38:34.667Z] 22:38:34 INFO - PID 14763 | #51 XRE_XPCShellMain(int, char**, char**, XREShellData const*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCShellImpl.cpp:1353:14 (libxul.so+0x18e82d2)
[task 2020-04-04T22:38:34.668Z] 22:38:34 INFO - PID 14763 | #52 mozilla::BootstrapImpl::XRE_XPCShellMain(int, char**, char**, XREShellData const*) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:54:12 (libxul.so+0x6347b4b)
[task 2020-04-04T22:38:34.668Z] 22:38:34 INFO - PID 14763 | #53 main /builds/worker/checkouts/gecko/js/xpconnect/shell/xpcshell.cpp:66:27 (xpcshell+0xc8306)
[task 2020-04-04T22:38:34.669Z] 22:38:34 INFO - PID 14763 | Mutex M266410756877146224 is already destroyed.
[task 2020-04-04T22:38:34.669Z] 22:38:34 INFO - PID 14763 | SUMMARY: ThreadSanitizer: use of an invalid mutex (e.g. uninitialized or destroyed) /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptsynch.c:176:10 in PR_Lock
[task 2020-04-04T22:38:34.670Z] 22:38:34 INFO - PID 14763 | ==================
[task 2020-04-04T22:38:34.670Z] 22:38:34 INFO - <<<<<<<
[task 2020-04-04T22:38:34.674Z] 22:38:34 INFO - TEST-START | toolkit/components/osfile/tests/xpcshell/test_unique.js
[task 2020-04-04T22:38:42.663Z] 22:38:42 INFO - TEST-PASS | toolkit/components/osfile/tests/xpcshell/test_unique.js | took 7990ms
[task 2020-04-04T22:38:42.678Z] 22:38:42 INFO - INFO | Result summary:
[task 2020-04-04T22:38:42.679Z] 22:38:42 INFO - INFO | Passed: 418
[task 2020-04-04T22:38:42.679Z] 22:38:42 WARNING - INFO | Failed: 1
[task 2020-04-04T22:38:42.679Z] 22:38:42 WARNING - One or more unittests failed.
[task 2020-04-04T22:38:42.679Z] 22:38:42 INFO - INFO | Todo: 0
[task 2020-04-04T22:38:42.679Z] 22:38:42 INFO - INFO | Retried: 2
[task 2020-04-04T22:38:42.679Z] 22:38:42 INFO - SUITE-END | took 1672s
[task 2020-04-04T22:38:42.679Z] 22:38:42 INFO - Node moz-http2 server shutting down ...
[task 2020-04-04T22:38:42.724Z] 22:38:42 ERROR - Return code: 1

Component: Search → Security: PSM
Product: Firefox → Core
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Blocks: tsan
You need to log in before you can comment on or make changes to this bug.