Closed Bug 1636095 Opened 5 years ago Closed 5 years ago

Intermittent SUMMARY: ThreadSanitizer: data race /usr/include/x86_64-linux-gnu/sys/stat.h:504:10 in stat64

Categories

(Core :: Preferences: Backend, defect, P5)

Product:
Core
Component:
Preferences: Backend
Type:
defect

Tracking

()

Status:
RESOLVED FIXED

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=301189333&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Htsun1pPRG2mprSZFbgjtg/runs/0/artifacts/public/logs/live_backing.log

[task 2020-05-07T11:32:35.968Z] 11:32:35 INFO - Running manifest: dom/canvas/test/crossorigin/mochitest.ini
[task 2020-05-07T11:32:35.988Z] 11:32:35 INFO - Setting pipeline to PAUSED ...
[task 2020-05-07T11:32:35.988Z] 11:32:35 INFO - Pipeline is PREROLLING ...
[task 2020-05-07T11:32:35.990Z] 11:32:35 INFO - Pipeline is PREROLLED ...
[task 2020-05-07T11:32:35.990Z] 11:32:35 INFO - Setting pipeline to PLAYING ...
[task 2020-05-07T11:32:35.991Z] 11:32:35 INFO - New clock: GstSystemClock
[task 2020-05-07T11:32:36.027Z] 11:32:36 INFO - Got EOS from element "pipeline0".
[task 2020-05-07T11:32:36.027Z] 11:32:36 INFO - Execution ended after 0:00:00.033449842
[task 2020-05-07T11:32:36.028Z] 11:32:36 INFO - Setting pipeline to PAUSED ...
[task 2020-05-07T11:32:36.028Z] 11:32:36 INFO - Setting pipeline to READY ...
[task 2020-05-07T11:32:36.028Z] 11:32:36 INFO - (gst-launch-1.0:1590): GStreamer-CRITICAL : 11:32:36.023: gst_object_unref: assertion '((GObject ) object)->ref_count > 0' failed
[task 2020-05-07T11:32:36.028Z] 11:32:36 INFO - Setting pipeline to NULL ...
[task 2020-05-07T11:32:36.028Z] 11:32:36 INFO - Freeing pipeline ...
[task 2020-05-07T11:32:36.378Z] 11:32:36 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-05-07T11:32:37.703Z] 11:32:37 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2020-05-07T11:32:38.332Z] 11:32:38 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-05-07T11:32:38.332Z] 11:32:38 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-05-07T11:32:38.340Z] 11:32:38 INFO - MochitestServer : launching [u'/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-f', '/builds/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpDE8Tx4.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2020-05-07T11:32:38.340Z] 11:32:38 INFO - runtests.py | Server pid: 1609
[task 2020-05-07T11:32:38.340Z] 11:32:38 INFO - runtests.py | Websocket server pid: 1612
[task 2020-05-07T11:32:38.341Z] 11:32:38 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-05-07T11:32:38.345Z] 11:32:38 INFO - runtests.py | SSL tunnel pid: 1615
[task 2020-05-07T11:32:39.051Z] 11:32:39 INFO - runtests.py | Running with scheme: http
[task 2020-05-07T11:32:39.051Z] 11:32:39 INFO - runtests.py | Running with e10s: True
[task 2020-05-07T11:32:39.051Z] 11:32:39 INFO - runtests.py | Running with fission: False
[task 2020-05-07T11:32:39.051Z] 11:32:39 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2020-05-07T11:32:39.051Z] 11:32:39 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2020-05-07T11:32:39.051Z] 11:32:39 INFO - runtests.py | Running tests: start.
[task 2020-05-07T11:32:39.052Z] 11:32:39 INFO -
[task 2020-05-07T11:32:39.067Z] 11:32:39 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpDE8Tx4.mozrunner
[task 2020-05-07T11:32:39.075Z] 11:32:39 INFO - runtests.py | Application pid: 1638
[task 2020-05-07T11:32:39.075Z] 11:32:39 INFO - TEST-INFO | started process GECKO(1638)
[task 2020-05-07T11:32:44.934Z] 11:32:44 INFO - GECKO(1638) | 1588851164929 Marionette TRACE Marionette enabled
[task 2020-05-07T11:32:45.756Z] 11:32:45 INFO - GECKO(1638) | 1588851165752 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-05-07T11:32:56.463Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.464Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.907Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.907Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.907Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.907Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.907Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.908Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.908Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.908Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.908Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.908Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.910Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.911Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.912Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.912Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.913Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.914Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.915Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.915Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.931Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.932Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.932Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.932Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.932Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.932Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.933Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.933Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.933Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.933Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.937Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.937Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.940Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.940Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.941Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:32:56.941Z] 11:32:56 INFO - GECKO(1638) | ==1771==WARNING: Symbolizer buffer too small
[task 2020-05-07T11:33:02.801Z] 11:33:02 INFO - GECKO(1638) | ==================
[task 2020-05-07T11:33:02.801Z] 11:33:02 INFO - GECKO(1638) | WARNING: ThreadSanitizer: data race (pid=1638)
[task 2020-05-07T11:33:02.802Z] 11:33:02 INFO - GECKO(1638) | Write of size 8 at 0x7b2c00011680 by thread T93:
[task 2020-05-07T11:33:02.802Z] 11:33:02 INFO - GECKO(1638) | #0 __xstat64 /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/../sanitizer_common/sanitizer_common_interceptors.inc:6535:3 (firefox+0x8518f)
[task 2020-05-07T11:33:02.803Z] 11:33:02 INFO - GECKO(1638) | #1 stat64 /usr/include/x86_64-linux-gnu/sys/stat.h:504:10 (libxul.so+0xa0aa2f)
[task 2020-05-07T11:33:02.803Z] 11:33:02 INFO - GECKO(1638) | #2 FillStatCache /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:259:7 (libxul.so+0xa0aa2f)
[task 2020-05-07T11:33:02.804Z] 11:33:02 INFO - GECKO(1638) | #3 nsLocalFile::GetPermissions(unsigned int) /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1152:3 (libxul.so+0xa0aa2f)
[task 2020-05-07T11:33:02.804Z] 11:33:02 INFO - GECKO(1638) | #4 nsAtomicFileOutputStream::DoOpen() /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp:803:9 (libxul.so+0xbbe224)
[task 2020-05-07T11:33:02.804Z] 11:33:02 INFO - GECKO(1638) | #5 MaybeOpen /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp:295:10 (libxul.so+0xbbd854)
[task 2020-05-07T11:33:02.806Z] 11:33:02 INFO - GECKO(1638) | #6 nsFileOutputStream::Init(nsIFile, int, int, int) /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp:702:10 (libxul.so+0xbbd854)
[task 2020-05-07T11:33:02.806Z] 11:33:02 INFO - GECKO(1638) | #7 Init /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp:759:30 (libxul.so+0xbbe0bb)
[task 2020-05-07T11:33:02.806Z] 11:33:02 INFO - GECKO(1638) | #8 non-virtual thunk to nsAtomicFileOutputStream::Init(nsIFile, int, int, int) /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp (libxul.so+0xbbe0bb)
[task 2020-05-07T11:33:02.806Z] 11:33:02 INFO - GECKO(1638) | #9 NS_NewSafeLocalFileOutputStream(nsIOutputStream**, nsIFile*, int, int, int) /builds/worker/checkouts/gecko/netwerk/base/nsNetUtil.cpp:1243:15 (libxul.so+0xbe32a8)
[task 2020-05-07T11:33:02.808Z] 11:33:02 INFO - GECKO(1638) | #10 mozilla::PreferencesWriter::Write(nsIFile*, nsTArray<nsTString<char> >&) /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:2990:10 (libxul.so+0xad1671)
[task 2020-05-07T11:33:02.808Z] 11:33:02 INFO - GECKO(1638) | #11 mozilla::PWRunnable::Run() /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:3084:12 (libxul.so+0xb27c73)
[task 2020-05-07T11:33:02.808Z] 11:33:02 INFO - GECKO(1638) | #12 nsThreadPool::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp:297:14 (libxul.so+0xa89326)
[task 2020-05-07T11:33:02.809Z] 11:33:02 INFO - GECKO(1638) | #13 non-virtual thunk to nsThreadPool::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp (libxul.so+0xa89c79)
[task 2020-05-07T11:33:02.809Z] 11:33:02 INFO - GECKO(1638) | #14 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1200:14 (libxul.so+0xa8309c)
[task 2020-05-07T11:33:02.809Z] 11:33:02 INFO - GECKO(1638) | #15 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:481:10 (libxul.so+0xa87452)
[task 2020-05-07T11:33:02.810Z] 11:33:02 INFO - GECKO(1638) | #16 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:302:20 (libxul.so+0x130408e)
[task 2020-05-07T11:33:02.811Z] 11:33:02 INFO - GECKO(1638) | #17 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:315:10 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.811Z] 11:33:02 INFO - GECKO(1638) | #18 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:308:3 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.811Z] 11:33:02 INFO - GECKO(1638) | #19 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:290:3 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.812Z] 11:33:02 INFO - GECKO(1638) | #20 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:444:10 (libxul.so+0xa7fc05)
[task 2020-05-07T11:33:02.812Z] 11:33:02 INFO - GECKO(1638) | #21 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x44d8f)
[task 2020-05-07T11:33:02.812Z] 11:33:02 INFO - GECKO(1638) | Previous write of size 8 at 0x7b2c00011680 by thread T84:
[task 2020-05-07T11:33:02.813Z] 11:33:02 INFO - GECKO(1638) | #0 __xstat64 /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/../sanitizer_common/sanitizer_common_interceptors.inc:6535:3 (firefox+0x8518f)
[task 2020-05-07T11:33:02.814Z] 11:33:02 INFO - GECKO(1638) | #1 stat64 /usr/include/x86_64-linux-gnu/sys/stat.h:504:10 (libxul.so+0xa0aa2f)
[task 2020-05-07T11:33:02.814Z] 11:33:02 INFO - GECKO(1638) | #2 FillStatCache /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:259:7 (libxul.so+0xa0aa2f)
[task 2020-05-07T11:33:02.814Z] 11:33:02 INFO - GECKO(1638) | #3 nsLocalFile::GetPermissions(unsigned int*) /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1152:3 (libxul.so+0xa0aa2f)
[task 2020-05-07T11:33:02.815Z] 11:33:02 INFO - GECKO(1638) | #4 nsAtomicFileOutputStream::DoOpen() /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp:803:9 (libxul.so+0xbbe224)
[task 2020-05-07T11:33:02.815Z] 11:33:02 INFO - GECKO(1638) | #5 MaybeOpen /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp:295:10 (libxul.so+0xbbd854)
[task 2020-05-07T11:33:02.815Z] 11:33:02 INFO - GECKO(1638) | #6 nsFileOutputStream::Init(nsIFile*, int, int, int) /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp:702:10 (libxul.so+0xbbd854)
[task 2020-05-07T11:33:02.815Z] 11:33:02 INFO - GECKO(1638) | #7 Init /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp:759:30 (libxul.so+0xbbe0bb)
[task 2020-05-07T11:33:02.816Z] 11:33:02 INFO - GECKO(1638) | #8 non-virtual thunk to nsAtomicFileOutputStream::Init(nsIFile*, int, int, int) /builds/worker/checkouts/gecko/netwerk/base/nsFileStreams.cpp (libxul.so+0xbbe0bb)
[task 2020-05-07T11:33:02.817Z] 11:33:02 INFO - GECKO(1638) | #9 NS_NewSafeLocalFileOutputStream(nsIOutputStream**, nsIFile*, int, int, int) /builds/worker/checkouts/gecko/netwerk/base/nsNetUtil.cpp:1243:15 (libxul.so+0xbe32a8)
[task 2020-05-07T11:33:02.817Z] 11:33:02 INFO - GECKO(1638) | #10 mozilla::PreferencesWriter::Write(nsIFile*, nsTArray<nsTString<char> >&) /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:2990:10 (libxul.so+0xad1671)
[task 2020-05-07T11:33:02.818Z] 11:33:02 INFO - GECKO(1638) | #11 mozilla::PWRunnable::Run() /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:3084:12 (libxul.so+0xb27c73)
[task 2020-05-07T11:33:02.818Z] 11:33:02 INFO - GECKO(1638) | #12 nsThreadPool::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp:297:14 (libxul.so+0xa89326)
[task 2020-05-07T11:33:02.819Z] 11:33:02 INFO - GECKO(1638) | #13 non-virtual thunk to nsThreadPool::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp (libxul.so+0xa89c79)
[task 2020-05-07T11:33:02.819Z] 11:33:02 INFO - GECKO(1638) | #14 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1200:14 (libxul.so+0xa8309c)
[task 2020-05-07T11:33:02.819Z] 11:33:02 INFO - GECKO(1638) | #15 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:481:10 (libxul.so+0xa87452)
[task 2020-05-07T11:33:02.820Z] 11:33:02 INFO - GECKO(1638) | #16 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:302:20 (libxul.so+0x130408e)
[task 2020-05-07T11:33:02.820Z] 11:33:02 INFO - GECKO(1638) | #17 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:315:10 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.821Z] 11:33:02 INFO - GECKO(1638) | #18 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:308:3 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.822Z] 11:33:02 INFO - GECKO(1638) | #19 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:290:3 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.822Z] 11:33:02 INFO - GECKO(1638) | #20 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:444:10 (libxul.so+0xa7fc05)
[task 2020-05-07T11:33:02.822Z] 11:33:02 INFO - GECKO(1638) | #21 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x44d8f)
[task 2020-05-07T11:33:02.822Z] 11:33:02 INFO - GECKO(1638) | Location is heap block of size 176 at 0x7b2c00011670 allocated by main thread:
[task 2020-05-07T11:33:02.823Z] 11:33:02 INFO - GECKO(1638) | #0 malloc /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors.cc:650:5 (firefox+0x55bf4)
[task 2020-05-07T11:33:02.823Z] 11:33:02 INFO - GECKO(1638) | #1 moz_xmalloc /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc.cpp:52:15 (firefox+0xca768)
[task 2020-05-07T11:33:02.824Z] 11:33:02 INFO - GECKO(1638) | #2 operator new /builds/worker/workspace/obj-build/dist/include/mozilla/cxxalloc.h:33:10 (libxul.so+0xa08a44)
[task 2020-05-07T11:33:02.824Z] 11:33:02 INFO - GECKO(1638) | #3 nsLocalFile::Clone(nsIFile**) /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:271:30 (libxul.so+0xa08a44)
[task 2020-05-07T11:33:02.825Z] 11:33:02 INFO - GECKO(1638) | #4 nsXREDirProvider::GetProfileDir(nsIFile**) /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:1339:25 (libxul.so+0x61b9053)
[task 2020-05-07T11:33:02.825Z] 11:33:02 INFO - GECKO(1638) | #5 nsXREDirProvider::GetFile(char const*, bool*, nsIFile**) /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:518:7 (libxul.so+0x61b7fca)
[task 2020-05-07T11:33:02.825Z] 11:33:02 INFO - GECKO(1638) | #6 FindProviderFile(nsIDirectoryServiceProvider*, FileData*) /builds/worker/checkouts/gecko/xpcom/io/nsDirectoryService.cpp:165:20 (libxul.so+0xa24039)
[task 2020-05-07T11:33:02.827Z] 11:33:02 INFO - GECKO(1638) | #7 nsDirectoryService::Get(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/io/nsDirectoryService.cpp:197:10 (libxul.so+0xa23e50)
[task 2020-05-07T11:33:02.827Z] 11:33:02 INFO - GECKO(1638) | #8 non-virtual thunk to nsDirectoryService::Get(char const*, nsID const&, void**) /builds/worker/checkouts/gecko/xpcom/io/nsDirectoryService.cpp (libxul.so+0xa24432)
[task 2020-05-07T11:33:02.827Z] 11:33:02 INFO - GECKO(1638) | #9 NS_GetSpecialDirectory /builds/worker/workspace/obj-build/dist/include/nsDirectoryServiceUtils.h:25:16 (libxul.so+0xace5a0)
[task 2020-05-07T11:33:02.828Z] 11:33:02 INFO - GECKO(1638) | #10 mozilla::Preferences::ReadSavedPrefs() /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:3996:7 (libxul.so+0xace5a0)
[task 2020-05-07T11:33:02.828Z] 11:33:02 INFO - GECKO(1638) | #11 mozilla::Preferences::InitializeUserPrefs() /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:3676:47 (libxul.so+0xace0fe)
[task 2020-05-07T11:33:02.828Z] 11:33:02 INFO - GECKO(1638) | #12 nsXREDirProvider::InitializeUserPrefs() /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:880:5 (libxul.so+0x61b9778)
[task 2020-05-07T11:33:02.828Z] 11:33:02 INFO - GECKO(1638) | #13 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4376:16 (libxul.so+0x61aabe5)
[task 2020-05-07T11:33:02.830Z] 11:33:02 INFO - GECKO(1638) | #14 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4750:8 (libxul.so+0x61abe64)
[task 2020-05-07T11:33:02.830Z] 11:33:02 INFO - GECKO(1638) | #15 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4804:21 (libxul.so+0x61ac194)
[task 2020-05-07T11:33:02.830Z] 11:33:02 INFO - GECKO(1638) | #16 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x61b6572)
[task 2020-05-07T11:33:02.830Z] 11:33:02 INFO - GECKO(1638) | #17 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:217:22 (firefox+0xc9433)
[task 2020-05-07T11:33:02.831Z] 11:33:02 INFO - GECKO(1638) | #18 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:331:16 (firefox+0xc9433)
[task 2020-05-07T11:33:02.831Z] 11:33:02 INFO - GECKO(1638) | Thread T93 'StreamTrans #52' (tid=1747, running) created by main thread at:
[task 2020-05-07T11:33:02.831Z] 11:33:02 INFO - GECKO(1638) | #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors.cc:967:3 (firefox+0x574db)
[task 2020-05-07T11:33:02.832Z] 11:33:02 INFO - GECKO(1638) | #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3bf93)
[task 2020-05-07T11:33:02.832Z] 11:33:02 INFO - GECKO(1638) | #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x308c2)
[task 2020-05-07T11:33:02.833Z] 11:33:02 INFO - GECKO(1638) | #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:650:8 (libxul.so+0xa80f67)
[task 2020-05-07T11:33:02.833Z] 11:33:02 INFO - GECKO(1638) | #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, unsigned int, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:620:12 (libxul.so+0xa86bdb)
[task 2020-05-07T11:33:02.833Z] 11:33:02 INFO - GECKO(1638) | #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, nsIRunnable*, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:139:57 (libxul.so+0xa88d08)
[task 2020-05-07T11:33:02.833Z] 11:33:02 INFO - GECKO(1638) | #6 nsThreadPool::PutEvent(already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp:114:17 (libxul.so+0xa88987)
[task 2020-05-07T11:33:02.835Z] 11:33:02 INFO - GECKO(1638) | #7 nsThreadPool::Dispatch(already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp:346:5 (libxul.so+0xa89d7f)
[task 2020-05-07T11:33:02.835Z] 11:33:02 INFO - GECKO(1638) | #8 mozilla::net::nsStreamTransportService::Dispatch(already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/netwerk/base/nsStreamTransportService.cpp:216:16 (libxul.so+0xc39c80)
[task 2020-05-07T11:33:02.835Z] 11:33:02 INFO - GECKO(1638) | #9 non-virtual thunk to mozilla::net::nsStreamTransportService::Dispatch(already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/netwerk/base/nsStreamTransportService.cpp (libxul.so+0xc39d48)
[task 2020-05-07T11:33:02.835Z] 11:33:02 INFO - GECKO(1638) | #10 nsSegmentedBuffer::FreeOMT(void*) /builds/worker/checkouts/gecko/xpcom/io/nsSegmentedBuffer.cpp:168:21 (libxul.so+0xa3aca0)
[task 2020-05-07T11:33:02.836Z] 11:33:02 INFO - GECKO(1638) | #11 Empty /builds/worker/checkouts/gecko/xpcom/io/nsSegmentedBuffer.cpp:106:9 (libxul.so+0xa3ae99)
[task 2020-05-07T11:33:02.836Z] 11:33:02 INFO - GECKO(1638) | #12 ~nsSegmentedBuffer /builds/worker/checkouts/gecko/xpcom/io/nsSegmentedBuffer.h:28:26 (libxul.so+0xa3ae99)
[task 2020-05-07T11:33:02.837Z] 11:33:02 INFO - GECKO(1638) | #13 nsStorageStream::~nsStorageStream() /builds/worker/checkouts/gecko/xpcom/io/nsStorageStream.cpp:64:39 (libxul.so+0xa3ae99)
[task 2020-05-07T11:33:02.837Z] 11:33:02 INFO - GECKO(1638) | #14 Release /builds/worker/checkouts/gecko/xpcom/io/nsStorageStream.cpp:66:1 (libxul.so+0xa3b09f)
[task 2020-05-07T11:33:02.838Z] 11:33:02 INFO - GECKO(1638) | #15 non-virtual thunk to nsStorageStream::Release() /builds/worker/checkouts/gecko/xpcom/io/nsStorageStream.cpp (libxul.so+0xa3b09f)
[task 2020-05-07T11:33:02.838Z] 11:33:02 INFO - GECKO(1638) | #16 ~nsCOMPtr_base /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:329:7 (libxul.so+0xa266bb)
[task 2020-05-07T11:33:02.838Z] 11:33:02 INFO - GECKO(1638) | #17 ~nsBinaryOutputStream /builds/worker/checkouts/gecko/xpcom/io/nsBinaryStream.h:55:43 (libxul.so+0xa266bb)
[task 2020-05-07T11:33:02.838Z] 11:33:02 INFO - GECKO(1638) | #18 nsBinaryOutputStream::~nsBinaryOutputStream() /builds/worker/checkouts/gecko/xpcom/io/nsBinaryStream.h:55:43 (libxul.so+0xa266bb)
[task 2020-05-07T11:33:02.838Z] 11:33:02 INFO - GECKO(1638) | #19 nsBinaryOutputStream::Release() /builds/worker/checkouts/gecko/xpcom/io/nsBinaryStream.cpp:70:1 (libxul.so+0xa1f1bf)
[task 2020-05-07T11:33:02.840Z] 11:33:02 INFO - GECKO(1638) | #20 ~nsCOMPtr_base /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:329:7 (libxul.so+0x43997f8)
[task 2020-05-07T11:33:02.840Z] 11:33:02 INFO - GECKO(1638) | #21 nsXULPrototypeCache::WritePrototype(nsXULPrototypeDocument*) /builds/worker/checkouts/gecko/dom/xul/nsXULPrototypeCache.cpp:238:1 (libxul.so+0x43997f8)
[task 2020-05-07T11:33:02.840Z] 11:33:02 INFO - GECKO(1638) | #22 mozilla::dom::PrototypeDocumentContentSink::DoneWalking() /builds/worker/checkouts/gecko/dom/prototype/PrototypeDocumentContentSink.cpp:635:43 (libxul.so+0x40b2ac8)
[task 2020-05-07T11:33:02.841Z] 11:33:02 INFO - GECKO(1638) | #23 MaybeDoneWalking /builds/worker/checkouts/gecko/dom/prototype/PrototypeDocumentContentSink.cpp:603:10 (libxul.so+0x40b2945)
[task 2020-05-07T11:33:02.841Z] 11:33:02 INFO - GECKO(1638) | #24 InitialTranslationCompleted /builds/worker/checkouts/gecko/dom/prototype/PrototypeDocumentContentSink.cpp:590:3 (libxul.so+0x40b2945)
[task 2020-05-07T11:33:02.841Z] 11:33:02 INFO - GECKO(1638) | #25 non-virtual thunk to mozilla::dom::PrototypeDocumentContentSink::InitialTranslationCompleted() /builds/worker/checkouts/gecko/dom/prototype/PrototypeDocumentContentSink.cpp (libxul.so+0x40b2945)
[task 2020-05-07T11:33:02.841Z] 11:33:02 INFO - GECKO(1638) | #26 InitialTranslationCompleted /builds/worker/checkouts/gecko/dom/l10n/DocumentL10n.cpp:282:19 (libxul.so+0x45686bb)
[task 2020-05-07T11:33:02.842Z] 11:33:02 INFO - GECKO(1638) | #27 L10nReadyHandler::ResolvedCallback(JSContext*, JS::Handle<JS::Value>) /builds/worker/checkouts/gecko/dom/l10n/DocumentL10n.cpp:96:20 (libxul.so+0x45686bb)
[task 2020-05-07T11:33:02.843Z] 11:33:02 INFO - GECKO(1638) | #28 mozilla::dom::(anonymous namespace)::PromiseNativeHandlerShim::ResolvedCallback(JSContext*, JS::Handle<JS::Value>) /builds/worker/checkouts/gecko/dom/promise/Promise.cpp:385:12 (libxul.so+0x42c0a09)
[task 2020-05-07T11:33:02.843Z] 11:33:02 INFO - GECKO(1638) | #29 mozilla::dom::NativeHandlerCallback(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/dom/promise/Promise.cpp:338:29 (libxul.so+0x42c0e8c)
[task 2020-05-07T11:33:02.843Z] 11:33:02 INFO - GECKO(1638) | #30 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:493:13 (libxul.so+0x62d7613)
[task 2020-05-07T11:33:02.844Z] 11:33:02 INFO - GECKO(1638) | #31 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:585:12 (libxul.so+0x62d7613)
[task 2020-05-07T11:33:02.844Z] 11:33:02 INFO - GECKO(1638) | #32 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x62d88ae)
[task 2020-05-07T11:33:02.845Z] 11:33:02 INFO - GECKO(1638) | #33 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:665:8 (libxul.so+0x62d88ae)
[task 2020-05-07T11:33:02.845Z] 11:33:02 INFO - GECKO(1638) | #34 Call /builds/worker/checkouts/gecko/js/src/vm/Interpreter.h:105:10 (libxul.so+0x64a0523)
[task 2020-05-07T11:33:02.845Z] 11:33:02 INFO - GECKO(1638) | #35 PromiseReactionJob(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:1871:10 (libxul.so+0x64a0523)
[task 2020-05-07T11:33:02.847Z] 11:33:02 INFO - GECKO(1638) | #36 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:493:13 (libxul.so+0x62d7613)
[task 2020-05-07T11:33:02.847Z] 11:33:02 INFO - GECKO(1638) | #37 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:585:12 (libxul.so+0x62d7613)
[task 2020-05-07T11:33:02.847Z] 11:33:02 INFO - GECKO(1638) | #38 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x62d88ae)
[task 2020-05-07T11:33:02.847Z] 11:33:02 INFO - GECKO(1638) | #39 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:665:8 (libxul.so+0x62d88ae)
[task 2020-05-07T11:33:02.848Z] 11:33:02 INFO - GECKO(1638) | #40 JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jsapi.cpp:2840:10 (libxul.so+0x638999d)
[task 2020-05-07T11:33:02.849Z] 11:33:02 INFO - GECKO(1638) | #41 mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/PromiseBinding.cpp:28:8 (libxul.so+0x29bc49b)
[task 2020-05-07T11:33:02.849Z] 11:33:02 INFO - GECKO(1638) | #42 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:91:12 (libxul.so+0x9a4a30)
[task 2020-05-07T11:33:02.849Z] 11:33:02 INFO - GECKO(1638) | #43 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:104:12 (libxul.so+0x9a4a30)
[task 2020-05-07T11:33:02.850Z] 11:33:02 INFO - GECKO(1638) | #44 mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:209:18 (libxul.so+0x9a4a30)
[task 2020-05-07T11:33:02.850Z] 11:33:02 INFO - GECKO(1638) | #45 mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:640:17 (libxul.so+0x993ec7)
[task 2020-05-07T11:33:02.850Z] 11:33:02 INFO - GECKO(1638) | #46 mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:459:3 (libxul.so+0x9947bf)
[task 2020-05-07T11:33:02.851Z] 11:33:02 INFO - GECKO(1638) | #47 XPCJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1340:28 (libxul.so+0x182325a)
[task 2020-05-07T11:33:02.852Z] 11:33:02 INFO - GECKO(1638) | #48 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1225:24 (libxul.so+0xa8331e)
[task 2020-05-07T11:33:02.852Z] 11:33:02 INFO - GECKO(1638) | #49 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:481:10 (libxul.so+0xa87452)
[task 2020-05-07T11:33:02.852Z] 11:33:02 INFO - GECKO(1638) | #50 mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:87:21 (libxul.so+0x1303649)
[task 2020-05-07T11:33:02.852Z] 11:33:02 INFO - GECKO(1638) | #51 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:315:10 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.853Z] 11:33:02 INFO - GECKO(1638) | #52 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:308:3 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.854Z] 11:33:02 INFO - GECKO(1638) | #53 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:290:3 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.854Z] 11:33:02 INFO - GECKO(1638) | #54 nsBaseAppShell::Run() /builds/worker/checkouts/gecko/widget/nsBaseAppShell.cpp:137:27 (libxul.so+0x45f3ce3)
[task 2020-05-07T11:33:02.854Z] 11:33:02 INFO - GECKO(1638) | #55 nsAppStartup::Run() /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:271:30 (libxul.so+0x60a2bd5)
[task 2020-05-07T11:33:02.854Z] 11:33:02 INFO - GECKO(1638) | #56 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4608:22 (libxul.so+0x61ab29a)
[task 2020-05-07T11:33:02.855Z] 11:33:02 INFO - GECKO(1638) | #57 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4750:8 (libxul.so+0x61abe64)
[task 2020-05-07T11:33:02.856Z] 11:33:02 INFO - GECKO(1638) | #58 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4804:21 (libxul.so+0x61ac194)
[task 2020-05-07T11:33:02.856Z] 11:33:02 INFO - GECKO(1638) | #59 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x61b6572)
[task 2020-05-07T11:33:02.857Z] 11:33:02 INFO - GECKO(1638) | #60 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:217:22 (firefox+0xc9433)
[task 2020-05-07T11:33:02.857Z] 11:33:02 INFO - GECKO(1638) | #61 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:331:16 (firefox+0xc9433)
[task 2020-05-07T11:33:02.857Z] 11:33:02 INFO - GECKO(1638) | Thread T84 'StreamTrans #43' (tid=1738, running) created by main thread at:
[task 2020-05-07T11:33:02.857Z] 11:33:02 INFO - GECKO(1638) | #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors.cc:967:3 (firefox+0x574db)
[task 2020-05-07T11:33:02.857Z] 11:33:02 INFO - GECKO(1638) | #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3bf93)
[task 2020-05-07T11:33:02.859Z] 11:33:02 INFO - GECKO(1638) | #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x308c2)
[task 2020-05-07T11:33:02.859Z] 11:33:02 INFO - GECKO(1638) | #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:650:8 (libxul.so+0xa80f67)
[task 2020-05-07T11:33:02.859Z] 11:33:02 INFO - GECKO(1638) | #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, unsigned int, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:620:12 (libxul.so+0xa86bdb)
[task 2020-05-07T11:33:02.860Z] 11:33:02 INFO - GECKO(1638) | #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, nsIRunnable*, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:139:57 (libxul.so+0xa88d08)
[task 2020-05-07T11:33:02.860Z] 11:33:02 INFO - GECKO(1638) | #6 nsThreadPool::PutEvent(already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp:114:17 (libxul.so+0xa88987)
[task 2020-05-07T11:33:02.860Z] 11:33:02 INFO - GECKO(1638) | #7 nsThreadPool::Dispatch(already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadPool.cpp:346:5 (libxul.so+0xa89d7f)
[task 2020-05-07T11:33:02.862Z] 11:33:02 INFO - GECKO(1638) | #8 mozilla::net::nsStreamTransportService::Dispatch(already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/netwerk/base/nsStreamTransportService.cpp:216:16 (libxul.so+0xc39c80)
[task 2020-05-07T11:33:02.862Z] 11:33:02 INFO - GECKO(1638) | #9 non-virtual thunk to mozilla::net::nsStreamTransportService::Dispatch(already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/netwerk/base/nsStreamTransportService.cpp (libxul.so+0xc39d48)
[task 2020-05-07T11:33:02.862Z] 11:33:02 INFO - GECKO(1638) | #10 nsSegmentedBuffer::FreeOMT(void*) /builds/worker/checkouts/gecko/xpcom/io/nsSegmentedBuffer.cpp:168:21 (libxul.so+0xa3aca0)
[task 2020-05-07T11:33:02.862Z] 11:33:02 INFO - GECKO(1638) | #11 Empty /builds/worker/checkouts/gecko/xpcom/io/nsSegmentedBuffer.cpp:106:9 (libxul.so+0xa3ae99)
[task 2020-05-07T11:33:02.862Z] 11:33:02 INFO - GECKO(1638) | #12 ~nsSegmentedBuffer /builds/worker/checkouts/gecko/xpcom/io/nsSegmentedBuffer.h:28:26 (libxul.so+0xa3ae99)
[task 2020-05-07T11:33:02.863Z] 11:33:02 INFO - GECKO(1638) | #13 nsStorageStream::~nsStorageStream() /builds/worker/checkouts/gecko/xpcom/io/nsStorageStream.cpp:64:39 (libxul.so+0xa3ae99)
[task 2020-05-07T11:33:02.864Z] 11:33:02 INFO - GECKO(1638) | #14 Release /builds/worker/checkouts/gecko/xpcom/io/nsStorageStream.cpp:66:1 (libxul.so+0xa3b09f)
[task 2020-05-07T11:33:02.864Z] 11:33:02 INFO - GECKO(1638) | #15 non-virtual thunk to nsStorageStream::Release() /builds/worker/checkouts/gecko/xpcom/io/nsStorageStream.cpp (libxul.so+0xa3b09f)
[task 2020-05-07T11:33:02.864Z] 11:33:02 INFO - GECKO(1638) | #16 ~nsCOMPtr_base /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:329:7 (libxul.so+0xa266bb)
[task 2020-05-07T11:33:02.865Z] 11:33:02 INFO - GECKO(1638) | #17 ~nsBinaryOutputStream /builds/worker/checkouts/gecko/xpcom/io/nsBinaryStream.h:55:43 (libxul.so+0xa266bb)
[task 2020-05-07T11:33:02.865Z] 11:33:02 INFO - GECKO(1638) | #18 nsBinaryOutputStream::~nsBinaryOutputStream() /builds/worker/checkouts/gecko/xpcom/io/nsBinaryStream.h:55:43 (libxul.so+0xa266bb)
[task 2020-05-07T11:33:02.865Z] 11:33:02 INFO - GECKO(1638) | #19 nsBinaryOutputStream::Release() /builds/worker/checkouts/gecko/xpcom/io/nsBinaryStream.cpp:70:1 (libxul.so+0xa1f1bf)
[task 2020-05-07T11:33:02.865Z] 11:33:02 INFO - GECKO(1638) | #20 ~nsCOMPtr_base /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:329:7 (libxul.so+0x43997f8)
[task 2020-05-07T11:33:02.867Z] 11:33:02 INFO - GECKO(1638) | #21 nsXULPrototypeCache::WritePrototype(nsXULPrototypeDocument*) /builds/worker/checkouts/gecko/dom/xul/nsXULPrototypeCache.cpp:238:1 (libxul.so+0x43997f8)
[task 2020-05-07T11:33:02.867Z] 11:33:02 INFO - GECKO(1638) | #22 mozilla::dom::PrototypeDocumentContentSink::DoneWalking() /builds/worker/checkouts/gecko/dom/prototype/PrototypeDocumentContentSink.cpp:635:43 (libxul.so+0x40b2ac8)
[task 2020-05-07T11:33:02.867Z] 11:33:02 INFO - GECKO(1638) | #23 MaybeDoneWalking /builds/worker/checkouts/gecko/dom/prototype/PrototypeDocumentContentSink.cpp:603:10 (libxul.so+0x40b2945)
[task 2020-05-07T11:33:02.867Z] 11:33:02 INFO - GECKO(1638) | #24 InitialTranslationCompleted /builds/worker/checkouts/gecko/dom/prototype/PrototypeDocumentContentSink.cpp:590:3 (libxul.so+0x40b2945)
[task 2020-05-07T11:33:02.868Z] 11:33:02 INFO - GECKO(1638) | #25 non-virtual thunk to mozilla::dom::PrototypeDocumentContentSink::InitialTranslationCompleted() /builds/worker/checkouts/gecko/dom/prototype/PrototypeDocumentContentSink.cpp (libxul.so+0x40b2945)
[task 2020-05-07T11:33:02.869Z] 11:33:02 INFO - GECKO(1638) | #26 InitialTranslationCompleted /builds/worker/checkouts/gecko/dom/l10n/DocumentL10n.cpp:282:19 (libxul.so+0x45686bb)
[task 2020-05-07T11:33:02.869Z] 11:33:02 INFO - GECKO(1638) | #27 L10nReadyHandler::ResolvedCallback(JSContext*, JS::Handle<JS::Value>) /builds/worker/checkouts/gecko/dom/l10n/DocumentL10n.cpp:96:20 (libxul.so+0x45686bb)
[task 2020-05-07T11:33:02.870Z] 11:33:02 INFO - GECKO(1638) | #28 mozilla::dom::(anonymous namespace)::PromiseNativeHandlerShim::ResolvedCallback(JSContext*, JS::Handle<JS::Value>) /builds/worker/checkouts/gecko/dom/promise/Promise.cpp:385:12 (libxul.so+0x42c0a09)
[task 2020-05-07T11:33:02.870Z] 11:33:02 INFO - GECKO(1638) | #29 mozilla::dom::NativeHandlerCallback(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/dom/promise/Promise.cpp:338:29 (libxul.so+0x42c0e8c)
[task 2020-05-07T11:33:02.870Z] 11:33:02 INFO - GECKO(1638) | #30 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:493:13 (libxul.so+0x62d7613)
[task 2020-05-07T11:33:02.871Z] 11:33:02 INFO - GECKO(1638) | #31 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:585:12 (libxul.so+0x62d7613)
[task 2020-05-07T11:33:02.872Z] 11:33:02 INFO - GECKO(1638) | #32 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x62d88ae)
[task 2020-05-07T11:33:02.872Z] 11:33:02 INFO - GECKO(1638) | #33 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:665:8 (libxul.so+0x62d88ae)
[task 2020-05-07T11:33:02.872Z] 11:33:02 INFO - GECKO(1638) | #34 Call /builds/worker/checkouts/gecko/js/src/vm/Interpreter.h:105:10 (libxul.so+0x64a0523)
[task 2020-05-07T11:33:02.873Z] 11:33:02 INFO - GECKO(1638) | #35 PromiseReactionJob(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:1871:10 (libxul.so+0x64a0523)
[task 2020-05-07T11:33:02.873Z] 11:33:02 INFO - GECKO(1638) | #36 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:493:13 (libxul.so+0x62d7613)
[task 2020-05-07T11:33:02.873Z] 11:33:02 INFO - GECKO(1638) | #37 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:585:12 (libxul.so+0x62d7613)
[task 2020-05-07T11:33:02.874Z] 11:33:02 INFO - GECKO(1638) | #38 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:10 (libxul.so+0x62d88ae)
[task 2020-05-07T11:33:02.875Z] 11:33:02 INFO - GECKO(1638) | #39 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:665:8 (libxul.so+0x62d88ae)
[task 2020-05-07T11:33:02.875Z] 11:33:02 INFO - GECKO(1638) | #40 JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jsapi.cpp:2840:10 (libxul.so+0x638999d)
[task 2020-05-07T11:33:02.875Z] 11:33:02 INFO - GECKO(1638) | #41 mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/PromiseBinding.cpp:28:8 (libxul.so+0x29bc49b)
[task 2020-05-07T11:33:02.875Z] 11:33:02 INFO - GECKO(1638) | #42 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:91:12 (libxul.so+0x9a4a30)
[task 2020-05-07T11:33:02.876Z] 11:33:02 INFO - GECKO(1638) | #43 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:104:12 (libxul.so+0x9a4a30)
[task 2020-05-07T11:33:02.877Z] 11:33:02 INFO - GECKO(1638) | #44 mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:209:18 (libxul.so+0x9a4a30)
[task 2020-05-07T11:33:02.877Z] 11:33:02 INFO - GECKO(1638) | #45 mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:640:17 (libxul.so+0x993ec7)
[task 2020-05-07T11:33:02.877Z] 11:33:02 INFO - GECKO(1638) | #46 mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:459:3 (libxul.so+0x9947bf)
[task 2020-05-07T11:33:02.878Z] 11:33:02 INFO - GECKO(1638) | #47 XPCJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1340:28 (libxul.so+0x182325a)
[task 2020-05-07T11:33:02.878Z] 11:33:02 INFO - GECKO(1638) | #48 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1225:24 (libxul.so+0xa8331e)
[task 2020-05-07T11:33:02.878Z] 11:33:02 INFO - GECKO(1638) | #49 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:481:10 (libxul.so+0xa87452)
[task 2020-05-07T11:33:02.880Z] 11:33:02 INFO - GECKO(1638) | #50 mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:87:21 (libxul.so+0x1303649)
[task 2020-05-07T11:33:02.880Z] 11:33:02 INFO - GECKO(1638) | #51 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:315:10 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.880Z] 11:33:02 INFO - GECKO(1638) | #52 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:308:3 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.880Z] 11:33:02 INFO - GECKO(1638) | #53 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:290:3 (libxul.so+0x129969c)
[task 2020-05-07T11:33:02.881Z] 11:33:02 INFO - GECKO(1638) | #54 nsBaseAppShell::Run() /builds/worker/checkouts/gecko/widget/nsBaseAppShell.cpp:137:27 (libxul.so+0x45f3ce3)
[task 2020-05-07T11:33:02.881Z] 11:33:02 INFO - GECKO(1638) | #55 nsAppStartup::Run() /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:271:30 (libxul.so+0x60a2bd5)
[task 2020-05-07T11:33:02.881Z] 11:33:02 INFO - GECKO(1638) | #56 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4608:22 (libxul.so+0x61ab29a)
[task 2020-05-07T11:33:02.883Z] 11:33:02 INFO - GECKO(1638) | #57 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4750:8 (libxul.so+0x61abe64)
[task 2020-05-07T11:33:02.884Z] 11:33:02 INFO - GECKO(1638) | #58 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4804:21 (libxul.so+0x61ac194)
[task 2020-05-07T11:33:02.885Z] 11:33:02 INFO - GECKO(1638) | #59 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x61b6572)
[task 2020-05-07T11:33:02.886Z] 11:33:02 INFO - GECKO(1638) | #60 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:217:22 (firefox+0xc9433)
[task 2020-05-07T11:33:02.886Z] 11:33:02 INFO - GECKO(1638) | #61 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:331:16 (firefox+0xc9433)
[task 2020-05-07T11:33:02.886Z] 11:33:02 INFO - GECKO(1638) | SUMMARY: ThreadSanitizer: data race /usr/include/x86_64-linux-gnu/sys/stat.h:504:10 in stat64
[task 2020-05-07T11:33:02.887Z] 11:33:02 INFO - GECKO(1638) | ==================
[task 2020-05-07T11:33:02.887Z] 11:33:02 INFO - GECKO(1638) | Exiting due to channel error.

The basic problem is PreferencesWriter::Write() is being called on different threads. The race shows up in nsLocalFile::mCacheStat, because it has a member mCacheStat which caches info from earlier stat() calls.

dthayer: your change in bug 1630655 landed after this was first reported. I'm wondering if it may have inadvertently fixed it?

Flags: needinfo?(dothayer)

(In reply to Nicholas Nethercote [:njn] from comment #3)

The basic problem is PreferencesWriter::Write() is being called on different threads. The race shows up in nsLocalFile::mCacheStat, because it has a member mCacheStat which caches info from earlier stat() calls.

dthayer: your change in bug 1630655 landed after this was first reported. I'm wondering if it may have inadvertently fixed it?

I always have trouble understanding these reports. Does it look fixed? Comment 2 would suggest to me that it's still happening.

In any case, I don't think my patch would have eliminated data races inside PreferencesWriter::Write. It doesn't add any guarantees that we don't run it from multiple threads at the same time. It could have reduced instances of the main thread running it at the same time as the STS thread pool though, if for some reason something tries to call PreferencesWriter::Flush() before doing a write?

Flags: needinfo?(dothayer)

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
Blocks: tsan

New occurrence: https://treeherder.mozilla.org/logviewer?job_id=324251080&repo=autoland&lineNumber=2530

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Depends on: 1681950

I filed a separate bug for this.

fixed in Bug 1681950

Status: REOPENED → RESOLVED
Closed: 5 years ago → 5 years ago
Resolution: --- → FIXED
You need to log in before you can comment on or make changes to this bug.