Closed Bug 1642230 Opened 2 years ago Closed 1 year ago

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/js/src/gc/Cell.h:597:49 in flagsField

Categories

(Core :: JavaScript Engine, 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=304462721&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WleFXi9BQFWAJTjpCM_gLA/runs/0/artifacts/public/logs/live_backing.log


[task 2020-05-31T18:01:43.946Z] 18:01:43     INFO - TEST-START | dom/tests/mochitest/geolocation/test_geoWatchPositionBlockedInInsecureContext.html
[task 2020-05-31T18:01:44.268Z] 18:01:44     INFO - GECKO(2213) | MEMORY STAT | vsize 130550461MB | residentFast 399MB
[task 2020-05-31T18:01:44.364Z] 18:01:44     INFO - TEST-OK | dom/tests/mochitest/geolocation/test_geoWatchPositionBlockedInInsecureContext.html | took 414ms
[task 2020-05-31T18:01:44.467Z] 18:01:44     INFO - TEST-START | Shutdown
[task 2020-05-31T18:01:44.467Z] 18:01:44     INFO - Passed:  4
[task 2020-05-31T18:01:44.467Z] 18:01:44     INFO - Failed:  0
[task 2020-05-31T18:01:44.467Z] 18:01:44     INFO - Todo:    0
[task 2020-05-31T18:01:44.467Z] 18:01:44     INFO - Mode:    e10s
[task 2020-05-31T18:01:44.467Z] 18:01:44     INFO - Slowest: 1675ms - /tests/dom/tests/mochitest/geolocation/test_geoGetCurrentPositionBlockedInInsecureContext.html
[task 2020-05-31T18:01:44.469Z] 18:01:44     INFO - SimpleTest FINISHED
[task 2020-05-31T18:01:44.472Z] 18:01:44     INFO - TEST-INFO | Ran 1 Loops
[task 2020-05-31T18:01:44.473Z] 18:01:44     INFO - SimpleTest FINISHED
[task 2020-05-31T18:01:46.888Z] 18:01:46     INFO - GECKO(2213) | 1590948106884	Marionette	TRACE	Received observer notification xpcom-will-shutdown
[task 2020-05-31T18:01:46.888Z] 18:01:46     INFO - GECKO(2213) | 1590948106884	Marionette	INFO	Stopped listening on port 2828
[task 2020-05-31T18:01:46.889Z] 18:01:46     INFO - GECKO(2213) | 1590948106885	Marionette	DEBUG	Marionette stopped listening
[task 2020-05-31T18:01:52.768Z] 18:01:52     INFO - GECKO(2213) | [2020-05-31T18:01:52Z ERROR xulstore::persist] removeDocument error: unavailable
[task 2020-05-31T18:01:53.960Z] 18:01:53     INFO - TEST-INFO | Main app process: exit 0
[task 2020-05-31T18:01:53.960Z] 18:01:53     INFO - runtests.py | Application ran for: 0:00:41.045167
[task 2020-05-31T18:01:53.961Z] 18:01:53     INFO - zombiecheck | Reading PID log: /tmp/tmpyskcd4pidlog
[task 2020-05-31T18:01:53.961Z] 18:01:53     INFO - ==> process 2213 launched child process 2228
[task 2020-05-31T18:01:53.961Z] 18:01:53     INFO - ==> process 2213 launched child process 2321
[task 2020-05-31T18:01:53.961Z] 18:01:53     INFO - ==> process 2213 launched child process 2337
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - ==> process 2213 launched child process 2407
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - ==> process 2213 launched child process 2432
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - zombiecheck | Checking for orphan process with PID: 2432
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - zombiecheck | Checking for orphan process with PID: 2321
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - zombiecheck | Checking for orphan process with PID: 2407
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - zombiecheck | Checking for orphan process with PID: 2228
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - zombiecheck | Checking for orphan process with PID: 2337
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - runtests.py | Running with scheme: https
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - runtests.py | Running with e10s: True
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - runtests.py | Running with fission: False
[task 2020-05-31T18:01:53.962Z] 18:01:53     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2020-05-31T18:01:53.963Z] 18:01:53     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2020-05-31T18:01:53.963Z] 18:01:53     INFO - runtests.py | Running tests: start.
[task 2020-05-31T18:01:53.963Z] 18:01:53     INFO - 
[task 2020-05-31T18:01:53.978Z] 18:01:53     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpb4SEDB.mozrunner
[task 2020-05-31T18:01:53.979Z] 18:01:53     INFO - runtests.py | Application pid: 2473
[task 2020-05-31T18:01:53.979Z] 18:01:53     INFO - TEST-INFO | started process GECKO(2473)
[task 2020-05-31T18:01:57.114Z] 18:01:57     INFO - GECKO(2473) | 1590948117112	Marionette	TRACE	Marionette enabled
[task 2020-05-31T18:01:57.115Z] 18:01:57     INFO - GECKO(2473) | 1590948117113	Marionette	TRACE	Received observer notification profile-after-change
[task 2020-05-31T18:01:57.240Z] 18:01:57     INFO - GECKO(2473) | 1590948117236	Marionette	TRACE	Received observer notification command-line-startup
[task 2020-05-31T18:01:58.092Z] 18:01:58     INFO - GECKO(2473) | ==================
[task 2020-05-31T18:01:58.093Z] 18:01:58     INFO - GECKO(2473) | WARNING: ThreadSanitizer: data race (pid=2473)
[task 2020-05-31T18:01:58.093Z] 18:01:58     INFO - GECKO(2473) |   Read of size 8 at 0x003764cb0b60 by thread T13:
[task 2020-05-31T18:01:58.093Z] 18:01:58     INFO - GECKO(2473) |     #0 flagsField /builds/worker/checkouts/gecko/js/src/gc/Cell.h:597:49 (libxul.so+0x6c38a3d)
[task 2020-05-31T18:01:58.093Z] 18:01:58     INFO - GECKO(2473) |     #1 flags /builds/worker/checkouts/gecko/js/src/vm/StringType.h:382:43 (libxul.so+0x6c38a3d)
[task 2020-05-31T18:01:58.093Z] 18:01:58     INFO - GECKO(2473) |     #2 hasIndexValue /builds/worker/checkouts/gecko/js/src/vm/StringType.h:419:39 (libxul.so+0x6c38a3d)
[task 2020-05-31T18:01:58.093Z] 18:01:58     INFO - GECKO(2473) |     #3 isIndex /builds/worker/checkouts/gecko/js/src/vm/StringType.h:863:19 (libxul.so+0x6c38a3d)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #4 AtomToId /builds/worker/checkouts/gecko/js/src/vm/JSAtom-inl.h:27:13 (libxul.so+0x6c38a3d)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #5 InterpretObjLiteralObj /builds/worker/checkouts/gecko/js/src/frontend/ObjLiteral.cpp:63:16 (libxul.so+0x6c38a3d)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #6 js::InterpretObjLiteral(JSContext*, mozilla::Vector<JSAtom*, 4ul, js::TempAllocPolicy> const&, mozilla::Span<unsigned char const, 18446744073709551615ul>, mozilla::EnumSet<js::ObjLiteralFlag, unsigned char>) /builds/worker/checkouts/gecko/js/src/frontend/ObjLiteral.cpp:122:16 (libxul.so+0x6c38a3d)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #7 InterpretObjLiteral /builds/worker/checkouts/gecko/js/src/frontend/ObjLiteral.h:548:10 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #8 create /builds/worker/checkouts/gecko/js/src/frontend/BytecodeSection.cpp:171:10 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #9 operator() /builds/worker/checkouts/gecko/js/src/frontend/BytecodeSection.cpp:86:28 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #10 match<Matcher &, const mozilla::Variant<JSAtom *, js::frontend::NullScriptThing, js::frontend::TypedIndex<js::frontend::BigIntCreationData>, js::ObjLiteralCreationData, js::frontend::TypedIndex<js::frontend::RegExpCreationData>, js::frontend::TypedIndex<js::Scope>, js::frontend::FunctionIndex, js::frontend::EmptyGlobalScopeType> > /builds/worker/workspace/obj-build/dist/include/mozilla/Variant.h:257:16 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #11 match<Matcher &, const mozilla::Variant<JSAtom *, js::frontend::NullScriptThing, js::frontend::TypedIndex<js::frontend::BigIntCreationData>, js::ObjLiteralCreationData, js::frontend::TypedIndex<js::frontend::RegExpCreationData>, js::frontend::TypedIndex<js::Scope>, js::frontend::FunctionIndex, js::frontend::EmptyGlobalScopeType> > /builds/worker/workspace/obj-build/dist/include/mozilla/Variant.h:269:14 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #12 match<Matcher &, const mozilla::Variant<JSAtom *, js::frontend::NullScriptThing, js::frontend::TypedIndex<js::frontend::BigIntCreationData>, js::ObjLiteralCreationData, js::frontend::TypedIndex<js::frontend::RegExpCreationData>, js::frontend::TypedIndex<js::Scope>, js::frontend::FunctionIndex, js::frontend::EmptyGlobalScopeType> > /builds/worker/workspace/obj-build/dist/include/mozilla/Variant.h:269:14 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #13 match<Matcher &, const mozilla::Variant<JSAtom *, js::frontend::NullScriptThing, js::frontend::TypedIndex<js::frontend::BigIntCreationData>, js::ObjLiteralCreationData, js::frontend::TypedIndex<js::frontend::RegExpCreationData>, js::frontend::TypedIndex<js::Scope>, js::frontend::FunctionIndex, js::frontend::EmptyGlobalScopeType> > /builds/worker/workspace/obj-build/dist/include/mozilla/Variant.h:269:14 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.094Z] 18:01:58     INFO - GECKO(2473) |     #14 match<Matcher &> /builds/worker/workspace/obj-build/dist/include/mozilla/Variant.h:772:12 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #15 js::frontend::EmitScriptThingsVector(JSContext*, js::frontend::CompilationInfo&, mozilla::Vector<mozilla::Variant<JSAtom*, js::frontend::NullScriptThing, js::frontend::TypedIndex<js::frontend::BigIntCreationData>, js::ObjLiteralCreationData, js::frontend::TypedIndex<js::frontend::RegExpCreationData>, js::frontend::TypedIndex<js::Scope>, js::frontend::FunctionIndex, js::frontend::EmptyGlobalScopeType>, 0ul, js::TempAllocPolicy> const&, mozilla::Span<JS::GCCellPtr, 18446744073709551615ul>) /builds/worker/checkouts/gecko/js/src/frontend/BytecodeSection.cpp:124:21 (libxul.so+0x6c1ad37)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #16 InitFromStencil /builds/worker/checkouts/gecko/js/src/vm/JSScript.cpp:4102:10 (libxul.so+0x6802a79)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #17 JSScript::fullyInitFromStencil(JSContext*, js::frontend::CompilationInfo&, JS::Handle<JSScript*>, js::frontend::ScriptStencil&) /builds/worker/checkouts/gecko/js/src/vm/JSScript.cpp:4247:8 (libxul.so+0x6802a79)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #18 JSScript::fromStencil(JSContext*, js::frontend::CompilationInfo&, js::frontend::ScriptStencil&, js::SourceExtent) /builds/worker/checkouts/gecko/js/src/vm/JSScript.cpp:4312:8 (libxul.so+0x68030de)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #19 InstantiateScriptStencils /builds/worker/checkouts/gecko/js/src/frontend/Parser.cpp:1903:27 (libxul.so+0x6beb234)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #20 js::frontend::CompilationInfo::instantiateStencils() /builds/worker/checkouts/gecko/js/src/frontend/Parser.cpp:1992:8 (libxul.so+0x6beb234)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #21 js::frontend::ScriptCompiler<char16_t>::compileScript(js::frontend::CompilationInfo&, js::frontend::SharedContext*) /builds/worker/checkouts/gecko/js/src/frontend/BytecodeCompiler.cpp:496:26 (libxul.so+0x6c1dc15)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #22 CreateGlobalScript<char16_t> /builds/worker/checkouts/gecko/js/src/frontend/BytecodeCompiler.cpp:188:17 (libxul.so+0x6bf2a2d)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #23 js::frontend::CompileGlobalScript(js::frontend::CompilationInfo&, js::frontend::GlobalSharedContext&, JS::SourceText<char16_t>&) /builds/worker/checkouts/gecko/js/src/frontend/BytecodeCompiler.cpp:203:10 (libxul.so+0x6bf2a2d)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #24 ScriptParseTask<char16_t>::parse(JSContext*) /builds/worker/checkouts/gecko/js/src/vm/HelperThreads.cpp:602:7 (libxul.so+0x6716ae4)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #25 js::ParseTask::runTask() /builds/worker/checkouts/gecko/js/src/vm/HelperThreads.cpp:554:3 (libxul.so+0x67061be)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #26 js::HelperThread::handleParseWorkload(js::AutoLockHelperThreadState&) /builds/worker/checkouts/gecko/js/src/vm/HelperThreads.cpp:2238:11 (libxul.so+0x670ed36)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #27 js::HelperThread::threadLoop() /builds/worker/checkouts/gecko/js/src/vm/HelperThreads.cpp:2532:5 (libxul.so+0x670db37)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #28 js::HelperThread::ThreadMain(void*) /builds/worker/checkouts/gecko/js/src/vm/HelperThreads.cpp:2050:11 (libxul.so+0x6709f2d)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #29 callMain<0> /builds/worker/checkouts/gecko/js/src/threading/Thread.h:217:5 (libxul.so+0x67174f0)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #30 js::detail::ThreadTrampoline<void (&)(void*), js::HelperThread*>::Start(void*) /builds/worker/checkouts/gecko/js/src/threading/Thread.h:206:11 (libxul.so+0x67174f0)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |   Previous write of size 8 at 0x003764cb0b60 by main thread:
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     [failed to restore the stack]
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |   Thread T13 'JS Helper' (tid=2493, running) created by main thread at:
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #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-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #1 js::Thread::create(void* (*)(void*), void*) /builds/worker/checkouts/gecko/js/src/threading/posix/PosixThread.cpp:52:7 (libxul.so+0x6671e91)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #2 bool js::Thread::init<void (&)(void*), js::HelperThread*>(void (&)(void*), js::HelperThread*&&) /builds/worker/checkouts/gecko/js/src/threading/Thread.h:90:12 (libxul.so+0x6709e42)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #3 js::GlobalHelperThreadState::ensureInitialized() /builds/worker/checkouts/gecko/js/src/vm/HelperThreads.cpp:1160:27 (libxul.so+0x6704a2a)
[task 2020-05-31T18:01:58.095Z] 18:01:58     INFO - GECKO(2473) |     #4 js::EnsureHelperThreadsInitialized() /builds/worker/checkouts/gecko/js/src/vm/HelperThreads.cpp:94:30 (libxul.so+0x6704672)
[task 2020-05-31T18:01:58.096Z] 18:01:58     INFO - GECKO(2473) |     #5 JSRuntime::init(JSContext*, unsigned int) /builds/worker/checkouts/gecko/js/src/vm/Runtime.cpp:200:32 (libxul.so+0x687b682)
[task 2020-05-31T18:01:58.097Z] 18:01:58     INFO - GECKO(2473) |     #6 js::NewContext(unsigned int, JSRuntime*) /builds/worker/checkouts/gecko/js/src/vm/JSContext.cpp:192:17 (libxul.so+0x67ad83f)
[task 2020-05-31T18:01:58.097Z] 18:01:58     INFO - GECKO(2473) |     #7 JS_NewContext(unsigned int, JSRuntime*) /builds/worker/checkouts/gecko/js/src/jsapi.cpp:393:10 (libxul.so+0x6600c34)
[task 2020-05-31T18:01:58.097Z] 18:01:58     INFO - GECKO(2473) |     #8 mozilla::CycleCollectedJSContext::Initialize(JSRuntime*, unsigned int) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:123:16 (libxul.so+0xa09dfb)
[task 2020-05-31T18:01:58.099Z] 18:01:58     INFO - GECKO(2473) |     #9 XPCJSContext::Initialize() /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1119:32 (libxul.so+0x19121ea)
[task 2020-05-31T18:01:58.099Z] 18:01:58     INFO - GECKO(2473) |     #10 XPCJSContext::NewXPCJSContext() /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1315:23 (libxul.so+0x1912de7)
[task 2020-05-31T18:01:58.099Z] 18:01:58     INFO - GECKO(2473) |     #11 nsXPConnect::InitJSContext() /builds/worker/checkouts/gecko/js/xpconnect/src/nsXPConnect.cpp:80:25 (libxul.so+0x194cc53)
[task 2020-05-31T18:01:58.100Z] 18:01:58     INFO - GECKO(2473) |     #12 xpc::InitializeJSContext() /builds/worker/checkouts/gecko/js/xpconnect/src/nsXPConnect.cpp:98:35 (libxul.so+0x194cd0f)
[task 2020-05-31T18:01:58.100Z] 18:01:58     INFO - GECKO(2473) |     #13 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4437:5 (libxul.so+0x64214df)
[task 2020-05-31T18:01:58.100Z] 18:01:58     INFO - GECKO(2473) |     #14 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4811:8 (libxul.so+0x6422773)
[task 2020-05-31T18:01:58.101Z] 18:01:58     INFO - GECKO(2473) |     #15 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4865:21 (libxul.so+0x6422ad4)
[task 2020-05-31T18:01:58.101Z] 18:01:58     INFO - GECKO(2473) |     #16 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x642d682)
[task 2020-05-31T18:01:58.102Z] 18:01:58     INFO - GECKO(2473) |     #17 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:217:22 (firefox+0xca433)
[task 2020-05-31T18:01:58.102Z] 18:01:58     INFO - GECKO(2473) |     #18 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:331:16 (firefox+0xca433)
[task 2020-05-31T18:01:58.102Z] 18:01:58     INFO - GECKO(2473) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/js/src/gc/Cell.h:597:49 in flagsField
[task 2020-05-31T18:01:58.103Z] 18:01:58     INFO - GECKO(2473) | ==================
[task 2020-05-31T18:04:54.045Z] 18:04:54     INFO - runtests.py | Waiting for browser...
[task 2020-05-31T18:04:54.046Z] 18:04:54     INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2020-05-31T18:04:54.046Z] 18:04:54     INFO - Buffered messages finished
[task 2020-05-31T18:04:54.046Z] 18:04:54    ERROR - TEST-UNEXPECTED-FAIL | automation.py | application terminated with exit code -6
[task 2020-05-31T18:04:54.047Z] 18:04:54     INFO - runtests.py | Application ran for: 0:03:00.073851
[task 2020-05-31T18:04:54.047Z] 18:04:54     INFO - zombiecheck | Reading PID log: /tmp/tmpsbYwywpidlog
[task 2020-05-31T18:04:54.047Z] 18:04:54     INFO - ==> process 2473 launched child process 2489
[task 2020-05-31T18:04:54.047Z] 18:04:54     INFO - zombiecheck | Checking for orphan process with PID: 2489
[task 2020-05-31T18:04:54.048Z] 18:04:54     INFO -  Traceback (most recent call last):
[task 2020-05-31T18:04:54.048Z] 18:04:54     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2933, in doTests
[task 2020-05-31T18:04:54.048Z] 18:04:54     INFO -      e10s=options.e10s
[task 2020-05-31T18:04:54.048Z] 18:04:54     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2434, in runApp
[task 2020-05-31T18:04:54.049Z] 18:04:54     INFO -      six.reraise(exc, value, tb)
[task 2020-05-31T18:04:54.049Z] 18:04:54     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2347, in runApp
[task 2020-05-31T18:04:54.049Z] 18:04:54     INFO -      self.marionette.start_session()
[task 2020-05-31T18:04:54.049Z] 18:04:54     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 36, in _
[task 2020-05-31T18:04:54.049Z] 18:04:54     INFO -      m._handle_socket_failure()
[task 2020-05-31T18:04:54.050Z] 18:04:54     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 654, in _handle_socket_failure
[task 2020-05-31T18:04:54.050Z] 18:04:54     INFO -      reraise(exc_cls, exc, tb)
[task 2020-05-31T18:04:54.050Z] 18:04:54     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-05-31T18:04:54.050Z] 18:04:54     INFO -      return func(*args, **kwargs)
[task 2020-05-31T18:04:54.050Z] 18:04:54     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1112, in start_session
[task 2020-05-31T18:04:54.051Z] 18:04:54     INFO -      self.raise_for_port(timeout=timeout)
[task 2020-05-31T18:04:54.051Z] 18:04:54     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 573, in raise_for_port
[task 2020-05-31T18:04:54.051Z] 18:04:54     INFO -      self.host, self.port))
[task 2020-05-31T18:04:54.051Z] 18:04:54     INFO -  timeout: Timed out waiting for connection on 127.0.0.1:2828!
[task 2020-05-31T18:04:54.051Z] 18:04:54    ERROR - Automation Error: Received unexpected exception while running application
[task 2020-05-31T18:04:54.051Z] 18:04:54    ERROR - 
[task 2020-05-31T18:04:54.052Z] 18:04:54     INFO - Stopping web server
[task 2020-05-31T18:04:54.068Z] 18:04:54     INFO - Stopping web socket server
[task 2020-05-31T18:04:54.083Z] 18:04:54     INFO - Stopping ssltunnel
[task 2020-05-31T18:04:54.105Z] 18:04:54  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2020-05-31T18:04:54.105Z] 18:04:54     INFO - runtests.py | Running tests: end.
[task 2020-05-31T18:04:54.105Z] 18:04:54     INFO - Buffered messages finished
[task 2020-05-31T18:04:54.105Z] 18:04:54     INFO - Running manifest: dom/websocket/tests/mochitest.ini
[task 2020-05-31T18:04:54.126Z] 18:04:54     INFO -  Setting pipeline to PAUSED ...
[task 2020-05-31T18:04:54.126Z] 18:04:54     INFO -  Pipeline is PREROLLING ...
[task 2020-05-31T18:04:54.126Z] 18:04:54     INFO -  Pipeline is PREROLLED ...
[task 2020-05-31T18:04:54.126Z] 18:04:54     INFO -  Setting pipeline to PLAYING ...
[task 2020-05-31T18:04:54.126Z] 18:04:54     INFO -  New clock: GstSystemClock
[task 2020-05-31T18:04:54.162Z] 18:04:54     INFO -  Got EOS from element "pipeline0".
[task 2020-05-31T18:04:54.162Z] 18:04:54     INFO -  Execution ended after 0:00:00.033467226
[task 2020-05-31T18:04:54.162Z] 18:04:54     INFO -  Setting pipeline to PAUSED ...
[task 2020-05-31T18:04:54.162Z] 18:04:54     INFO -  Setting pipeline to READY ...
[task 2020-05-31T18:04:54.162Z] 18:04:54     INFO -  (gst-launch-1.0:2529): GStreamer-CRITICAL **: 18:04:54.159: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2020-05-31T18:04:54.162Z] 18:04:54     INFO -  Setting pipeline to NULL ...
[task 2020-05-31T18:04:54.162Z] 18:04:54     INFO -  Freeing pipeline ...
[task 2020-05-31T18:04:54.178Z] 18:04:54     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-05-31T18:04:54.446Z] 18:04:54     INFO -  pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2020-05-31T18:04:54.823Z] 18:04:54     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-05-31T18:04:54.824Z] 18:04:54     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-05-31T18:04:54.827Z] 18:04:54     INFO - MochitestServer : launching [u'/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-f', '/builds/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpzfKDP1.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2020-05-31T18:04:54.828Z] 18:04:54     INFO - runtests.py | Server pid: 2548
[task 2020-05-31T18:04:54.844Z] 18:04:54     INFO - runtests.py | Websocket server pid: 2551
[task 2020-05-31T18:04:54.845Z] 18:04:54     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-05-31T18:04:54.852Z] 18:04:54     INFO - runtests.py | SSL tunnel pid: 2554
...
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Blocks: tsan
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.