1901791 - Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.h:1262:29 in SetToEmptyBuffer

Status: RESOLVED FIXED

(Core :: Networking: HTTP, defect, P2)

Description

[Treeherder Bug Filer]

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

---

[task 2024-06-11T14:28:47.555Z] 14:28:47     INFO -  TEST-START | TestPACMan.WhenTheDHCPResponseIsEmptyWPADDefaultsToStandardURL
[task 2024-06-11T14:28:47.617Z] 14:28:47     INFO -  ==================
[task 2024-06-11T14:28:47.618Z] 14:28:47     INFO -  WARNING: ThreadSanitizer: data race (pid=1000)
[task 2024-06-11T14:28:47.619Z] 14:28:47     INFO -    Write of size 8 at 0x7fdce6f1a678 by main thread:
[task 2024-06-11T14:28:47.619Z] 14:28:47     INFO -      #0 SetToEmptyBuffer /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.h:1262:29 (libxul.so+0x56218ff) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.620Z] 14:28:47     INFO -      #1 nsTSubstring<char>::StartBulkWriteImpl(unsigned long, unsigned long, bool, unsigned long, unsigned long, unsigned long) /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.cpp:120:5 (libxul.so+0x56218ff)
[task 2024-06-11T14:28:47.621Z] 14:28:47     INFO -      #2 nsTSubstring<char>::Assign(char const*, unsigned long, std::nothrow_t const&) /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.cpp:429:12 (libxul.so+0x562b86b) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.622Z] 14:28:47     INFO -      #3 nsTSubstring<char>::Assign(char const*, unsigned long) /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.cpp:401:7 (libxul.so+0x562832c) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.624Z] 14:28:47     INFO -      #4 SetOptionResult /builds/worker/checkouts/gecko/netwerk/test/gtest/TestPACMan.cpp:76:61 (libxul.so+0x39c5765) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.625Z] 14:28:47     INFO -      #5 mozilla::net::TestPACMan_WhenTheDHCPResponseIsEmptyWPADDefaultsToStandardURL_Test::TestBody() /builds/worker/checkouts/gecko/netwerk/test/gtest/TestPACMan.cpp:202:3 (libxul.so+0x39c5765)
[task 2024-06-11T14:28:47.626Z] 14:28:47     INFO -      #6 HandleExceptionsInMethodIfSupported<testing::Test, void> /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc (libxul.so+0x3f43ff3) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.626Z] 14:28:47     INFO -      #7 testing::Test::Run() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2710:5 (libxul.so+0x3f43ff3)
[task 2024-06-11T14:28:47.627Z] 14:28:47     INFO -      #8 testing::TestInfo::Run() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2856:11 (libxul.so+0x3f45777) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.628Z] 14:28:47     INFO -      #9 testing::TestSuite::Run() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:3034:30 (libxul.so+0x3f46dd4) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.628Z] 14:28:47     INFO -      #10 testing::internal::UnitTestImpl::RunAllTests() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:5964:44 (libxul.so+0x3f652c9) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.629Z] 14:28:47     INFO -      #11 HandleExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool> /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc (libxul.so+0x3f648ca) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.630Z] 14:28:47     INFO -      #12 testing::UnitTest::Run() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:5543:10 (libxul.so+0x3f648ca)
[task 2024-06-11T14:28:47.630Z] 14:28:47     INFO -      #13 RUN_ALL_TESTS /builds/worker/workspace/obj-build/dist/include/gtest/gtest.h:2336:73 (libxul.so+0x3f1a79b) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.630Z] 14:28:47     INFO -      #14 mozilla::RunGTestFunc(int*, char**) /builds/worker/checkouts/gecko/testing/gtest/mozilla/GTestRunner.cpp:172:10 (libxul.so+0x3f1a79b)
[task 2024-06-11T14:28:47.631Z] 14:28:47     INFO -      #15 XREMain::XRE_mainStartup(bool*) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4717:16 (libxul.so+0xb5a7358) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.631Z] 14:28:47     INFO -      #16 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5980:12 (libxul.so+0xb5af0a2) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.631Z] 14:28:47     INFO -      #17 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:6049:21 (libxul.so+0xb5af7e1) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.631Z] 14:28:47     INFO -      #18 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:49:12 (libxul.so+0xb5be2d2) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.632Z] 14:28:47     INFO -      #19 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:230:22 (firefox-bin+0x144731) (BuildId: 728f1e6e43acc93cfb4c8769cefc433984b34daa)
[task 2024-06-11T14:28:47.632Z] 14:28:47     INFO -      #20 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:448:16 (firefox-bin+0x144731)
[task 2024-06-11T14:28:47.633Z] 14:28:47     INFO -    Previous read of size 8 at 0x7fdce6f1a678 by thread T140817:
[task 2024-06-11T14:28:47.634Z] 14:28:47     INFO -      #0 nsTSubstring<char>::Assign(nsTSubstring<char> const&, std::nothrow_t const&) /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.cpp:506:18 (libxul.so+0x5620d62) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.634Z] 14:28:47     INFO -      #1 nsTSubstring<char>::Assign(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.cpp:476:8 (libxul.so+0x56207d5) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.635Z] 14:28:47     INFO -      #2 mozilla::net::nsTestDHCPClient::GetOption(unsigned char, nsTSubstring<char>&) /builds/worker/checkouts/gecko/netwerk/test/gtest/TestPACMan.cpp:59:11 (libxul.so+0x39c4dfc) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.637Z] 14:28:47     INFO -      #3 mozilla::net::nsPACMan::GetPACFromDHCP(nsTSubstring<char>&) /builds/worker/checkouts/gecko/netwerk/base/nsPACMan.cpp:588:21 (libxul.so+0x5947523) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.638Z] 14:28:47     INFO -      #4 mozilla::net::nsPACMan::ConfigureWPAD(nsTSubstring<char>&) /builds/worker/checkouts/gecko/netwerk/base/nsPACMan.cpp:614:5 (libxul.so+0x594776f) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.646Z] 14:28:47     INFO -      #5 mozilla::net::ExecutePACThreadAction::Run() /builds/worker/checkouts/gecko/netwerk/base/nsPACMan.cpp:287:16 (libxul.so+0x59935e0) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.646Z] 14:28:47     INFO -      #6 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1198:16 (libxul.so+0x575e866) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.646Z] 14:28:47     INFO -      #7 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:480:10 (libxul.so+0x5764b94) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.646Z] 14:28:47     INFO -      #8 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:300:20 (libxul.so+0x620bdde) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.647Z] 14:28:47     INFO -      #9 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:370:10 (libxul.so+0x61892d8) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.647Z] 14:28:47     INFO -      #10 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:363:3 (libxul.so+0x61892d8)
[task 2024-06-11T14:28:47.648Z] 14:28:47     INFO -      #11 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:345:3 (libxul.so+0x61892d8)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #12 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:370:10 (libxul.so+0x575a093) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #13 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x4aef8) (BuildId: 2f48a6bf7ad6210be88702a39a89fa3b18614cff)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -    Location is global 'WPADOptionResult' of size 16 at 0x7fdce6f1a678 (libxul.so+0xee24678)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -    Thread T140817 'ProxyResolution' (tid=13120, running) created by main thread at:
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #0 pthread_create /builds/worker/fetches/llvm-project/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:1023:3 (firefox-bin+0xc5deb) (BuildId: 728f1e6e43acc93cfb4c8769cefc433984b34daa)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x4219e) (BuildId: 2f48a6bf7ad6210be88702a39a89fa3b18614cff)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x374a4) (BuildId: 2f48a6bf7ad6210be88702a39a89fa3b18614cff)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:625:20 (libxul.so+0x575b957) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, nsIThreadManager::ThreadCreationOptions, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:606:22 (libxul.so+0x5763802) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, already_AddRefed<nsIRunnable>, nsIThreadManager::ThreadCreationOptions) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:176:57 (libxul.so+0x576b08c) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #6 NS_NewNamedThread<16UL> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:87:10 (libxul.so+0x5946699) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #7 mozilla::net::nsPACMan::DispatchToPAC(already_AddRefed<nsIRunnable>, bool) /builds/worker/checkouts/gecko/netwerk/base/nsPACMan.cpp:449:5 (libxul.so+0x5946699)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #8 mozilla::net::nsPACMan::StartLoading() /builds/worker/checkouts/gecko/netwerk/base/nsPACMan.cpp (libxul.so+0x59474c5) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #9 operator()<> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1085:18 (libxul.so+0x3733aa9) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.649Z] 14:28:47     INFO -      #10 __invoke_impl<void, (lambda at /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1084:9)> /builds/worker/fetches/sysroot-x86_64-linux-gnu/usr/lib/gcc/x86_64-linux-gnu/8/../../../../include/c++/8/bits/invoke.h:60:14 (libxul.so+0x3733aa9)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #11 __invoke<(lambda at /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1084:9)> /builds/worker/fetches/sysroot-x86_64-linux-gnu/usr/lib/gcc/x86_64-linux-gnu/8/../../../../include/c++/8/bits/invoke.h:95:14 (libxul.so+0x3733aa9)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #12 __apply_impl<(lambda at /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1084:9), std::tuple<> &> /builds/worker/fetches/sysroot-x86_64-linux-gnu/usr/lib/gcc/x86_64-linux-gnu/8/../../../../include/c++/8/tuple:1678:14 (libxul.so+0x3733aa9)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #13 apply<(lambda at /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1084:9), std::tuple<> &> /builds/worker/fetches/sysroot-x86_64-linux-gnu/usr/lib/gcc/x86_64-linux-gnu/8/../../../../include/c++/8/tuple:1687:14 (libxul.so+0x3733aa9)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #14 apply<mozilla::Canonical<int>::Impl, void (mozilla::Canonical<int>::Impl::*)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1083:12 (libxul.so+0x3733aa9)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #15 mozilla::detail::RunnableMethodImpl<nsUpdateProcessor*, void (nsUpdateProcessor::*)(), true, (mozilla::RunnableKind)0>::Run() /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1134:13 (libxul.so+0x3733aa9)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #16 mozilla::RunnableTask::Run() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:580:16 (libxul.so+0x57493f2) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #17 mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:907:26 (libxul.so+0x573fdb9) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #18 mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:730:15 (libxul.so+0x573e5e6) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #19 mozilla::TaskController::ProcessPendingMTTask(bool) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:516:36 (libxul.so+0x573e91f) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #20 operator() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:237:37 (libxul.so+0x574b997) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #21 mozilla::detail::RunnableFunction<mozilla::TaskController::TaskController()::$_1>::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.h:548:5 (libxul.so+0x574b997)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #22 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1204:16 (libxul.so+0x575e650) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #23 NS_ProcessNextEvent /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:480:10 (libxul.so+0x5773be6) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #24 bool mozilla::SpinEventLoopUntil<(mozilla::ProcessFailureBehavior)1, nsThreadSyncDispatch::SpinEventLoopUntilComplete(nsTSubstring<char> const&)::'lambda'()>(nsTSubstring<char> const&, nsThreadSyncDispatch::SpinEventLoopUntilComplete(nsTSubstring<char> const&)::'lambda'()&&, nsIThread*) /builds/worker/workspace/obj-build/dist/include/mozilla/SpinEventLoopUntil.h:176:25 (libxul.so+0x5773be6)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #25 SpinEventLoopUntilComplete /builds/worker/checkouts/gecko/xpcom/threads/nsThreadSyncDispatch.h:32:5 (libxul.so+0x576d005) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #26 NS_DispatchAndSpinEventLoopUntilComplete(nsTSubstring<char> const&, nsIEventTarget*, already_AddRefed<nsIRunnable>) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:767:12 (libxul.so+0x576d005)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #27 mozilla::net::nsPACMan::DispatchToPAC(already_AddRefed<nsIRunnable>, bool) /builds/worker/checkouts/gecko/netwerk/base/nsPACMan.cpp:457:12 (libxul.so+0x59465df) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #28 ProcessPendingEventsOnPACThread /builds/worker/checkouts/gecko/netwerk/test/gtest/TestPACMan.cpp:167:14 (libxul.so+0x3a1d444) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #29 mozilla::net::TestPACMan::ProcessAllEvents() /builds/worker/checkouts/gecko/netwerk/test/gtest/TestPACMan.cpp:100:5 (libxul.so+0x3a1d444)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #30 ProcessAllEventsTenTimes /builds/worker/checkouts/gecko/netwerk/test/gtest/TestPACMan.cpp:117:7 (libxul.so+0x39c53c0) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #31 mozilla::net::TestPACMan_WhenTheDHCPClientExistsAndDHCPIsNonEmptyDHCPOptionIsUsedAsPACUri_Test::TestBody() /builds/worker/checkouts/gecko/netwerk/test/gtest/TestPACMan.cpp:195:3 (libxul.so+0x39c53c0)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #32 HandleExceptionsInMethodIfSupported<testing::Test, void> /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc (libxul.so+0x3f43ff3) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #33 testing::Test::Run() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2710:5 (libxul.so+0x3f43ff3)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #34 testing::TestInfo::Run() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2856:11 (libxul.so+0x3f45777) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #35 testing::TestSuite::Run() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:3034:30 (libxul.so+0x3f46dd4) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #36 testing::internal::UnitTestImpl::RunAllTests() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:5964:44 (libxul.so+0x3f652c9) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #37 HandleExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool> /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc (libxul.so+0x3f648ca) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #38 testing::UnitTest::Run() /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:5543:10 (libxul.so+0x3f648ca)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #39 RUN_ALL_TESTS /builds/worker/workspace/obj-build/dist/include/gtest/gtest.h:2336:73 (libxul.so+0x3f1a79b) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #40 mozilla::RunGTestFunc(int*, char**) /builds/worker/checkouts/gecko/testing/gtest/mozilla/GTestRunner.cpp:172:10 (libxul.so+0x3f1a79b)
[task 2024-06-11T14:28:47.650Z] 14:28:47     INFO -      #41 XREMain::XRE_mainStartup(bool*) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4717:16 (libxul.so+0xb5a7358) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.651Z] 14:28:47     INFO -      #42 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5980:12 (libxul.so+0xb5af0a2) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.651Z] 14:28:47     INFO -      #43 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:6049:21 (libxul.so+0xb5af7e1) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.651Z] 14:28:47     INFO -      #44 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:49:12 (libxul.so+0xb5be2d2) (BuildId: cc1fde97ab2f50c22c2aadaf3b0c7b385f26b1f7)
[task 2024-06-11T14:28:47.651Z] 14:28:47     INFO -      #45 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:230:22 (firefox-bin+0x144731) (BuildId: 728f1e6e43acc93cfb4c8769cefc433984b34daa)
[task 2024-06-11T14:28:47.651Z] 14:28:47     INFO -      #46 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:448:16 (firefox-bin+0x144731)
[task 2024-06-11T14:28:47.651Z] 14:28:47     INFO -  SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.h:1262:29 in SetToEmptyBuffer
[task 2024-06-11T14:28:47.651Z] 14:28:47     INFO -  ==================
[task 2024-06-11T14:28:48.665Z] 14:28:48     INFO -  gtest INFO | gtest | process wait complete, returncode=-6
[task 2024-06-11T14:28:48.665Z] 14:28:48     INFO -  mozcrash checking /builds/worker/workspace/build/tests/gtest for minidumps...
[task 2024-06-11T14:28:48.667Z] 14:28:48  WARNING -  gtest TEST-UNEXPECTED-FAIL | gtest | test failed with return code -6
[task 2024-06-11T14:28:48.667Z] 14:28:48     INFO -  gtest INFO | rungtests.py exits with code 1
[task 2024-06-11T14:28:48.693Z] 14:28:48     INFO - Return code: 1
[task 2024-06-11T14:28:48.694Z] 14:28:48    ERROR - No tests run or test summary not found
[task 2024-06-11T14:28:48.695Z] 14:28:48     INFO - TinderboxPrint: gtest-gtest<br/><em class="testfail">T-FAIL</em>
[task 2024-06-11T14:28:48.695Z] 14:28:48  WARNING - setting return code to 2
[task 2024-06-11T14:28:48.697Z] 14:28:48     INFO - The gtest suite: gtest ran with return status: FAILURE
[task 2024-06-11T14:28:48.697Z] 14:28:48     INFO - Running post-action listener: _package_coverage_data
[task 2024-06-11T14:28:48.699Z] 14:28:48     INFO - Running post-action listener: _resource_record_post_action
[task 2024-06-11T14:28:48.699Z] 14:28:48     INFO - Running post-action listener: process_java_coverage_data
[task 2024-06-11T14:28:48.700Z] 14:28:48     INFO - [mozharness: 2024-06-11 14:28:48.694018Z] Finished run-tests step (success)
[task 2024-06-11T14:28:48.701Z] 14:28:48     INFO - [mozharness: 2024-06-11 14:28:48.694085Z] Running uninstall step.
[task 2024-06-11T14:28:48.701Z] 14:28:48     INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-06-11T14:28:48.702Z] 14:28:48     INFO - Running main action method: uninstall
[task 2024-06-11T14:28:48.702Z] 14:28:48     INFO - Skipping uninstall for non-MSIX test
[task 2024-06-11T14:28:48.702Z] 14:28:48     INFO - Running post-action listener: _resource_record_post_action
[task 2024-06-11T14:28:48.703Z] 14:28:48     INFO - [mozharness: 2024-06-11 14:28:48.694351Z] Finished uninstall step (success)
[task 2024-06-11T14:28:48.703Z] 14:28:48     INFO - Running post-run listener: _resource_record_post_run
[task 2024-06-11T14:28:49.339Z] 14:28:49     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2024-06-11T14:28:49.343Z] 14:28:49     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "gtest.gtest.overall", "extraOptions": ["taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 46.520042711104324}, {"name": "io_write_bytes", "value": 2430148608}, {"name": "io.read_bytes", "value": 27635712}, {"name": "io_write_time", "value": 2532416}, {"name": "io_read_time", "value": 4492}]}, {"name": "gtest.gtest.start-pulseaudio", "subtests": [{"name": "time", "value": 0.021189520999541855}, {"name": "cpu_percent", "value": 0}]}, {"name": "gtest.gtest.install", "subtests": [{"name": "time", "value": 43.00507359900075}, {"name": "cpu_percent", "value": 50.9955710955711}]}, {"name": "gtest.gtest.stage-files", "subtests": [{"name": "time", "value": 2.056761750000078}, {"name": "cpu_percent", "value": 16.94736842105263}]}, {"name": "gtest.gtest.run-tests", "subtests": [{"name": "time", "value": 1382.9279664349997}, {"name": "cpu_percent", "value": 46.42253271636122}]}, {"name": "gtest.gtest.uninstall", "subtests": [{"name": "time", "value": 0.00016645300001982832}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-06-11T14:28:49.345Z] 14:28:49     INFO - Total resource usage - Wall time: 1428s; CPU: Can't collect data; Read bytes: 27635712; Write bytes: 2430148608; Read time: 4492; Write time: 2532416
[task 2024-06-11T14:28:49.346Z] 14:28:49     INFO - TinderboxPrint: I/O read bytes / time<br/>27,635,712 / 4,492
[task 2024-06-11T14:28:49.347Z] 14:28:49     INFO - TinderboxPrint: I/O write bytes / time<br/>2,430,148,608 / 2,532,416
[task 2024-06-11T14:28:49.348Z] 14:28:49     INFO - TinderboxPrint: CPU idle<br/>1,492.8 (52.5%)
[task 2024-06-11T14:28:49.349Z] 14:28:49     INFO - TinderboxPrint: CPU system<br/>57.7 (2.0%)
[task 2024-06-11T14:28:49.350Z] 14:28:49     INFO - TinderboxPrint: CPU user<br/>1,267.0 (44.5%)
[task 2024-06-11T14:28:49.351Z] 14:28:49     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2024-06-11T14:28:49.352Z] 14:28:49     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-06-11T14:28:49.354Z] 14:28:49     INFO - install - Wall time: 43s; CPU: 51%; Read bytes: 0; Write bytes: 1028370432; Read time: 0; Write time: 1026616
[task 2024-06-11T14:28:49.357Z] 14:28:49     INFO - stage-files - Wall time: 2s; CPU: 17%; Read bytes: 0; Write bytes: 220250112; Read time: 0; Write time: 206196
[task 2024-06-11T14:28:49.419Z] 14:28:49     INFO - run-tests - Wall time: 1383s; CPU: 46%; Read bytes: 27635712; Write bytes: 1168945152; Read time: 4492; Write time: 1285560
[task 2024-06-11T14:28:49.429Z] 14:28:49     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-06-11T14:28:51.743Z] 14:28:51  WARNING - returning nonzero exit status 2
[task 2024-06-11T14:28:51.860Z] cleanup
[task 2024-06-11T14:28:51.860Z] + cleanup
[task 2024-06-11T14:28:51.860Z] + local rv=2
[task 2024-06-11T14:28:51.861Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2024-06-11T14:28:51.861Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2024-06-11T14:28:51.881Z] + '[' ']'
[task 2024-06-11T14:28:51.881Z] + true
[task 2024-06-11T14:28:51.881Z] + cleanup_xvfb
[task 2024-06-11T14:28:51.882Z] ++ pidof Xvfb
[task 2024-06-11T14:28:51.887Z] + local xvfb_pid=48
[task 2024-06-11T14:28:51.888Z] + local vnc=false
[task 2024-06-11T14:28:51.888Z] + local interactive=false
[task 2024-06-11T14:28:51.888Z] + '[' -n 48 ']'
[task 2024-06-11T14:28:51.888Z] + [[ false == false ]]
[task 2024-06-11T14:28:51.888Z] + [[ false == false ]]
[task 2024-06-11T14:28:51.888Z] + kill 48
[task 2024-06-11T14:28:51.888Z] + screen -XS xvfb quit
[task 2024-06-11T14:28:52.265Z] No screen session found.
[task 2024-06-11T14:28:52.265Z] + true
[task 2024-06-11T14:28:52.265Z] + exit 2
[taskcluster 2024-06-11 14:28:52.795Z] === Task Finished ===
[taskcluster 2024-06-11 14:28:54.113Z] Unsuccessful task run with exit code: 2 completed in 1514.637 seconds

Comment 1

[Andrew McCreight [:mccr8]]

This looks like a race in a PACMan gtest, so it can probably be unhidden, but it would be good for somebody familiar with this code to take a look first.

Comment 2

[Valentin Gosu [:valentin] (he/him)]

This doesn't need to be hidden.
We race on WPADOptionResult which is set by the test but also used on the PAC thread.
Considering this is just a test race, adding a mutex is the easy way forward.

Comment 3

[Sean Kim [:sekim]]

Attachment: Bug 1901791 - Guard WPADOptionResult with a static mutex to prevent data race r?kershaw

Comment 4

[Intermittent Failures Robot]

2 failures in 4625 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1901791&startday=2024-06-10&endday=2024-06-16&tree=all

Comment 5

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/623684709fe4
Guard WPADOptionResult with a static mutex to prevent data race r=kershaw,necko-reviewers

Comment 6

[Sandor Molnar[:smolnar]]

Backed out for causing build bustages @ TestPACMan.cpp

Backout link: https://hg.mozilla.org/integration/autoland/rev/a3a5216fe84812acff36d4970cd4244cd4a27085

Push with failures

Failure log -> ERROR - /builds/worker/checkouts/gecko/netwerk/test/gtest/TestPACMan.cpp:22:8: error: unknown type name 'StaticMutex'; did you mean 'mozilla::StaticMutex'?

Comment 7

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ad07f490b1a3
Guard WPADOptionResult with a static mutex to prevent data race r=kershaw,necko-reviewers

Comment 8

[Serban Stanca [:SerbanS]]

https://hg.mozilla.org/mozilla-central/rev/ad07f490b1a3

Comment 9

[Intermittent Failures Robot]

1 failures in 4577 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1901791&startday=2024-06-17&endday=2024-06-23&tree=all