Closed Bug 1680389 Opened 4 years ago Closed 4 years ago

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:223:28 in nsDebugImpl::SetMultiprocessMode(char const*)

Categories

(Core :: DOM: Content Processes, defect, P2)

Product:
Core
Component:
DOM: Content Processes
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
85 Branch
Tracking Flags:
Tracking Status
firefox85 --- fixed

People

(Reporter: bogdan_tara, Assigned: mccr8)

References

(Blocks 1 open bug)

Details

(Keywords: csectype-race, intermittent-failure)

Attachments

(1 file)

Bug 1680389 - Only call SetMultiprocessMode once in the main process.
(deleted), text/x-phabricator-request
Details

https://treeherder.mozilla.org/logviewer?job_id=323365737&repo=autoland&lineNumber=2337

https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/F1jb5dzZQO-CwDhd4Qtopw/runs/0/artifacts/public/logs/live_backing.log

[task 2020-12-02T23:09:23.442Z] 23:09:23     INFO - TEST-START | browser/base/content/test/webrtc/browser_WebrtcGlobalInformation.js
[task 2020-12-02T23:09:23.679Z] 23:09:23     INFO - GECKO(1544) | [Parent 1544: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:325: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2020-12-02T23:09:23.680Z] 23:09:23     INFO - GECKO(1544) | (unknown/INFO) insert '' (registry) succeeded:
[task 2020-12-02T23:09:23.680Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) Initialized registry
[task 2020-12-02T23:09:23.828Z] 23:09:23     INFO - GECKO(1544) | [Parent 1544: Unnamed thread 0x7b440028ff00]: E/signaling [|WebrtcAudioSessionConduit] AudioConduit.cpp:927: SendRtcp RTCP Packet Send Failed
[task 2020-12-02T23:09:23.828Z] 23:09:23     INFO - GECKO(1544) | [Parent 1544: Unnamed thread 0x7b440028ff00]: E/signaling [|WebrtcAudioSessionConduit] AudioConduit.cpp:927: SendRtcp RTCP Packet Send Failed
[task 2020-12-02T23:09:23.970Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice' (registry) succeeded: ice
[task 2020-12-02T23:09:23.971Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref' (registry) succeeded: ice.pref
[task 2020-12-02T23:09:23.972Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type' (registry) succeeded: ice.pref.type
[task 2020-12-02T23:09:23.972Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type.srv_rflx' (UCHAR) succeeded: 0x64
[task 2020-12-02T23:09:23.973Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type.peer_rflx' (UCHAR) succeeded: 0x6e
[task 2020-12-02T23:09:23.974Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type.host' (UCHAR) succeeded: 0x7e
[task 2020-12-02T23:09:23.974Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type.relayed' (UCHAR) succeeded: 0x05
[task 2020-12-02T23:09:23.975Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type.srv_rflx_tcp' (UCHAR) succeeded: 0x63
[task 2020-12-02T23:09:23.975Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type.peer_rflx_tcp' (UCHAR) succeeded: 0x6d
[task 2020-12-02T23:09:23.977Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type.host_tcp' (UCHAR) succeeded: 0x7d
[task 2020-12-02T23:09:23.977Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.pref.type.relayed_tcp' (UCHAR) succeeded: 0x00
[task 2020-12-02T23:09:23.978Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'stun' (registry) succeeded: stun
[task 2020-12-02T23:09:23.978Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'stun.client' (registry) succeeded: stun.client
[task 2020-12-02T23:09:23.979Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'stun.client.maximum_transmits' (UINT4) succeeded: 7
[task 2020-12-02T23:09:23.979Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.trickle_grace_period' (UINT4) succeeded: 5000
[task 2020-12-02T23:09:23.980Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.tcp' (registry) succeeded: ice.tcp
[task 2020-12-02T23:09:23.981Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.tcp.so_sock_count' (INT4) succeeded: 0
[task 2020-12-02T23:09:23.982Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.tcp.listen_backlog' (INT4) succeeded: 10
[task 2020-12-02T23:09:23.982Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.tcp.disable' (char) succeeded: \000
[task 2020-12-02T23:09:23.982Z] 23:09:23     INFO - GECKO(1544) | (registry/INFO) insert 'ice.forced_interface_name' (string) succeeded:
[task 2020-12-02T23:09:24.009Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 0x7b1c001949b0
[task 2020-12-02T23:09:24.010Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Main Thread]: I/jsep [{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)]: stable -> have-local-offer
[task 2020-12-02T23:09:24.016Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:519: GetChannel: Document does not have an HTTP channel
[task 2020-12-02T23:09:24.020Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:0 1 UDP 2122187007 172.17.0.4 34203 typ host
[task 2020-12-02T23:09:24.021Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml) transport-id=transport_0', no candidates.
[task 2020-12-02T23:09:24.022Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:1 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 50880 typ host
[task 2020-12-02T23:09:24.023Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml) transport-id=transport_0', no candidates.
[task 2020-12-02T23:09:24.025Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:2 1 TCP 2105442559 172.17.0.4 59632 typ host tcptype passive
[task 2020-12-02T23:09:24.025Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml) transport-id=transport_0', no candidates.
[task 2020-12-02T23:09:24.029Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:2 1 TCP 2105458943 172.17.0.4 9 typ host tcptype active
[task 2020-12-02T23:09:24.029Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml) transport-id=transport_0', no candidates.
[task 2020-12-02T23:09:24.029Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:3 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:4 64138 typ host tcptype passive
[task 2020-12-02T23:09:24.030Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml) transport-id=transport_0', no candidates.
[task 2020-12-02T23:09:24.030Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:3 1 TCP 2105524479 fd15:4ba5:5a2b:100a:0:242:ac11:4 9 typ host tcptype active
[task 2020-12-02T23:09:24.031Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml) transport-id=transport_0', no candidates.
[task 2020-12-02T23:09:24.033Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:0 2 UDP 2122187006 172.17.0.4 35634 typ host
[task 2020-12-02T23:09:24.041Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:1 2 UDP 2122252542 fd15:4ba5:5a2b:100a:0:242:ac11:4 51556 typ host
[task 2020-12-02T23:09:24.042Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:2 2 TCP 2105442558 172.17.0.4 57198 typ host tcptype passive
[task 2020-12-02T23:09:24.042Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:2 2 TCP 2105458942 172.17.0.4 9 typ host tcptype active
[task 2020-12-02T23:09:24.043Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:3 2 TCP 2105508094 fd15:4ba5:5a2b:100a:0:242:ac11:4 62346 typ host tcptype passive
[task 2020-12-02T23:09:24.043Z] 23:09:24     INFO - GECKO(1544) | [Parent 1544: Socket Thread]: D/mtransport NrIceCtx(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): trickling candidate candidate:3 2 TCP 2105524478 fd15:4ba5:5a2b:100a:0:242:ac11:4 9 typ host tcptype active
[task 2020-12-02T23:09:24.044Z] 23:09:24     INFO - GECKO(1544) | (ice/INFO) ICE(PC:{4c5b7d8b-d023-4032-8898-f322a10f3c21} 1606950563674436 (id=2 url=chrome://browser/content/browser.xhtml)): All candidates initialized
[task 2020-12-02T23:09:24.108Z] 23:09:24     INFO - GECKO(1544) | ==================
[task 2020-12-02T23:09:24.109Z] 23:09:24     INFO - GECKO(1544) | WARNING: ThreadSanitizer: data race (pid=1544)
[task 2020-12-02T23:09:24.111Z] 23:09:24     INFO - GECKO(1544) |   Write of size 8 at 0x7f81821a52a0 by main thread:
[task 2020-12-02T23:09:24.111Z] 23:09:24     INFO - GECKO(1544) |     #0 nsDebugImpl::SetMultiprocessMode(char const*) /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:223:28 (libxul.so+0xa5f919)
[task 2020-12-02T23:09:24.113Z] 23:09:24     INFO - GECKO(1544) |     #1 mozilla::dom::ContentParent::ContentParent(nsTSubstring<char> const&, int) /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:2581:3 (libxul.so+0x45e1d45)
[task 2020-12-02T23:09:24.115Z] 23:09:24     INFO - GECKO(1544) |     #2 ContentParent /builds/worker/checkouts/gecko/dom/ipc/ContentParent.h:775:9 (libxul.so+0x45d49d4)
[task 2020-12-02T23:09:24.115Z] 23:09:24     INFO - GECKO(1544) |     #3 mozilla::dom::ContentParent::PreallocateProcess() /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:649:39 (libxul.so+0x45d49d4)
[task 2020-12-02T23:09:24.116Z] 23:09:24     INFO - GECKO(1544) |     #4 mozilla::PreallocatedProcessManagerImpl::AllocateNow() /builds/worker/checkouts/gecko/dom/ipc/PreallocatedProcessManager.cpp:302:3 (libxul.so+0x460d544)
[task 2020-12-02T23:09:24.116Z] 23:09:24     INFO - GECKO(1544) |     #5 applyImpl<mozilla::PreallocatedProcessManagerImpl, void (mozilla::PreallocatedProcessManagerImpl::*)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1096:12 (libxul.so+0x461e736)
[task 2020-12-02T23:09:24.117Z] 23:09:24     INFO - GECKO(1544) |     #6 apply<mozilla::PreallocatedProcessManagerImpl, void (mozilla::PreallocatedProcessManagerImpl::*)()> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1102:12 (libxul.so+0x461e736)
[task 2020-12-02T23:09:24.118Z] 23:09:24     INFO - GECKO(1544) |     #7 mozilla::detail::RunnableMethodImpl<mozilla::PreallocatedProcessManagerImpl*, void (mozilla::PreallocatedProcessManagerImpl::*)(), true, (mozilla::RunnableKind)0>::Run() /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:1148:13 (libxul.so+0x461e736)
[task 2020-12-02T23:09:24.118Z] 23:09:24     INFO - GECKO(1544) |     #8 mozilla::RunnableTask::Run() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:452:16 (libxul.so+0xb5f042)
[task 2020-12-02T23:09:24.120Z] 23:09:24     INFO - GECKO(1544) |     #9 mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:722:26 (libxul.so+0xb5d000)
[task 2020-12-02T23:09:24.120Z] 23:09:24     INFO - GECKO(1544) |     #10 mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:604:15 (libxul.so+0xb5bd99)
[task 2020-12-02T23:09:24.121Z] 23:09:24     INFO - GECKO(1544) |     #11 mozilla::TaskController::ProcessPendingMTTask(bool) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:375:36 (libxul.so+0xb5bf04)
[task 2020-12-02T23:09:24.124Z] 23:09:24     INFO - GECKO(1544) |     #12 operator() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:122:37 (libxul.so+0xb62067)
[task 2020-12-02T23:09:24.124Z] 23:09:24     INFO - GECKO(1544) |     #13 mozilla::detail::RunnableFunction<mozilla::TaskController::InitializeInternal()::$_3>::Run() /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:485:5 (libxul.so+0xb62067)
[task 2020-12-02T23:09:24.125Z] 23:09:24     INFO - GECKO(1544) |     #14 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1196:14 (libxul.so+0xb7310b)
[task 2020-12-02T23:09:24.125Z] 23:09:24     INFO - GECKO(1544) |     #15 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:513:10 (libxul.so+0xb78e32)
[task 2020-12-02T23:09:24.126Z] 23:09:24     INFO - GECKO(1544) |     #16 mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:87:21 (libxul.so+0x1469ecd)
[task 2020-12-02T23:09:24.127Z] 23:09:24     INFO - GECKO(1544) |     #17 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:334:10 (libxul.so+0x13f616c)
[task 2020-12-02T23:09:24.127Z] 23:09:24     INFO - GECKO(1544) |     #18 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:327:3 (libxul.so+0x13f616c)
[task 2020-12-02T23:09:24.128Z] 23:09:24     INFO - GECKO(1544) |     #19 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:309:3 (libxul.so+0x13f616c)
[task 2020-12-02T23:09:24.128Z] 23:09:24     INFO - GECKO(1544) |     #20 nsBaseAppShell::Run() /builds/worker/checkouts/gecko/widget/nsBaseAppShell.cpp:137:27 (libxul.so+0x4a7f176)
[task 2020-12-02T23:09:24.129Z] 23:09:24     INFO - GECKO(1544) |     #21 nsAppStartup::Run() /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:271:30 (libxul.so+0x65bce40)
[task 2020-12-02T23:09:24.129Z] 23:09:24     INFO - GECKO(1544) |     #22 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5109:22 (libxul.so+0x66d46c5)
[task 2020-12-02T23:09:24.130Z] 23:09:24     INFO - GECKO(1544) |     #23 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5301:8 (libxul.so+0x66d5497)
[task 2020-12-02T23:09:24.131Z] 23:09:24     INFO - GECKO(1544) |     #24 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5357:21 (libxul.so+0x66d5814)
[task 2020-12-02T23:09:24.132Z] 23:09:24     INFO - GECKO(1544) |     #25 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x66dfd52)
[task 2020-12-02T23:09:24.132Z] 23:09:24     INFO - GECKO(1544) |     #26 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:219:22 (firefox+0xc7c80)
[task 2020-12-02T23:09:24.133Z] 23:09:24     INFO - GECKO(1544) |     #27 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:337:16 (firefox+0xc7c80)
[task 2020-12-02T23:09:24.134Z] 23:09:24     INFO - GECKO(1544) |   Previous read of size 8 at 0x7f81821a52a0 by thread T6:
[task 2020-12-02T23:09:24.134Z] 23:09:24     INFO - GECKO(1544) |     #0 nsDebugImpl::GetMultiprocessMode() /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:227:10 (libxul.so+0xa5f946)
[task 2020-12-02T23:09:24.135Z] 23:09:24     INFO - GECKO(1544) |     #1 mozilla::LogModuleManager::Print(char const*, mozilla::LogLevel, mozilla::TimeStamp const*, char const*, __va_list_tag*) /builds/worker/checkouts/gecko/xpcom/base/Logging.cpp:487:24 (libxul.so+0xa7c9cc)
[task 2020-12-02T23:09:24.135Z] 23:09:24     INFO - GECKO(1544) |     #2 Print /builds/worker/checkouts/gecko/xpcom/base/Logging.cpp:384:5 (libxul.so+0xa7b7fa)
[task 2020-12-02T23:09:24.136Z] 23:09:24     INFO - GECKO(1544) |     #3 Printv /builds/worker/checkouts/gecko/xpcom/base/Logging.cpp:671:22 (libxul.so+0xa7b7fa)
[task 2020-12-02T23:09:24.136Z] 23:09:24     INFO - GECKO(1544) |     #4 mozilla::detail::log_print(mozilla::LogModule const*, mozilla::LogLevel, char const*, ...) /builds/worker/checkouts/gecko/xpcom/base/Logging.cpp:55:12 (libxul.so+0xa7b7fa)
[task 2020-12-02T23:09:24.138Z] 23:09:24     INFO - GECKO(1544) |     #5 mozilla::NrIceCtx::trickle_cb(void*, nr_ice_ctx_*, nr_ice_media_stream_*, int, nr_ice_candidate_*) /builds/worker/checkouts/gecko/dom/media/webrtc/transport/nricectx.cpp:453:3 (libxul.so+0x1ad0a8d)
[task 2020-12-02T23:09:24.138Z] 23:09:24     INFO - GECKO(1544) |     #6 nr_ice_gather_finished_cb /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/ice/ice_ctx.c:528:9 (libxul.so+0x6757764)
[task 2020-12-02T23:09:24.139Z] 23:09:24     INFO - GECKO(1544) |     #7 nr_ice_candidate_fire_ready_cb /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/ice/ice_candidate.c:579:5 (libxul.so+0x6751807)
[task 2020-12-02T23:09:24.140Z] 23:09:24     INFO - GECKO(1544) |     #8 mozilla::nrappkitScheduledCallback::Run() /builds/worker/checkouts/gecko/dom/media/webrtc/transport/nr_timer.cpp:142:7 (libxul.so+0x1acca59)
[task 2020-12-02T23:09:24.140Z] 23:09:24     INFO - GECKO(1544) |     #9 __invoke_impl<void, void (mozilla::nrappkitScheduledCallback::*const &)(), mozilla::UniquePtr<mozilla::nrappkitScheduledCallback, mozilla::DefaultDelete<mozilla::nrappkitScheduledCallback> > &> /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/bits/invoke.h:73:14 (libxul.so+0x1accf11)
[task 2020-12-02T23:09:24.141Z] 23:09:24     INFO - GECKO(1544) |     #10 __invoke<void (mozilla::nrappkitScheduledCallback::*const &)(), mozilla::UniquePtr<mozilla::nrappkitScheduledCallback, mozilla::DefaultDelete<mozilla::nrappkitScheduledCallback> > &> /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/bits/invoke.h:95:14 (libxul.so+0x1accf11)
[task 2020-12-02T23:09:24.141Z] 23:09:24     INFO - GECKO(1544) |     #11 operator()<mozilla::UniquePtr<mozilla::nrappkitScheduledCallback, mozilla::DefaultDelete<mozilla::nrappkitScheduledCallback> > &> /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/functional:175:11 (libxul.so+0x1accf11)
[task 2020-12-02T23:09:24.142Z] 23:09:24     INFO - GECKO(1544) |     #12 __invoke_impl<void, std::_Mem_fn<void (mozilla::nrappkitScheduledCallback::*)()>, mozilla::UniquePtr<mozilla::nrappkitScheduledCallback, mozilla::DefaultDelete<mozilla::nrappkitScheduledCallback> > &> /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/bits/invoke.h:60:14 (libxul.so+0x1accf11)
[task 2020-12-02T23:09:24.142Z] 23:09:24     INFO - GECKO(1544) |     #13 __invoke<std::_Mem_fn<void (mozilla::nrappkitScheduledCallback::*)()>, mozilla::UniquePtr<mozilla::nrappkitScheduledCallback, mozilla::DefaultDelete<mozilla::nrappkitScheduledCallback> > &> /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/bits/invoke.h:95:14 (libxul.so+0x1accf11)
[task 2020-12-02T23:09:24.143Z] 23:09:24     INFO - GECKO(1544) |     #14 __apply_impl<std::_Mem_fn<void (mozilla::nrappkitScheduledCallback::*)()>, std::tuple<mozilla::UniquePtr<mozilla::nrappkitScheduledCallback, mozilla::DefaultDelete<mozilla::nrappkitScheduledCallback> > &>, 0> /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/tuple:1662:14 (libxul.so+0x1accf11)
[task 2020-12-02T23:09:24.144Z] 23:09:24     INFO - GECKO(1544) |     #15 apply<std::_Mem_fn<void (mozilla::nrappkitScheduledCallback::*)()>, std::tuple<mozilla::UniquePtr<mozilla::nrappkitScheduledCallback, mozilla::DefaultDelete<mozilla::nrappkitScheduledCallback> > &> > /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/tuple:1671:14 (libxul.so+0x1accf11)
[task 2020-12-02T23:09:24.145Z] 23:09:24     INFO - GECKO(1544) |     #16 mozilla::runnable_args_memfn<mozilla::UniquePtr<mozilla::nrappkitScheduledCallback, mozilla::DefaultDelete<mozilla::nrappkitScheduledCallback> >, void (mozilla::nrappkitScheduledCallback::*)()>::RunInternal() /builds/worker/checkouts/gecko/dom/media/webrtc/transport/runnable_utils.h:121:5 (libxul.so+0x1accf11)
[task 2020-12-02T23:09:24.146Z] 23:09:24     INFO - GECKO(1544) |     #17 mozilla::detail::runnable_args_base<(mozilla::detail::RunnableResult)0>::Run() /builds/worker/checkouts/gecko/dom/media/webrtc/transport/runnable_utils.h:41:5 (libxul.so+0x13dedfe)
[task 2020-12-02T23:09:24.146Z] 23:09:24     INFO - GECKO(1544) |     #18 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1196:14 (libxul.so+0xb7330d)
[task 2020-12-02T23:09:24.148Z] 23:09:24     INFO - GECKO(1544) |     #19 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:513:10 (libxul.so+0xb78e32)
[task 2020-12-02T23:09:24.148Z] 23:09:24     INFO - GECKO(1544) |     #20 mozilla::net::nsSocketTransportService::Run() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp:1202:11 (libxul.so+0xd198e7)
[task 2020-12-02T23:09:24.150Z] 23:09:24     INFO - GECKO(1544) |     #21 non-virtual thunk to mozilla::net::nsSocketTransportService::Run() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp (libxul.so+0xd1ada9)
[task 2020-12-02T23:09:24.151Z] 23:09:24     INFO - GECKO(1544) |     #22 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1196:14 (libxul.so+0xb7330d)
[task 2020-12-02T23:09:24.151Z] 23:09:24     INFO - GECKO(1544) |     #23 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:513:10 (libxul.so+0xb78e32)
[task 2020-12-02T23:09:24.152Z] 23:09:24     INFO - GECKO(1544) |     #24 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:302:20 (libxul.so+0x146a99e)
[task 2020-12-02T23:09:24.152Z] 23:09:24     INFO - GECKO(1544) |     #25 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:334:10 (libxul.so+0x13f616c)
[task 2020-12-02T23:09:24.153Z] 23:09:24     INFO - GECKO(1544) |     #26 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:327:3 (libxul.so+0x13f616c)
[task 2020-12-02T23:09:24.153Z] 23:09:24     INFO - GECKO(1544) |     #27 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:309:3 (libxul.so+0x13f616c)
[task 2020-12-02T23:09:24.155Z] 23:09:24     INFO - GECKO(1544) |     #28 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:441:10 (libxul.so+0xb6f771)
[task 2020-12-02T23:09:24.166Z] 23:09:24     INFO - GECKO(1544) |     #29 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x4524b)
[task 2020-12-02T23:09:24.168Z] 23:09:24     INFO - GECKO(1544) |   Location is global 'sMultiprocessDescription' of size 8 at 0x7f81821a52a0 (libxul.so+0x00000b6c72a0)
[task 2020-12-02T23:09:24.170Z] 23:09:24     INFO - GECKO(1544) |   Thread T6 'Socket Thread' (tid=1556, running) created by main thread at:
[task 2020-12-02T23:09:24.170Z] 23:09:24     INFO - GECKO(1544) |     #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:966:3 (firefox+0x5669b)
[task 2020-12-02T23:09:24.170Z] 23:09:24     INFO - GECKO(1544) |     #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3c3f3)
[task 2020-12-02T23:09:24.170Z] 23:09:24     INFO - GECKO(1544) |     #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x30d15)
[task 2020-12-02T23:09:24.171Z] 23:09:24     INFO - GECKO(1544) |     #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:658:8 (libxul.so+0xb70c9f)
[task 2020-12-02T23:09:24.171Z] 23:09:24     INFO - GECKO(1544) |     #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, unsigned int, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:641:12 (libxul.so+0xb7822a)
[task 2020-12-02T23:09:24.171Z] 23:09:24     INFO - GECKO(1544) |     #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:161:57 (libxul.so+0xb7ec37)
[task 2020-12-02T23:09:24.171Z] 23:09:24     INFO - GECKO(1544) |     #6 NS_NewNamedThread<14> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:84:10 (libxul.so+0xd17fff)
[task 2020-12-02T23:09:24.171Z] 23:09:24     INFO - GECKO(1544) |     #7 mozilla::net::nsSocketTransportService::Init() /builds/worker/checkouts/gecko/netwerk/base/nsSocketTransportService2.cpp:775:7 (libxul.so+0xd17fff)
[task 2020-12-02T23:09:24.171Z] 23:09:24     INFO - GECKO(1544) |     #8 mozilla::xpcom::CreateInstanceImpl(mozilla::xpcom::ModuleID, nsISupports*, nsID const&, void**) /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:11849:7 (libxul.so+0xb3d108)
[task 2020-12-02T23:09:24.172Z] 23:09:24     INFO - GECKO(1544) |     #9 mozilla::xpcom::StaticModule::CreateInstance(nsISupports*, nsID const&, void**) const /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:12235:10 (libxul.so+0xb35333)
[task 2020-12-02T23:09:24.173Z] 23:09:24     INFO - GECKO(1544) |     #10 CreateInstance /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:176:46 (libxul.so+0xb4c12e)
[task 2020-12-02T23:09:24.173Z] 23:09:24     INFO - GECKO(1544) |     #11 nsComponentManagerImpl::GetServiceLocked(mozilla::Maybe<mozilla::MonitorAutoLock>&, (anonymous namespace)::EntryWrapper&, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1282:17 (libxul.so+0xb4c12e)
[task 2020-12-02T23:09:24.173Z] 23:09:24     INFO - GECKO(1544) |     #12 nsComponentManagerImpl::GetServiceByContractID(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1471:10 (libxul.so+0xb4cdb7)
[task 2020-12-02T23:09:24.173Z] 23:09:24     INFO - GECKO(1544) |     #13 CallGetService /builds/worker/checkouts/gecko/xpcom/components/nsComponentManagerUtils.cpp:61:43 (libxul.so+0xb4fa1d)
[task 2020-12-02T23:09:24.174Z] 23:09:24     INFO - GECKO(1544) |     #14 nsGetServiceByContractIDWithError::operator()(nsID const&, void**) const /builds/worker/checkouts/gecko/xpcom/components/nsComponentManagerUtils.cpp:253:21 (libxul.so+0xb4fa1d)
[task 2020-12-02T23:09:24.178Z] 23:09:24     INFO - GECKO(1544) |     #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+0xa92ba7)
[task 2020-12-02T23:09:24.178Z] 23:09:24     INFO - GECKO(1544) |     #16 operator= /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:774:5 (libxul.so+0xcbed51)
[task 2020-12-02T23:09:24.178Z] 23:09:24     INFO - GECKO(1544) |     #17 InitializeSocketTransportService /builds/worker/checkouts/gecko/netwerk/base/nsIOService.cpp:436:29 (libxul.so+0xcbed51)
[task 2020-12-02T23:09:24.178Z] 23:09:24     INFO - GECKO(1544) |     #18 mozilla::net::nsIOService::SetOffline(bool) /builds/worker/checkouts/gecko/netwerk/base/nsIOService.cpp:1303:7 (libxul.so+0xcbed51)
[task 2020-12-02T23:09:24.178Z] 23:09:24     INFO - GECKO(1544) |     #19 mozilla::net::nsIOService::Init() /builds/worker/checkouts/gecko/netwerk/base/nsIOService.cpp:319:3 (libxul.so+0xcbe000)
[task 2020-12-02T23:09:24.179Z] 23:09:24     INFO - GECKO(1544) |     #20 mozilla::net::nsIOService::GetInstance() /builds/worker/checkouts/gecko/netwerk/base/nsIOService.cpp:493:9 (libxul.so+0xcbfb11)
[task 2020-12-02T23:09:24.179Z] 23:09:24     INFO - GECKO(1544) |     #21 mozilla::xpcom::CreateInstanceImpl(mozilla::xpcom::ModuleID, nsISupports*, nsID const&, void**) /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:9477:48 (libxul.so+0xb3e6ac)
[task 2020-12-02T23:09:24.179Z] 23:09:24     INFO - GECKO(1544) |     #22 mozilla::xpcom::StaticModule::CreateInstance(nsISupports*, nsID const&, void**) const /builds/worker/workspace/obj-build/xpcom/components/StaticComponents.cpp:12235:10 (libxul.so+0xb35333)
[task 2020-12-02T23:09:24.179Z] 23:09:24     INFO - GECKO(1544) |     #23 CreateInstance /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:176:46 (libxul.so+0xb4c12e)
[task 2020-12-02T23:09:24.179Z] 23:09:24     INFO - GECKO(1544) |     #24 nsComponentManagerImpl::GetServiceLocked(mozilla::Maybe<mozilla::MonitorAutoLock>&, (anonymous namespace)::EntryWrapper&, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1282:17 (libxul.so+0xb4c12e)
[task 2020-12-02T23:09:24.179Z] 23:09:24     INFO - GECKO(1544) |     #25 nsComponentManagerImpl::GetServiceByContractID(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:1471:10 (libxul.so+0xb4cdb7)
[task 2020-12-02T23:09:24.180Z] 23:09:24     INFO - GECKO(1544) |     #26 CallGetService(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/components/nsComponentManagerUtils.cpp:61:43 (libxul.so+0xb4f52d)
[task 2020-12-02T23:09:24.180Z] 23:09:24     INFO - GECKO(1544) |     #27 CallGetService<nsIIOService> /builds/worker/workspace/obj-build/dist/include/nsServiceManagerUtils.h:52:10 (libxul.so+0x1b6ff63)
[task 2020-12-02T23:09:24.180Z] 23:09:24     INFO - GECKO(1544) |     #28 nsScriptSecurityManager::Init() /builds/worker/checkouts/gecko/caps/nsScriptSecurityManager.cpp:1378:17 (libxul.so+0x1b6ff63)
[task 2020-12-02T23:09:24.180Z] 23:09:24     INFO - GECKO(1544) |     #29 nsScriptSecurityManager::InitStatics() /builds/worker/checkouts/gecko/caps/nsScriptSecurityManager.cpp:1439:28 (libxul.so+0x1b70551)
[task 2020-12-02T23:09:24.180Z] 23:09:24     INFO - GECKO(1544) |     #30 nsXPConnect::InitStatics() /builds/worker/checkouts/gecko/js/xpconnect/src/nsXPConnect.cpp:153:3 (libxul.so+0x1a46083)
[task 2020-12-02T23:09:24.181Z] 23:09:24     INFO - GECKO(1544) |     #31 xpcModuleCtor() /builds/worker/checkouts/gecko/js/xpconnect/src/XPCModule.cpp:11:3 (libxul.so+0x1a1a0d2)
[task 2020-12-02T23:09:24.181Z] 23:09:24     INFO - GECKO(1544) |     #32 nsLayoutModuleInitialize() /builds/worker/checkouts/gecko/layout/build/nsLayoutModule.cpp:108:7 (libxul.so+0x5269d77)
[task 2020-12-02T23:09:24.181Z] 23:09:24     INFO - GECKO(1544) |     #33 nsComponentManagerImpl::Init() /builds/worker/checkouts/gecko/xpcom/components/nsComponentManager.cpp:408:5 (libxul.so+0xb47bf1)
[task 2020-12-02T23:09:24.181Z] 23:09:24     INFO - GECKO(1544) |     #34 NS_InitXPCOM /builds/worker/checkouts/gecko/xpcom/build/XPCOMInit.cpp:444:51 (libxul.so+0xbaaeba)
[task 2020-12-02T23:09:24.181Z] 23:09:24     INFO - GECKO(1544) |     #35 ScopedXPCOMStartup::Initialize(bool) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:1683:8 (libxul.so+0x66cb8dd)
[task 2020-12-02T23:09:24.181Z] 23:09:24     INFO - GECKO(1544) |     #36 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5297:22 (libxul.so+0x66d5487)
[task 2020-12-02T23:09:24.182Z] 23:09:24     INFO - GECKO(1544) |     #37 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5357:21 (libxul.so+0x66d5814)
[task 2020-12-02T23:09:24.182Z] 23:09:24     INFO - GECKO(1544) |     #38 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x66dfd52)
[task 2020-12-02T23:09:24.182Z] 23:09:24     INFO - GECKO(1544) |     #39 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:219:22 (firefox+0xc7c80)
[task 2020-12-02T23:09:24.184Z] 23:09:24     INFO - GECKO(1544) |     #40 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:337:16 (firefox+0xc7c80)
[task 2020-12-02T23:09:24.184Z] 23:09:24     INFO - GECKO(1544) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:223:28 in nsDebugImpl::SetMultiprocessMode(char const*)
[task 2020-12-02T23:09:24.184Z] 23:09:24     INFO - GECKO(1544) | ==================
[task 2020-12-02T23:09:24.185Z] 23:09:24     INFO - GECKO(1544) | Exiting due to channel error.
[task 2020-12-02T23:09:24.185Z] 23:09:24     INFO - GECKO(1544) | Exiting due to channel error.
[task 2020-12-02T23:09:24.185Z] 23:09:24     INFO - GECKO(1544) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=3.59866) Exiting due to channel error.
[task 2020-12-02T23:09:24.185Z] 23:09:24     INFO - GECKO(1544) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=14.8006) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=13.986) Exiting due to channel error.
[task 2020-12-02T23:09:24.187Z] 23:09:24     INFO - GECKO(1544) | ###!!! [Child][MessageChannel] Error: (msgtype=0xAD0002,name=PVsync::Msg_Observe) Channel error: cannot send/recv
[task 2020-12-02T23:09:25.234Z] 23:09:25     INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2020-12-02T23:09:25.234Z] 23:09:25     INFO - Buffered messages logged at 23:09:23
[task 2020-12-02T23:09:25.234Z] 23:09:25     INFO - Entering test bound 
[task 2020-12-02T23:09:25.234Z] 23:09:25     INFO - Test that clearAllStats is callable
[task 2020-12-02T23:09:25.235Z] 23:09:25     INFO - TEST-PASS | browser/base/content/test/webrtc/browser_WebrtcGlobalInformation.js | clearAllStats returns - 
[task 2020-12-02T23:09:25.235Z] 23:09:25     INFO - Leaving test bound

Some kind of race getting and setting the process type, with WebRTC.

Group: core-security → dom-core-security
Component: XPCOM → DOM: Content Processes

Jesup, maybe you could take a look? As this involves the preallocated process and WebRTC I can think of no person more qualified to look at it. ;)

Blocks: tsan
Flags: needinfo?(rjesup)
Keywords: csectype-race

I took a bit of a look at this. Fundamentally it seems goofy to me that we're calling nsDebugImpl::SetMultiprocessMode("Parent"); in ContentParent. That means that we re-set the multiprocess mode every time we create a new content parent in the parent process. I think it should be moved to some other point in process startup.

Assignee: nobody → continuation
Flags: needinfo?(rjesup)

A parent process race on writing a string used for logging, and where the old and new values are the same, doesn't seem very exploitable to me, so I'm just going to unhide it.

Group: dom-core-security

This behavior was added in bug 724072. A comment in there says: "We could unconditionally display the pid, but then that's added noise for the majority of people, who aren't developing for e10s."

So, by only calling this function in the ContentParent ctor, it would only enable the pid logging in the parent process when e10s was enabled. Needless to say, this is no longer something we need to worry about. I guess NS_DebugBreak could be changed to unconditionally print out the pid or something but whatever.

This was being called every time we created a ContentParent, which meant
that we'd get races with non-main-thread logging in the parent
process. This patch fixes it by only calling it once during process
startup.

The original goal was to avoid making logging noisier with process
information when e10s was not enabled, but given that e10s is the default
now, that is no longer relevant.

P2/S3

Severity: -- → S3
Priority: -- → P2
Summary: Intermittent ThreadSanitizer: data race /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:223:28 in nsDebugImpl::SetMultiprocessMode(char const*) → Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:223:28 in nsDebugImpl::SetMultiprocessMode(char const*)
Pushed by amccreight@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/b137de789ce0 Only call SetMultiprocessMode once in the main process. r=pbone

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

Status: NEW → RESOLVED
Closed: 4 years ago
status-firefox85: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → 85 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: