Closed Bug 1730031 Opened 3 years ago Closed 3 years ago

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/widget/gtk/nsWindow.cpp:6108:11 in nsWindow::IsWaitingForCompositorResume()

Categories

(Core :: Graphics: WebRender, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1727225

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 2 open bugs, Regression)

Details

(Keywords: csectype-race, intermittent-failure, regression)

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


[task 2021-09-09T22:06:21.924Z] 22:06:21     INFO - TEST-START | browser/components/preferences/tests/siteData/browser_siteData2.js
[task 2021-09-09T22:07:02.474Z] 22:07:02     INFO - GECKO(2717) | ==================
[task 2021-09-09T22:07:02.475Z] 22:07:02     INFO - GECKO(2717) | WARNING: ThreadSanitizer: data race (pid=2717)
[task 2021-09-09T22:07:02.476Z] 22:07:02     INFO - GECKO(2717) |   Read of size 1 at 0x7b60007409a9 by thread T37:
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #0 nsWindow::IsWaitingForCompositorResume() /builds/worker/checkouts/gecko/widget/gtk/nsWindow.cpp:6108:11 (libxul.so+0x4c618d9)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #1 mozilla::widget::GtkCompositorWidget::RemoteLayoutSizeUpdated(mozilla::gfx::RectTyped<mozilla::LayoutDevicePixel, float> const&) /builds/worker/checkouts/gecko/widget/gtk/GtkCompositorWidget.cpp:93:29 (libxul.so+0x4c7c241)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #2 mozilla::layers::WebRenderBridgeParent::SetDisplayList(mozilla::gfx::RectTyped<mozilla::LayoutDevicePixel, float> const&, mozilla::ipc::ByteBuf&&, mozilla::ipc::ByteBuf&&, mozilla::ipc::ByteBuf&&, mozilla::wr::BuiltDisplayListDescriptor const&, nsTArray<mozilla::layers::OpUpdateResource> const&, nsTArray<mozilla::layers::RefCountedShmem> const&, nsTArray<mozilla::ipc::Shmem> const&, mozilla::TimeStamp const&, mozilla::wr::TransactionBuilder&, mozilla::wr::Epoch, bool) /builds/worker/checkouts/gecko/gfx/layers/wr/WebRenderBridgeParent.cpp:1155:25 (libxul.so+0x20ef795)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #3 mozilla::layers::WebRenderBridgeParent::ProcessDisplayListData(mozilla::layers::DisplayListData&, mozilla::wr::Epoch, mozilla::TimeStamp const&, bool, bool) /builds/worker/checkouts/gecko/gfx/layers/wr/WebRenderBridgeParent.cpp:1214:8 (libxul.so+0x20efe15)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #4 mozilla::layers::WebRenderBridgeParent::RecvSetDisplayList(mozilla::layers::DisplayListData&&, nsTArray<mozilla::layers::OpDestroy>&&, unsigned long const&, mozilla::layers::BaseTransactionId<mozilla::layers::TransactionIdType> const&, bool const&, mozilla::layers::BaseTransactionId<mozilla::VsyncIdType> const&, mozilla::TimeStamp const&, mozilla::TimeStamp const&, mozilla::TimeStamp const&, nsTString<char> const&, mozilla::TimeStamp const&, nsTArray<mozilla::layers::CompositionPayload>&&) /builds/worker/checkouts/gecko/gfx/layers/wr/WebRenderBridgeParent.cpp:1270:8 (libxul.so+0x20f0be7)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #5 mozilla::layers::PWebRenderBridgeParent::OnMessageReceived(IPC::Message const&) /builds/worker/workspace/obj-build/ipc/ipdl/PWebRenderBridgeParent.cpp:403:28 (libxul.so+0x1a1155e)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #6 mozilla::layers::PCompositorManagerParent::OnMessageReceived(IPC::Message const&) /builds/worker/workspace/obj-build/ipc/ipdl/PCompositorManagerParent.cpp:200:32 (libxul.so+0x16a27f1)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #7 mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:2039:25 (libxul.so+0x15b39ae)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #8 mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1964:9 (libxul.so+0x15b1ec5)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #9 mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1823:3 (libxul.so+0x15b2aba)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #10 mozilla::ipc::MessageChannel::MessageTask::Run() /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1851:14 (libxul.so+0x15b30b5)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #11 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1142:16 (libxul.so+0xc89af2)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #12 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:466:10 (libxul.so+0xc90862)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #13 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:300:20 (libxul.so+0x15b845e)
[task 2021-09-09T22:07:02.487Z] 22:07:02     INFO - GECKO(2717) |     #14 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:331:10 (libxul.so+0x153213c)
[task 2021-09-09T22:07:02.488Z] 22:07:02     INFO - GECKO(2717) |     #15 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:324:3 (libxul.so+0x153213c)
[task 2021-09-09T22:07:02.488Z] 22:07:02     INFO - GECKO(2717) |     #16 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:306:3 (libxul.so+0x153213c)
[task 2021-09-09T22:07:02.488Z] 22:07:02     INFO - GECKO(2717) |     #17 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:390:10 (libxul.so+0xc862f8)
[task 2021-09-09T22:07:02.489Z] 22:07:02     INFO - GECKO(2717) |     #18 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x44cd6)
[task 2021-09-09T22:07:02.489Z] 22:07:02     INFO - GECKO(2717) |   Previous write of size 1 at 0x7b60007409a9 by main thread:
[task 2021-09-09T22:07:02.490Z] 22:07:02     INFO - GECKO(2717) |     #0 nsWindow::Destroy() /builds/worker/checkouts/gecko/widget/gtk/nsWindow.cpp:703:16 (libxul.so+0x4c3edee)
[task 2021-09-09T22:07:02.490Z] 22:07:02     INFO - GECKO(2717) |     #1 mozilla::AppWindow::Destroy() /builds/worker/checkouts/gecko/xpfe/appshell/AppWindow.cpp:652:14 (libxul.so+0x6546fb1)
[task 2021-09-09T22:07:02.491Z] 22:07:02     INFO - GECKO(2717) |     #2 Destroy /builds/worker/checkouts/gecko/xpfe/appshell/nsChromeTreeOwner.cpp:255:22 (libxul.so+0x655c1c4)
[task 2021-09-09T22:07:02.491Z] 22:07:02     INFO - GECKO(2717) |     #3 non-virtual thunk to nsChromeTreeOwner::Destroy() /builds/worker/checkouts/gecko/xpfe/appshell/nsChromeTreeOwner.cpp (libxul.so+0x655c1c4)
[task 2021-09-09T22:07:02.492Z] 22:07:02     INFO - GECKO(2717) |     #4 nsGlobalWindowOuter::ReallyCloseWindow() /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:6345:19 (libxul.so+0x248415f)
[task 2021-09-09T22:07:02.492Z] 22:07:02     INFO - GECKO(2717) |     #5 nsCloseEvent::Run() /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:6146:16 (libxul.so+0x248964b)
[task 2021-09-09T22:07:02.493Z] 22:07:02     INFO - GECKO(2717) |     #6 mozilla::RunnableTask::Run() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:502:16 (libxul.so+0xc9bc87)
[task 2021-09-09T22:07:02.494Z] 22:07:02     INFO - GECKO(2717) |     #7 mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:805:26 (libxul.so+0xc77b72)
[task 2021-09-09T22:07:02.494Z] 22:07:02     INFO - GECKO(2717) |     #8 mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:641:15 (libxul.so+0xc761c6)
[task 2021-09-09T22:07:02.494Z] 22:07:02     INFO - GECKO(2717) |     #9 mozilla::TaskController::ProcessPendingMTTask(bool) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:425:36 (libxul.so+0xc76494)
[task 2021-09-09T22:07:02.495Z] 22:07:02     INFO - GECKO(2717) |     #10 operator() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:138:37 (libxul.so+0xc9fb2a)
...
[task 2021-09-09T22:07:02.696Z] 22:07:02     INFO - GECKO(2717) |     #29 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3054:12 (libxul.so+0x6b6bc99)
[task 2021-09-09T22:07:02.696Z] 22:07:02     INFO - GECKO(2717) |     #30 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:370:13 (libxul.so+0x6b60a46)
[task 2021-09-09T22:07:02.697Z] 22:07:02     INFO - GECKO(2717) |     #31 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:520:13 (libxul.so+0x6b778e7)
[task 2021-09-09T22:07:02.697Z] 22:07:02     INFO - GECKO(2717) |     #32 InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:548:10 (libxul.so+0x6b786ae)
[task 2021-09-09T22:07:02.697Z] 22:07:02     INFO - GECKO(2717) |     #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:565:8 (libxul.so+0x6b7879a)
[task 2021-09-09T22:07:02.697Z] 22:07:02     INFO - GECKO(2717) |     #34 JS_CallFunctionValue(JSContext*, JS::Handle<JSObject*>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CallAndConstruct.cpp:53:10 (libxul.so+0x6cb5779)
[task 2021-09-09T22:07:02.697Z] 22:07:02     INFO - GECKO(2717) |     #35 nsXPCWrappedJS::CallMethod(unsigned short, nsXPTMethodInfo const*, nsXPTCMiniVariant*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedJSClass.cpp:973:17 (libxul.so+0x1bf7579)
[task 2021-09-09T22:07:02.698Z] 22:07:02     INFO - GECKO(2717) |     #36 PrepareAndDispatch /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcstubs_x86_64_linux.cpp:115:37 (libxul.so+0xcb8cc1)
[task 2021-09-09T22:07:02.698Z] 22:07:02     INFO - GECKO(2717) |     #37 SharedStub <null> (libxul.so+0xcb8022)
[task 2021-09-09T22:07:02.698Z] 22:07:02     INFO - GECKO(2717) |     #38 nsXREDirProvider::DoStartup() /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:976:11 (libxul.so+0x6a48f4e)
[task 2021-09-09T22:07:02.698Z] 22:07:02     INFO - GECKO(2717) |     #39 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5027:18 (libxul.so+0x6a3785f)
[task 2021-09-09T22:07:02.698Z] 22:07:02     INFO - GECKO(2717) |     #40 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5476:8 (libxul.so+0x6a38bf6)
[task 2021-09-09T22:07:02.703Z] 22:07:02     INFO - GECKO(2717) |     #41 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5535:21 (libxul.so+0x6a391c4)
[task 2021-09-09T22:07:02.703Z] 22:07:02     INFO - GECKO(2717) |     #42 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x6a43862)
[task 2021-09-09T22:07:02.703Z] 22:07:02     INFO - GECKO(2717) |     #43 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:225:22 (firefox+0xca390)
[task 2021-09-09T22:07:02.703Z] 22:07:02     INFO - GECKO(2717) |     #44 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:378:16 (firefox+0xca390)
[task 2021-09-09T22:07:02.704Z] 22:07:02     INFO - GECKO(2717) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/widget/gtk/nsWindow.cpp:6108:11 in nsWindow::IsWaitingForCompositorResume()
[task 2021-09-09T22:07:02.704Z] 22:07:02     INFO - GECKO(2717) | ==================
[task 2021-09-09T22:07:02.704Z] 22:07:02     INFO - GECKO(2717) | Exiting due to channel error.
[task 2021-09-09T22:07:02.704Z] 22:07:02     INFO - GECKO(2717) | Exiting due to channel error.
[task 2021-09-09T22:07:02.704Z] 22:07:02     INFO - GECKO(2717) | Exiting due to channel error.
[task 2021-09-09T22:07:02.705Z] 22:07:02     INFO - GECKO(2717) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=126.31) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=132.195) Exiting due to channel error.
[task 2021-09-09T22:07:02.705Z] 22:07:02     INFO - GECKO(2717) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=140.85) Exiting due to channel error.
[task 2021-09-09T22:07:02.705Z] 22:07:02     INFO - GECKO(2717) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=141.9) Exiting due to channel error.
[task 2021-09-09T22:07:03.665Z] 22:07:03     INFO - GECKO(2717) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=127.783)
[task 2021-09-09T22:07:03.665Z] 22:07:03     INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2021-09-09T22:07:03.666Z] 22:07:03     INFO - Buffered messages logged at 22:06:21
[task 2021-09-09T22:07:03.667Z] 22:07:03     INFO - Entering test bound test_selectRemove
[task 2021-09-09T22:07:03.667Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Passed an origin string into addTestData. - 
[task 2021-09-09T22:07:03.668Z] 22:07:03     INFO - Buffered messages logged at 22:06:22
[task 2021-09-09T22:07:03.669Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Passed an origin string into addTestData. - 
[task 2021-09-09T22:07:03.669Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Passed an origin string into addTestData. - 
[task 2021-09-09T22:07:03.670Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Passed an origin string into addTestData. - 
[task 2021-09-09T22:07:03.671Z] 22:07:03     INFO - Buffered messages logged at 22:06:29
[task 2021-09-09T22:07:03.671Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Check the proper URL is loaded - 
[task 2021-09-09T22:07:03.672Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Element should not be null, when checking visibility - 
[task 2021-09-09T22:07:03.672Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Overlay is visible - 
[task 2021-09-09T22:07:03.673Z] 22:07:03     INFO - found chrome://browser/skin/preferences/preferences.css
[task 2021-09-09T22:07:03.673Z] 22:07:03     INFO - found chrome://global/skin/in-content/common.css
[task 2021-09-09T22:07:03.674Z] 22:07:03     INFO - found chrome://browser/skin/preferences/dialog.css
[task 2021-09-09T22:07:03.674Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | All expectedStyleSheetURLs should have been found - 
[task 2021-09-09T22:07:03.675Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Element should not be null, when checking visibility - 
[task 2021-09-09T22:07:03.676Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | The Settings dialog should be visible - 
[task 2021-09-09T22:07:03.676Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Should list the right sites number - 
...
[task 2021-09-09T22:07:03.794Z] 22:07:03     INFO - found chrome://global/skin/in-content/common.css
[task 2021-09-09T22:07:03.795Z] 22:07:03     INFO - found chrome://browser/skin/preferences/dialog.css
[task 2021-09-09T22:07:03.796Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | All expectedStyleSheetURLs should have been found - 
[task 2021-09-09T22:07:03.797Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Element should not be null, when checking visibility - 
[task 2021-09-09T22:07:03.798Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | The Settings dialog should be visible - 
[task 2021-09-09T22:07:03.799Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Should list the right sites number - 
[task 2021-09-09T22:07:03.800Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Should list the site of xyz.com - 
[task 2021-09-09T22:07:03.801Z] 22:07:03     INFO - TEST-PASS | browser/components/preferences/tests/siteData/browser_siteData2.js | Should enable the removeAllBtn button - 
[task 2021-09-09T22:07:03.801Z] 22:07:03     INFO - Console message: [JavaScript Error: "Security Error: Content at chrome://global/skin/search-textbox.css may not load or link to resource://gre-resources/searchfield-cancel.svg."]
[task 2021-09-09T22:07:03.802Z] 22:07:03     INFO - Buffered messages finished
[task 2021-09-09T22:07:03.803Z] 22:07:03    ERROR - TEST-UNEXPECTED-FAIL | browser/components/preferences/tests/siteData/browser_siteData2.js | application terminated with exit code -6
[task 2021-09-09T22:07:03.804Z] 22:07:03     INFO - runtests.py | Application ran for: 0:02:33.648296
[task 2021-09-09T22:07:03.804Z] 22:07:03     INFO - zombiecheck | Reading PID log: /tmp/tmpeg6ii90gpidlog
[task 2021-09-09T22:07:03.805Z] 22:07:03     INFO - ==> process 2717 launched child process 2732
[task 2021-09-09T22:07:03.808Z] 22:07:03     INFO - ==> process 2717 launched child process 2799
[task 2021-09-09T22:07:03.808Z] 22:07:03     INFO - ==> process 2717 launched child process 2817
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - ==> process 2717 launched child process 2872
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - ==> process 2717 launched child process 2909
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - ==> process 2717 launched child process 2934
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - ==> process 2717 launched child process 2961
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - zombiecheck | Checking for orphan process with PID: 2817
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - zombiecheck | Checking for orphan process with PID: 2732
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - zombiecheck | Checking for orphan process with PID: 2799
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - zombiecheck | Checking for orphan process with PID: 2961
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - zombiecheck | Checking for orphan process with PID: 2934
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - zombiecheck | Checking for orphan process with PID: 2872
[task 2021-09-09T22:07:03.809Z] 22:07:03     INFO - zombiecheck | Checking for orphan process with PID: 2909
[task 2021-09-09T22:07:03.810Z] 22:07:03     INFO - Stopping web server
[task 2021-09-09T22:07:03.810Z] 22:07:03     INFO - Server shut down.
[task 2021-09-09T22:07:03.811Z] 22:07:03     INFO - Web server killed.
[task 2021-09-09T22:07:03.811Z] 22:07:03     INFO - Stopping web socket server
[task 2021-09-09T22:07:03.811Z] 22:07:03     INFO - Stopping ssltunnel
[task 2021-09-09T22:07:03.811Z] 22:07:03  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
...
task 2021-09-09T22:07:27.388Z] 22:07:27     INFO - GECKO(3233) | 1631225247387	Marionette	TRACE	All scripts recorded.
[task 2021-09-09T22:07:27.410Z] 22:07:27     INFO - GECKO(3233) | 1631225247409	Marionette	INFO	Listening on port 2828
[task 2021-09-09T22:07:27.412Z] 22:07:27     INFO - GECKO(3233) | 1631225247411	Marionette	DEBUG	Marionette is listening
[task 2021-09-09T22:07:27.617Z] 22:07:27     INFO - GECKO(3233) | 1631225247616	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:37504
[task 2021-09-09T22:07:27.645Z] 22:07:27     INFO - GECKO(3233) | 1631225247643	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:37506
[task 2021-09-09T22:07:27.648Z] 22:07:27     INFO - GECKO(3233) | 1631225247647	Marionette	DEBUG	Closed connection 0
[task 2021-09-09T22:07:27.762Z] 22:07:27     INFO - GECKO(3233) | 1631225247761	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-09-09T22:07:27.893Z] 22:07:27     INFO - GECKO(3233) | 1631225247892	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"97fb7cc9-caef-47c0-9ba1-7eed640e3b30","capabilities":{"browserName":"firefox","browserVersion":"94.0a ... .mozrunner","moz:shutdownTimeout":360000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-09-09T22:07:27.930Z] 22:07:27     INFO - GECKO(3233) | 1631225247929	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpyo_uwjpx.zip","temporary":false}]
[task 2021-09-09T22:07:28.741Z] 22:07:28     INFO - GECKO(3233) | 1631225248739	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-09-09T22:07:28.780Z] 22:07:28     INFO - GECKO(3233) | 1631225248779	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmp96wcjrf6.zip","temporary":false}]
[task 2021-09-09T22:07:28.972Z] 22:07:28     INFO - GECKO(3233) | 1631225248971	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2021-09-09T22:07:28.995Z] 22:07:28     INFO - GECKO(3233) | 1631225248994	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-09-09T22:07:28.997Z] 22:07:28     INFO - GECKO(3233) | 1631225248996	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2021-09-09T22:07:29.004Z] 22:07:29     INFO - GECKO(3233) | 1631225249003	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-09-09T22:07:29.007Z] 22:07:29     INFO - GECKO(3233) | 1631225249005	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2021-09-09T22:07:29.015Z] 22:07:29     INFO - GECKO(3233) | 1631225249013	Marionette	DEBUG	1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... hrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":1937,"filename":"tests/mochitest/runtests.py"}]
[task 2021-09-09T22:07:29.059Z] 22:07:29     INFO - GECKO(3233) | 1631225249058	Marionette	TRACE	[7] MarionetteCommands actor created for window id 2
[task 2021-09-09T22:07:29.296Z] 22:07:29     INFO - GECKO(3233) | 1631225249295	Marionette	TRACE	Received observer notification domwindowopened
[task 2021-09-09T22:07:29.347Z] 22:07:29     INFO - GECKO(3233) | 1631225249346	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2021-09-09T22:07:29.375Z] 22:07:29     INFO - GECKO(3233) | 1631225249374	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-09-09T22:07:29.381Z] 22:07:29     INFO - GECKO(3233) | 1631225249377	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2021-09-09T22:07:29.550Z] 22:07:29     INFO - GECKO(3233) | 1631225249549	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-09-09T22:07:29.561Z] 22:07:29     INFO - GECKO(3233) | 1631225249560	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2021-09-09T22:07:29.640Z] 22:07:29     INFO - GECKO(3233) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-09-09T22:07:29.642Z] 22:07:29     INFO - GECKO(3233) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-09-09T22:07:29.665Z] 22:07:29     INFO - runtests.py | Waiting for browser...
[task 2021-09-09T22:07:29.734Z] 22:07:29     INFO - GECKO(3233) | 1631225249732	Marionette	DEBUG	Closed connection 1
[task 2021-09-09T22:07:30.043Z] 22:07:30     INFO - GECKO(3233) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-09-09T22:07:31.147Z] 22:07:31     INFO - TEST-START | browser/components/uitour/test/browser_UITour4.js
Blocks: tsan
Component: Preferences → Graphics: WebRender
Keywords: csectype-race
Product: Firefox → Core
Group: core-security → gfx-core-security

This was first seen by fuzzers running m-c 20210827-ab80197101b9. Unsurprisingly we don't have a reliable test case since this is intermittent.

It looks like the off-main-thread code was added in bug 1722450, which is consistent with comment 1.

Keywords: regression
Regressed by: 1722450
Has Regression Range: --- → yes
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
Group: gfx-core-security
You need to log in before you can comment on or make changes to this bug.