Closed Bug 1724499 Opened 3 years ago Closed 3 years ago

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.cpp:2297:16 in mozilla::net::nsSocketTransport::OpenInputStream(unsigned int, unsigned int, unsigned int, nsIInputStream**)

Categories

(Core :: Networking, defect, P5)

Product:
Core
Component:
Networking
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1712671

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: csectype-race, intermittent-failure, sec-moderate)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=347679737&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Jb85xua5RSyyNqtDonDFVQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Jb85xua5RSyyNqtDonDFVQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2021-08-06T18:10:56.497Z] 18:10:56     INFO - REFTEST TEST-START | layout/reftests/font-face/bug-1481905-cancel-load.html == layout/reftests/font-face/bug-1481905-cancel-load-ref.html
[task 2021-08-06T18:10:56.503Z] 18:10:56     INFO - REFTEST TEST-LOAD | http://localhost:45974/1628273347666/12/font-face/bug-1481905-cancel-load.html | 121 / 142 (85%)
[task 2021-08-06T18:10:56.796Z] 18:10:56     INFO - ==================
[task 2021-08-06T18:10:56.798Z] 18:10:56     INFO - WARNING: ThreadSanitizer: data race (pid=1708)
[task 2021-08-06T18:10:56.799Z] 18:10:56     INFO -   Write of size 1 at 0x7b5c001ceef5 by main thread:
[task 2021-08-06T18:10:56.800Z] 18:10:56     INFO -     #0 mozilla::net::nsSocketTransport::OpenInputStream(unsigned int, unsigned int, unsigned int, nsIInputStream**) /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.cpp:2297:16 (libxul.so+0xe56eca)
[task 2021-08-06T18:10:56.802Z] 18:10:56     INFO -     #1 non-virtual thunk to mozilla::net::nsSocketTransport::OpenInputStream(unsigned int, unsigned int, unsigned int, nsIInputStream**) /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.cpp (libxul.so+0xe57006)
[task 2021-08-06T18:10:56.803Z] 18:10:56     INFO -     #2 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0xcc6c95)
[task 2021-08-06T18:10:56.803Z] 18:10:56     INFO -     #3 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:921:10 (libxul.so+0x1bc0a90)
[task 2021-08-06T18:10:56.803Z] 18:10:56     INFO -     #4 <null> <null> (0x7f0fcd5600a1)
[task 2021-08-06T18:10:56.803Z] 18:10:56     INFO -     #5 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:361:32 (libxul.so+0x6c52f4b)
[task 2021-08-06T18:10:56.804Z] 18:10:56     INFO -     #6 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:521:13 (libxul.so+0x6c6a151)
[task 2021-08-06T18:10:56.805Z] 18:10:56     INFO -     #7 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:549:10 (libxul.so+0x6c6abe3)
[task 2021-08-06T18:10:56.806Z] 18:10:56     INFO -     #8 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:566:8 (libxul.so+0x6c6abe3)
[task 2021-08-06T18:10:56.807Z] 18:10:56     INFO -     #9 JS_CallFunctionValue(JSContext*, JS::Handle<JSObject*>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CallAndConstruct.cpp:53:10 (libxul.so+0x6da8549)
[task 2021-08-06T18:10:56.808Z] 18:10:56     INFO -     #10 nsXPCWrappedJS::CallMethod(unsigned short, nsXPTMethodInfo const*, nsXPTCMiniVariant*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedJSClass.cpp:973:17 (libxul.so+0x1bb76c9)
[task 2021-08-06T18:10:56.810Z] 18:10:56     INFO -     #11 PrepareAndDispatch /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcstubs_x86_64_linux.cpp:115:37 (libxul.so+0xcc7c71)
[task 2021-08-06T18:10:56.816Z] 18:10:56     INFO -     #12 SharedStub <null> (libxul.so+0xcc6fd2)
[task 2021-08-06T18:10:56.816Z] 18:10:56     INFO -     #13 mozilla::RunnableTask::Run() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:502:16 (libxul.so+0xcacac7)
[task 2021-08-06T18:10:56.817Z] 18:10:56     INFO -     #14 mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:805:26 (libxul.so+0xc89b04)
[task 2021-08-06T18:10:56.823Z] 18:10:56     INFO -     #15 mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:641:15 (libxul.so+0xc88136)
[task 2021-08-06T18:10:56.823Z] 18:10:56     INFO -     #16 mozilla::TaskController::ProcessPendingMTTask(bool) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:425:36 (libxul.so+0xc88404)
[task 2021-08-06T18:10:56.823Z] 18:10:56     INFO -     #17 operator() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:135:37 (libxul.so+0xcb09b7)
[task 2021-08-06T18:10:56.824Z] 18:10:56     INFO -     #18 mozilla::detail::RunnableFunction<mozilla::TaskController::InitializeInternal()::$_0>::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.h:532:5 (libxul.so+0xcb09b7)
[task 2021-08-06T18:10:56.827Z] 18:10:56     INFO -     #19 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1148:16 (libxul.so+0xc9a5c5)
[task 2021-08-06T18:10:56.827Z] 18:10:56     INFO -     #20 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:466:10 (libxul.so+0xca1632)
[task 2021-08-06T18:10:56.827Z] 18:10:56     INFO -     #21 mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:85:21 (libxul.so+0x15afe2d)
[task 2021-08-06T18:10:56.828Z] 18:10:56     INFO -     #22 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:331:10 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.831Z] 18:10:56     INFO -     #23 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:324:3 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.831Z] 18:10:56     INFO -     #24 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:306:3 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.831Z] 18:10:56     INFO -     #25 nsBaseAppShell::Run() /builds/worker/checkouts/gecko/widget/nsBaseAppShell.cpp:137:27 (libxul.so+0x4cc42b6)
[task 2021-08-06T18:10:56.832Z] 18:10:56     INFO -     #26 nsAppStartup::Run() /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:274:30 (libxul.so+0x6a14918)
[task 2021-08-06T18:10:56.840Z] 18:10:56     INFO -     #27 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5295:22 (libxul.so+0x6b3088a)
[task 2021-08-06T18:10:56.843Z] 18:10:56     INFO -     #28 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5487:8 (libxul.so+0x6b315d7)
[task 2021-08-06T18:10:56.843Z] 18:10:56     INFO -     #29 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5546:21 (libxul.so+0x6b31b74)
[task 2021-08-06T18:10:56.843Z] 18:10:56     INFO -     #30 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x6b3c0e2)
[task 2021-08-06T18:10:56.843Z] 18:10:56     INFO -     #31 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:225:22 (firefox+0xca340)
[task 2021-08-06T18:10:56.844Z] 18:10:56     INFO -     #32 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:378:16 (firefox+0xca340)
[task 2021-08-06T18:10:56.846Z] 18:10:56     INFO -   Previous write of size 1 at 0x7b5c001ceef5 by thread T6:
[task 2021-08-06T18:10:56.847Z] 18:10:56     INFO -     #0 OnMsgInputClosed /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.cpp:1749:16 (libxul.so+0xe4d938)
[task 2021-08-06T18:10:56.847Z] 18:10:56     INFO -     #1 mozilla::net::nsSocketTransport::OnInputClosed(nsresult) /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.h:409:7 (libxul.so+0xe4d938)
[task 2021-08-06T18:10:56.847Z] 18:10:56     INFO -     #2 mozilla::net::nsSocketInputStream::CloseWithStatus(nsresult) /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.cpp:441:34 (libxul.so+0xe4e119)
[task 2021-08-06T18:10:56.847Z] 18:10:56     INFO -     #3 mozilla::net::nsSocketInputStream::Close() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.cpp:302:39 (libxul.so+0xe4d0c3)
[task 2021-08-06T18:10:56.847Z] 18:10:56     INFO -     #4 mozilla::net::nsSocketInputStream::Release() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.cpp:297:29 (libxul.so+0xe4cfaf)
[task 2021-08-06T18:10:56.850Z] 18:10:56     INFO -     #5 assign_assuming_AddRef /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:377:7 (libxul.so+0xc59a3c)
[task 2021-08-06T18:10:56.850Z] 18:10:56     INFO -     #6 assign_assuming_AddRef /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:400:20 (libxul.so+0xc59a3c)
[task 2021-08-06T18:10:56.850Z] 18:10:56     INFO -     #7 operator= /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:696:5 (libxul.so+0xc59a3c)
[task 2021-08-06T18:10:56.850Z] 18:10:56     INFO -     #8 nsAStreamCopier::Process() /builds/worker/checkouts/gecko/xpcom/io/nsStreamUtils.cpp:346:17 (libxul.so+0xc59a3c)
[task 2021-08-06T18:10:56.850Z] 18:10:56     INFO -     #9 Run /builds/worker/checkouts/gecko/xpcom/io/nsStreamUtils.cpp:418:5 (libxul.so+0xc56e71)
[task 2021-08-06T18:10:56.850Z] 18:10:56     INFO -     #10 non-virtual thunk to nsAStreamCopier::Run() /builds/worker/checkouts/gecko/xpcom/io/nsStreamUtils.cpp (libxul.so+0xc56e71)
[task 2021-08-06T18:10:56.851Z] 18:10:56     INFO -     #11 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1142:16 (libxul.so+0xc9a8aa)
[task 2021-08-06T18:10:56.854Z] 18:10:56     INFO -     #12 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:466:10 (libxul.so+0xca1632)
[task 2021-08-06T18:10:56.854Z] 18:10:56     INFO -     #13 mozilla::net::nsSocketTransportService::Run() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp:1190:11 (libxul.so+0xe61187)
[task 2021-08-06T18:10:56.854Z] 18:10:56     INFO -     #14 non-virtual thunk to mozilla::net::nsSocketTransportService::Run() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp (libxul.so+0xe62619)
[task 2021-08-06T18:10:56.857Z] 18:10:56     INFO -     #15 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1142:16 (libxul.so+0xc9a8aa)
[task 2021-08-06T18:10:56.857Z] 18:10:56     INFO -     #16 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:466:10 (libxul.so+0xca1632)
[task 2021-08-06T18:10:56.858Z] 18:10:56     INFO -     #17 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:300:20 (libxul.so+0x15b09be)
[task 2021-08-06T18:10:56.861Z] 18:10:56     INFO -     #18 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:331:10 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.861Z] 18:10:56     INFO -     #19 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:324:3 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.861Z] 18:10:56     INFO -     #20 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:306:3 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.861Z] 18:10:56     INFO -     #21 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:390:10 (libxul.so+0xc96ff8)
[task 2021-08-06T18:10:56.864Z] 18:10:56     INFO -     #22 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x44cd6)
[task 2021-08-06T18:10:56.864Z] 18:10:56     INFO -   Location is heap block of size 776 at 0x7b5c001cee00 allocated by thread T6:
[task 2021-08-06T18:10:56.864Z] 18:10:56     INFO -     #0 malloc /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:651:5 (firefox+0x55fbb)
[task 2021-08-06T18:10:56.864Z] 18:10:56     INFO -     #1 moz_xmalloc /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc.cpp:52:15 (firefox+0xcc44b)
[task 2021-08-06T18:10:56.864Z] 18:10:56     INFO -     #2 operator new /builds/worker/workspace/obj-build/dist/include/mozilla/cxxalloc.h:33:10 (libxul.so+0xe46084)
[task 2021-08-06T18:10:56.864Z] 18:10:56     INFO -     #3 mozilla::net::nsServerSocket::CreateClientTransport(PRFileDesc*, mozilla::net::NetAddr const&) /builds/worker/checkouts/gecko/netwerk/base/nsServerSocket.cpp:128:37 (libxul.so+0xe46084)
[task 2021-08-06T18:10:56.864Z] 18:10:56     INFO -     #4 mozilla::net::nsServerSocket::OnSocketReady(PRFileDesc*, short) /builds/worker/checkouts/gecko/netwerk/base/nsServerSocket.cpp:178:3 (libxul.so+0xe464de)
[task 2021-08-06T18:10:56.868Z] 18:10:56     INFO -     #5 mozilla::net::nsSocketTransportService::DoPollIteration(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator>*) /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp (libxul.so+0xe61d0f)
[task 2021-08-06T18:10:56.868Z] 18:10:56     INFO -     #6 mozilla::net::nsSocketTransportService::Run() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp:1150:7 (libxul.so+0xe60ef9)
[task 2021-08-06T18:10:56.868Z] 18:10:56     INFO -     #7 non-virtual thunk to mozilla::net::nsSocketTransportService::Run() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp (libxul.so+0xe62619)
[task 2021-08-06T18:10:56.868Z] 18:10:56     INFO -     #8 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1142:16 (libxul.so+0xc9a8aa)
[task 2021-08-06T18:10:56.868Z] 18:10:56     INFO -     #9 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:466:10 (libxul.so+0xca1632)
[task 2021-08-06T18:10:56.868Z] 18:10:56     INFO -     #10 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:300:20 (libxul.so+0x15b09be)
[task 2021-08-06T18:10:56.870Z] 18:10:56     INFO -     #11 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:331:10 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.870Z] 18:10:56     INFO -     #12 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:324:3 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.870Z] 18:10:56     INFO -     #13 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:306:3 (libxul.so+0x1529dac)
[task 2021-08-06T18:10:56.871Z] 18:10:56     INFO -     #14 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:390:10 (libxul.so+0xc96ff8)
[task 2021-08-06T18:10:56.871Z] 18:10:56     INFO -     #15 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x44cd6)
[task 2021-08-06T18:10:56.871Z] 18:10:56     INFO -   Thread T6 'Socket Thread' (tid=1720, running) created by main thread at:
[task 2021-08-06T18:10:56.873Z] 18:10:56     INFO -     #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:965:3 (firefox+0x577eb)
[task 2021-08-06T18:10:56.873Z] 18:10:56     INFO -     #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3be33)
[task 2021-08-06T18:10:56.873Z] 18:10:56     INFO -     #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x30915)
[task 2021-08-06T18:10:56.873Z] 18:10:56     INFO -     #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:602:18 (libxul.so+0xc98362)
[task 2021-08-06T18:10:56.873Z] 18:10:56     INFO -     #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, unsigned int, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:574:12 (libxul.so+0xca04ba)
[task 2021-08-06T18:10:56.876Z] 18:10:56     INFO -     #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:162:57 (libxul.so+0xca8374)
[task 2021-08-06T18:10:56.876Z] 18:10:56     INFO -     #6 NS_NewNamedThread<14> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:85:10 (libxul.so+0xe5f8af)
[task 2021-08-06T18:10:56.876Z] 18:10:56     INFO -     #7 mozilla::net::nsSocketTransportService::Init() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp:760:7 (libxul.so+0xe5f8af)
[task 2021-08-06T18:10:56.876Z] 18:10:56     INFO -     #8 mozilla::xpcom::CreateInstanceImpl(mozilla::xpcom::ModuleID, nsISupports*, nsID const&, void**) /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:10599:7 (libxul.so+0xc5e648)
[task 2021-08-06T18:10:56.877Z] 18:10:56     INFO -     #9 mozilla::xpcom::StaticModule::CreateInstance(nsISupports*, nsID const&, void**) const /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:12100:10 (libxul.so+0xc5b983)
[task 2021-08-06T18:10:56.878Z] 18:10:56     INFO -     #10 CreateInstance /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:177:46 (libxul.so+0xc72d38)
[task 2021-08-06T18:10:56.879Z] 18:10:56     INFO -     #11 nsComponentManagerImpl::GetServiceLocked(mozilla::Maybe<mozilla::MonitorAutoLock>&, (anonymous namespace)::EntryWrapper&, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1276:17 (libxul.so+0xc72d38)
[task 2021-08-06T18:10:56.880Z] 18:10:56     INFO -     #12 nsComponentManagerImpl::GetServiceByContractID(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1465:10 (libxul.so+0xc739a7)
[task 2021-08-06T18:10:56.881Z] 18:10:56     INFO -     #13 CallGetService /builds/worker/checkouts/gecko/xpcom/components/nsComponentManagerUtils.cpp:61:43 (libxul.so+0xc7686d)
[task 2021-08-06T18:10:56.883Z] 18:10:56     INFO -     #14 nsGetServiceByContractIDWithError::operator()(nsID const&, void**) const /builds/worker/checkouts/gecko/xpcom/components/nsComponentManagerUtils.cpp:253:21 (libxul.so+0xc7686d)
[task 2021-08-06T18:10:56.884Z] 18:10:56     INFO -     #15 nsCOMPtr_base::assign_from_gs_contractid_with_error(nsGetServiceByContractIDWithError const&, nsID const&) /builds/worker/checkouts/gecko/xpcom/base/nsCOMPtr.cpp:91:7 (libxul.so+0xbb47e7)
[task 2021-08-06T18:10:56.885Z] 18:10:56     INFO -     #16 operator= /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:774:5 (libxul.so+0xe04561)
[task 2021-08-06T18:10:56.886Z] 18:10:56     INFO -     #17 InitializeSocketTransportService /builds/worker/checkouts/gecko/netwerk/base/nsIOService.cpp:420:29 (libxul.so+0xe04561)
[task 2021-08-06T18:10:56.887Z] 18:10:56     INFO -     #18 mozilla::net::nsIOService::SetOffline(bool) /builds/worker/checkouts/gecko/netwerk/base/nsIOService.cpp:1271:7 (libxul.so+0xe04561)
[task 2021-08-06T18:10:56.889Z] 18:10:56     INFO -     #19 mozilla::net::nsIOService::Init() /builds/worker/checkouts/gecko/netwerk/base/nsIOService.cpp:304:3 (libxul.so+0xe0379d)
[task 2021-08-06T18:10:56.890Z] 18:10:56     INFO -     #20 mozilla::net::nsIOService::GetInstance() /builds/worker/checkouts/gecko/netwerk/base/nsIOService.cpp:477:9 (libxul.so+0xe05321)
[task 2021-08-06T18:10:56.891Z] 18:10:56     INFO -     #21 mozilla::xpcom::CreateInstanceImpl(mozilla::xpcom::ModuleID, nsISupports*, nsID const&, void**) /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:8756:48 (libxul.so+0xc5c225)
[task 2021-08-06T18:10:56.892Z] 18:10:56     INFO -     #22 mozilla::xpcom::StaticModule::CreateInstance(nsISupports*, nsID const&, void**) const /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:12100:10 (libxul.so+0xc5b983)
[task 2021-08-06T18:10:56.893Z] 18:10:56     INFO -     #23 CreateInstance /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:177:46 (libxul.so+0xc72d38)
[task 2021-08-06T18:10:56.894Z] 18:10:56     INFO -     #24 nsComponentManagerImpl::GetServiceLocked(mozilla::Maybe<mozilla::MonitorAutoLock>&, (anonymous namespace)::EntryWrapper&, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1276:17 (libxul.so+0xc72d38)
[task 2021-08-06T18:10:56.896Z] 18:10:56     INFO -     #25 nsComponentManagerImpl::GetServiceByContractID(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1465:10 (libxul.so+0xc739a7)
[task 2021-08-06T18:10:56.897Z] 18:10:56     INFO -     #26 CallGetService(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManagerUtils.cpp:61:43 (libxul.so+0xc7637d)
[task 2021-08-06T18:10:56.898Z] 18:10:56     INFO -     #27 CallGetService<nsIIOService> /builds/worker/workspace/obj-build/dist/include/nsServiceManagerUtils.h:52:10 (libxul.so+0x1cf2b55)
[task 2021-08-06T18:10:56.899Z] 18:10:56     INFO -     #28 nsScriptSecurityManager::Init() /builds/worker/checkouts/gecko/caps/nsScriptSecurityManager.cpp:1466:17 (libxul.so+0x1cf2b55)
[task 2021-08-06T18:10:56.900Z] 18:10:56     INFO -     #29 nsScriptSecurityManager::InitStatics() /builds/worker/checkouts/gecko/caps/nsScriptSecurityManager.cpp:1527:28 (libxul.so+0x1cf3151)
[task 2021-08-06T18:10:56.901Z] 18:10:56     INFO -     #30 nsXPConnect::InitStatics() /builds/worker/checkouts/gecko/js/xpconnect/src/nsXPConnect.cpp:154:3 (libxul.so+0x1bc76f3)
[task 2021-08-06T18:10:56.903Z] 18:10:56     INFO -     #31 xpcModuleCtor() /builds/worker/checkouts/gecko/js/xpconnect/src/XPCModule.cpp:11:3 (libxul.so+0x1b9a4a2)
[task 2021-08-06T18:10:56.904Z] 18:10:56     INFO -     #32 nsLayoutModuleInitialize() /builds/worker/checkouts/gecko/layout/build/nsLayoutModule.cpp:100:7 (libxul.so+0x54ea5cb)
[task 2021-08-06T18:10:56.905Z] 18:10:56     INFO -     #33 nsComponentManagerImpl::Init() /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:408:5 (libxul.so+0xc6e81f)
[task 2021-08-06T18:10:56.906Z] 18:10:56     INFO -     #34 NS_InitXPCOM /builds/worker/checkouts/gecko/xpcom/build/XPCOMInit.cpp:446:51 (libxul.so+0xcd890a)
[task 2021-08-06T18:10:56.907Z] 18:10:56     INFO -     #35 ScopedXPCOMStartup::Initialize(bool) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:1717:8 (libxul.so+0x6b2671d)
[task 2021-08-06T18:10:56.908Z] 18:10:56     INFO -     #36 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5476:22 (libxul.so+0x6b315c1)
[task 2021-08-06T18:10:56.909Z] 18:10:56     INFO -     #37 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5546:21 (libxul.so+0x6b31b74)
[task 2021-08-06T18:10:56.910Z] 18:10:56     INFO -     #38 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x6b3c0e2)
[task 2021-08-06T18:10:56.911Z] 18:10:56     INFO -     #39 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:225:22 (firefox+0xca340)
[task 2021-08-06T18:10:56.912Z] 18:10:56     INFO -     #40 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:378:16 (firefox+0xca340)
[task 2021-08-06T18:10:56.913Z] 18:10:56     INFO - SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransport2.cpp:2297:16 in mozilla::net::nsSocketTransport::OpenInputStream(unsigned int, unsigned int, unsigned int, nsIInputStream**)
[task 2021-08-06T18:10:56.914Z] 18:10:56     INFO - ==================
[task 2021-08-06T18:10:56.936Z] 18:10:56     INFO - Exiting due to channel error.
[task 2021-08-06T18:10:56.938Z] 18:10:56     INFO - Exiting due to channel error.
[task 2021-08-06T18:10:56.950Z] 18:10:56     INFO - Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=116.657) Exiting due to channel error.
[task 2021-08-06T18:10:56.951Z] 18:10:56     INFO - Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=18.9324) Exiting due to channel error.
[task 2021-08-06T18:10:57.968Z] 18:10:57     INFO - Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=133.293)
[task 2021-08-06T18:10:57.968Z] 18:10:57    ERROR - TEST-UNEXPECTED-FAIL | layout/reftests/font-face/bug-1481905-cancel-load.html | application terminated with exit code -6
[task 2021-08-06T18:10:57.985Z] 18:10:57     INFO - REFTEST INFO | Process mode: e10s
[task 2021-08-06T18:10:57.986Z] 18:10:57  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-08-06T18:10:57.987Z] 18:10:57     INFO - REFTEST INFO | Running tests in file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/css-blending/reftest.list
[task 2021-08-06T18:10:58.013Z] 18:10:58     INFO - REFTEST INFO | INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-08-06T18:10:58.023Z] 18:10:58     INFO - REFTEST INFO | Running with e10s: True
[task 2021-08-06T18:10:58.023Z] 18:10:58     INFO - REFTEST INFO | Running with fission: False
[task 2021-08-06T18:10:58.023Z] 18:10:58     INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmpyc_3p6g6.mozrunner
[task 2021-08-06T18:11:03.121Z] 18:11:03     INFO - 1628273463120	Marionette	INFO	Marionette enabled
[task 2021-08-06T18:11:03.964Z] 18:11:03     INFO - 1628273463962	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-08-06T18:11:17.644Z] 18:11:17     INFO - console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpyc_3p6g6.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-08-06T18:11:25.991Z] 18:11:25     INFO - 1628273485990	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-08-06T18:11:25.996Z] 18:11:25     INFO - 1628273485992	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-08-06T18:11:26.124Z] 18:11:26     INFO - 1628273486122	Marionette	TRACE	All scripts recorded.
[task 2021-08-06T18:11:26.159Z] 18:11:26     INFO - 1628273486157	Marionette	INFO	Listening on port 2828
[task 2021-08-06T18:11:26.163Z] 18:11:26     INFO - 1628273486162	Marionette	DEBUG	Marionette is listening
[task 2021-08-06T18:11:26.484Z] 18:11:26     INFO - 1628273486483	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:43922
[task 2021-08-06T18:11:26.572Z] 18:11:26     INFO - 1628273486571	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:43924
[task 2021-08-06T18:11:26.581Z] 18:11:26     INFO - 1628273486580	Marionette	DEBUG	Closed connection 0
[task 2021-08-06T18:11:26.701Z] 18:11:26     INFO - 1628273486699	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-08-06T18:11:26.925Z] 18:11:26     INFO - 1628273486918	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"c85a1726-68a4-4992-aea8-ad0325098202","capabilities":{"browserName":"firefox","browserVersion":"92.0a ... .mozrunner","moz:shutdownTimeout":360000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-08-06T18:11:27.045Z] 18:11:27     INFO - 1628273487043	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/reftest","temporary":true}]
[task 2021-08-06T18:11:27.768Z] 18:11:27     INFO - 1628273487766	Marionette	TRACE	Received observer notification domwindowopened
[task 2021-08-06T18:11:27.787Z] 18:11:27     INFO - 1628273487785	Marionette	DEBUG	1 <- [1,2,null,{"value":"reftest@mozilla.org"}]
[task 2021-08-06T18:11:27.846Z] 18:11:27     INFO - 1628273487845	Marionette	DEBUG	1 -> [0,3,"WebDriver:DeleteSession",{}]
[task 2021-08-06T18:11:27.877Z] 18:11:27     INFO - 1628273487875	Marionette	DEBUG	1 <- [1,3,null,{"value":null}]
[task 2021-08-06T18:11:27.890Z] 18:11:27     INFO - 1628273487888	Marionette	DEBUG	Closed connection 1
[task 2021-08-06T18:11:30.285Z] 18:11:30     INFO - REFTEST TEST-START | layout/reftests/css-blending/blend-canvas.html == layout/reftests/css-blending/blend-canvas-ref.html
Blocks: tsan
Group: core-security → network-core-security
Keywords: csectype-race

From the line numbers, maybe this is a race on nsSocketTransport::mInputClosed.

This seems to be similar to bug 1712671. Should we hide also the other one?

Flags: needinfo?(dveditz)
Status: NEW → RESOLVED
Closed: 3 years ago
Flags: needinfo?(dveditz)
Resolution: --- → DUPLICATE
Group: network-core-security
You need to log in before you can comment on or make changes to this bug.