Closed Bug 1894073 Opened 6 months ago Closed 5 months ago

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/mfbt/UniquePtrExtensions.cpp:28:10 in mozilla::detail::FileHandleDeleter::operator()(mozilla::detail::FileHandleHelper) | single tracking bug

Tracking

()

Status:

RESOLVED FIXED

Milestone:

129 Branch

Tracking Flags:

Tracking

Status

firefox129

---

fixed

People

(Reporter: intermittent-bug-filer, Assigned: gerard-majax)

References

Details

(Keywords: intermittent-failure, intermittent-testcase)

Attachments

(1 file)

Bug 1894073 - Suppress TSan false positive during an edge case in process launch. 5 months ago Jed Davis [:jld] ⟨⏰\|UTC-7⟩ ⟦he/him⟧ 48 bytes, text/x-phabricator-request		Details \| Review

Treeherder Bug Filer

Reporter

Description

•

6 months ago

treeherder

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

[task 2024-04-29T18:08:14.718Z] 18:08:14     INFO - TEST-START | browser/components/shell/test/browser_setDefaultPDFHandler.js
[task 2024-04-29T18:08:14.718Z] 18:08:14     INFO - TEST-SKIP | browser/components/shell/test/browser_setDefaultPDFHandler.js | took 0ms
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO - Running manifest: browser/base/content/test/performance/browser.toml
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO - The following extra prefs will be set:
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO -   browser.migration.version=9999999
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO -   browser.startup.record=true
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO -   gfx.canvas.willReadFrequently.enable=true
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO -   extensions.formautofill.addresses.available='on'
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO -   browser.urlbar.disableExtendForTests=true
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO -   browser.chrome.disableRemoteControlCueForTests=true
[task 2024-04-29T18:08:14.719Z] 18:08:14     INFO -   extensions.screenshots.disabled=false
[task 2024-04-29T18:08:14.855Z] 18:08:14     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2024-04-29T18:08:15.012Z] 18:08:15     INFO -  error: XDG_RUNTIME_DIR not set in the environment.
[task 2024-04-29T18:08:15.252Z] 18:08:15     INFO -  Setting pipeline to PAUSED ...
[task 2024-04-29T18:08:15.253Z] 18:08:15     INFO -  Pipeline is PREROLLING ...
[task 2024-04-29T18:08:15.257Z] 18:08:15     INFO -  Pipeline is PREROLLED ...
[task 2024-04-29T18:08:15.257Z] 18:08:15     INFO -  Setting pipeline to PLAYING ...
[task 2024-04-29T18:08:15.257Z] 18:08:15     INFO -  New clock: GstSystemClock
[task 2024-04-29T18:08:15.290Z] 18:08:15     INFO -  Got EOS from element "pipeline0".
[task 2024-04-29T18:08:15.290Z] 18:08:15     INFO -  Execution ended after 0:00:00.032993946
[task 2024-04-29T18:08:15.290Z] 18:08:15     INFO -  Setting pipeline to PAUSED ...
[task 2024-04-29T18:08:15.291Z] 18:08:15     INFO -  Setting pipeline to READY ...
[task 2024-04-29T18:08:15.293Z] 18:08:15     INFO -  (gst-launch-1.0:1013): GStreamer-CRITICAL **: 18:08:15.292: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2024-04-29T18:08:15.293Z] 18:08:15     INFO -  Setting pipeline to NULL ...
[task 2024-04-29T18:08:15.294Z] 18:08:15     INFO -  Freeing pipeline ...
[task 2024-04-29T18:08:15.669Z] 18:08:15     INFO - PID 1032 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2024-04-29T18:08:15.669Z] 18:08:15     INFO - 
[task 2024-04-29T18:08:15.683Z] 18:08:15     INFO - Increasing default timeout to 120 seconds (tsan)
[task 2024-04-29T18:08:15.683Z] 18:08:15     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2024-04-29T18:08:15.684Z] 18:08:15     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2024-04-29T18:08:15.687Z] 18:08:15     INFO - MochitestServer : launching ['/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-e', "const _PROFILE_PATH = '/tmp/tmpyuhu8ery.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = '/builds/worker/workspace/build/tests/bin/components';", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2024-04-29T18:08:15.687Z] 18:08:15     INFO - runtests.py | Server pid: 1033
[task 2024-04-29T18:08:15.690Z] 18:08:15     INFO - runtests.py | Websocket server pid: 1034
[task 2024-04-29T18:08:15.691Z] 18:08:15     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2024-04-29T18:08:15.705Z] 18:08:15     INFO - runtests.py | SSL tunnel pid: 1035
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - use http3 server: 0
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - runtests.py | Running with scheme: http
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - runtests.py | Running with e10s: True
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - runtests.py | Running with fission: True
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - runtests.py | Running with cross-origin iframes: False
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - runtests.py | Running tests: start.
[task 2024-04-29T18:08:16.112Z] 18:08:16     INFO - 
[task 2024-04-29T18:08:16.123Z] 18:08:16     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpyuhu8ery.mozrunner
[task 2024-04-29T18:08:16.143Z] 18:08:16     INFO - runtests.py | Application pid: 1060
[task 2024-04-29T18:08:16.143Z] 18:08:16     INFO - TEST-INFO | started process GECKO(1060)
[task 2024-04-29T18:08:18.732Z] 18:08:18     INFO - GECKO(1060) | 1714414098731	Marionette	INFO	Marionette enabled
[task 2024-04-29T18:08:18.743Z] 18:08:18     INFO - GECKO(1060) | 1714414098742	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-04-29T18:08:19.244Z] 18:08:19     INFO - GECKO(1060) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2024-04-29T18:08:19.502Z] 18:08:19     INFO - GECKO(1060) | 1714414099501	Marionette	INFO	Listening on port 2828
[task 2024-04-29T18:08:19.522Z] 18:08:19     INFO - GECKO(1060) | 1714414099520	Marionette	DEBUG	Marionette is listening
[task 2024-04-29T18:08:19.650Z] 18:08:19     INFO - GECKO(1060) | 1714414099649	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:55488
[task 2024-04-29T18:08:19.772Z] 18:08:19     INFO - GECKO(1060) | 1714414099771	Marionette	DEBUG	Closed connection 0
[task 2024-04-29T18:08:20.039Z] 18:08:20     INFO - GECKO(1060) | 1714414100038	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:55490
[task 2024-04-29T18:08:21.391Z] 18:08:21     INFO - GECKO(1060) | 1714414101388	Marionette	DEBUG	Closed connection 1
[task 2024-04-29T18:08:21.517Z] 18:08:21     INFO - GECKO(1060) | 1714414101516	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:55494
[task 2024-04-29T18:08:21.612Z] 18:08:21     INFO - GECKO(1060) | 1714414101611	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:55496
[task 2024-04-29T18:08:21.615Z] 18:08:21     INFO - GECKO(1060) | 1714414101613	Marionette	DEBUG	Closed connection 2
[task 2024-04-29T18:08:21.782Z] 18:08:21     INFO - GECKO(1060) | 1714414101781	Marionette	DEBUG	3 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-04-29T18:08:21.813Z] 18:08:21     INFO - GECKO(1060) | 1714414101812	Marionette	DEBUG	Waiting for initial application window
[task 2024-04-29T18:08:21.887Z] 18:08:21     INFO - GECKO(1060) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpyuhu8ery.mozrunner/runtests_leaks_tab_pid1154.log
[task 2024-04-29T18:08:22.936Z] 18:08:22     INFO - GECKO(1060) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpyuhu8ery.mozrunner/runtests_leaks_tab_pid1187.log
[task 2024-04-29T18:08:26.129Z] 18:08:26     INFO - GECKO(1060) | console.error: ({})
[task 2024-04-29T18:08:33.144Z] 18:08:33     INFO - GECKO(1060) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpyuhu8ery.mozrunner/runtests_leaks_tab_pid1242.log
[task 2024-04-29T18:08:33.378Z] 18:08:33     INFO - GECKO(1060) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpyuhu8ery.mozrunner/runtests_leaks_tab_pid1249.log
[task 2024-04-29T18:08:35.650Z] 18:08:35     INFO - GECKO(1060) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-04-29T18:08:35.681Z] 18:08:35     INFO - GECKO(1060) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-04-29T18:08:35.817Z] 18:08:35     INFO - GECKO(1060) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpyuhu8ery.mozrunner/runtests_leaks_utility_pid1294.log
[task 2024-04-29T18:08:35.987Z] 18:08:35     INFO - GECKO(1060) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpyuhu8ery.mozrunner/runtests_leaks_tab_pid1302.log
[task 2024-04-29T18:08:36.460Z] 18:08:36     INFO - GECKO(1060) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpyuhu8ery.mozrunner/runtests_leaks_tab_pid1324.log
[task 2024-04-29T18:08:36.834Z] 18:08:36     INFO - GECKO(1060) | ==================
[task 2024-04-29T18:08:36.835Z] 18:08:36     INFO - GECKO(1060) | WARNING: ThreadSanitizer: data race (pid=1060)
[task 2024-04-29T18:08:36.836Z] 18:08:36     INFO - GECKO(1060) |   Write of size 8 at 0x7bb000000f00 by main thread:
[task 2024-04-29T18:08:36.838Z] 18:08:36     INFO - GECKO(1060) |     #0 close /builds/worker/fetches/llvm-project/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:1816:3 (firefox-bin+0xcbbd2) (BuildId: 1576665b7dc497210df93fcbc55ef98086750dc7)
[task 2024-04-29T18:08:36.839Z] 18:08:36     INFO - GECKO(1060) |     #1 mozilla::detail::FileHandleDeleter::operator()(mozilla::detail::FileHandleHelper) /builds/worker/checkouts/gecko/mfbt/UniquePtrExtensions.cpp:28:10 (firefox-bin+0x213ce8) (BuildId: 1576665b7dc497210df93fcbc55ef98086750dc7)
[task 2024-04-29T18:08:36.840Z] 18:08:36     INFO - GECKO(1060) |     #2 reset /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:302:7 (libxul.so+0x3d8a805) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.841Z] 18:08:36     INFO - GECKO(1060) |     #3 ~UniquePtr /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:250:18 (libxul.so+0x3d8a805)
[task 2024-04-29T18:08:36.843Z] 18:08:36     INFO - GECKO(1060) |     #4 mozilla::ipc::SharedPreferenceSerializer::~SharedPreferenceSerializer() /builds/worker/checkouts/gecko/ipc/glue/ProcessUtils_common.cpp:28:1 (libxul.so+0x3d8a805)
[task 2024-04-29T18:08:36.843Z] 18:08:36     INFO - GECKO(1060) |     #5 operator() /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:460:5 (libxul.so+0x7626299) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.843Z] 18:08:36     INFO - GECKO(1060) |     #6 reset /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:302:7 (libxul.so+0x7626299)
[task 2024-04-29T18:08:36.844Z] 18:08:36     INFO - GECKO(1060) |     #7 operator= /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:272:5 (libxul.so+0x7626299)
[task 2024-04-29T18:08:36.846Z] 18:08:36     INFO - GECKO(1060) |     #8 mozilla::dom::ContentParent::LaunchSubprocessResolve(bool, mozilla::hal::ProcessPriority) /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:2683:19 (libxul.so+0x7626299)
[task 2024-04-29T18:08:36.847Z] 18:08:36     INFO - GECKO(1060) |     #9 mozilla::dom::ContentParent::WaitForLaunchSync(mozilla::hal::ProcessPriority) /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1191:7 (libxul.so+0x7625e02) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.851Z] 18:08:36     INFO - GECKO(1060) |     #10 GetNewOrUsedBrowserProcess /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1133:41 (libxul.so+0x7626fe3) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.853Z] 18:08:36     INFO - GECKO(1060) |     #11 mozilla::dom::ContentParent::CreateBrowser(mozilla::dom::TabContext const&, mozilla::dom::Element*, nsTSubstring<char> const&, mozilla::dom::BrowsingContext*, mozilla::dom::ContentParent*) /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1449:25 (libxul.so+0x7626fe3)
[task 2024-04-29T18:08:36.853Z] 18:08:36     INFO - GECKO(1060) |     #12 nsFrameLoader::TryRemoteBrowserInternal() /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2686:9 (libxul.so+0x4c45542) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.854Z] 18:08:36     INFO - GECKO(1060) |     #13 TryRemoteBrowser /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2751:7 (libxul.so+0x4c3c4e2) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.855Z] 18:08:36     INFO - GECKO(1060) |     #14 EnsureRemoteBrowser /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2487:28 (libxul.so+0x4c3c4e2)
[task 2024-04-29T18:08:36.857Z] 18:08:36     INFO - GECKO(1060) |     #15 nsFrameLoader::ShowRemoteFrame(mozilla::gfx::IntSizeTyped<mozilla::ScreenPixel> const&, nsSubDocumentFrame*) /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:1085:8 (libxul.so+0x4c3c4e2)
[task 2024-04-29T18:08:36.857Z] 18:08:36     INFO - GECKO(1060) |     #16 nsFrameLoader::Show(nsSubDocumentFrame*) /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:950:12 (libxul.so+0x4c3daf6) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.859Z] 18:08:36     INFO - GECKO(1060) |     #17 nsSubDocumentFrame::ShowViewer() /builds/worker/checkouts/gecko/layout/generic/nsSubDocumentFrame.cpp:198:38 (libxul.so+0x844deb2) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.859Z] 18:08:36     INFO - GECKO(1060) |     #18 AsyncFrameInit::Run() /builds/worker/checkouts/gecko/layout/generic/nsSubDocumentFrame.cpp:110:60 (libxul.so+0x848fb17) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.860Z] 18:08:36     INFO - GECKO(1060) |     #19 nsContentUtils::RemoveScriptBlocker() /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:6176:17 (libxul.so+0x490050d) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.862Z] 18:08:36     INFO - GECKO(1060) |     #20 ~nsAutoScriptBlocker /builds/worker/checkouts/gecko/dom/base/nsContentUtils.h:3779:28 (libxul.so+0x81e25b8) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.863Z] 18:08:36     INFO - GECKO(1060) |     #21 mozilla::PresShell::DoFlushPendingNotifications(mozilla::ChangesToFlush) /builds/worker/checkouts/gecko/layout/base/PresShell.cpp:4421:5 (libxul.so+0x81e25b8)
[task 2024-04-29T18:08:36.865Z] 18:08:36     INFO - GECKO(1060) |     #22 FlushPendingNotifications /builds/worker/workspace/obj-build/dist/include/mozilla/PresShell.h:1480:5 (libxul.so+0x4aabc2e) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.865Z] 18:08:36     INFO - GECKO(1060) |     #23 mozilla::dom::Document::FlushPendingNotifications(mozilla::ChangesToFlush) /builds/worker/checkouts/gecko/dom/base/Document.cpp:10947:16 (libxul.so+0x4aabc2e)
[task 2024-04-29T18:08:36.868Z] 18:08:36     INFO - GECKO(1060) |     #24 mozilla::dom::Document::FlushPendingNotifications(mozilla::FlushType) /builds/worker/checkouts/gecko/dom/base/Document.cpp:10879:3 (libxul.so+0x4a8fb78) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.868Z] 18:08:36     INFO - GECKO(1060) |     #25 nsFrameLoader::TryRemoteBrowserInternal() /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2518:10 (libxul.so+0x4c44e3a) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.868Z] 18:08:36     INFO - GECKO(1060) |     #26 TryRemoteBrowser /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2751:7 (libxul.so+0x4c46abe) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.870Z] 18:08:36     INFO - GECKO(1060) |     #27 EnsureRemoteBrowser /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:2487:28 (libxul.so+0x4c46abe)
[task 2024-04-29T18:08:36.870Z] 18:08:36     INFO - GECKO(1060) |     #28 nsFrameLoader::GetBrowsingContext() /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3436:17 (libxul.so+0x4c46abe)
[task 2024-04-29T18:08:36.871Z] 18:08:36     INFO - GECKO(1060) |     #29 nsFrameLoader::GetLoadContext() /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3430:20 (libxul.so+0x4c498e5) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.873Z] 18:08:36     INFO - GECKO(1060) |     #30 mozilla::dom::FrameLoader_Binding::get_loadContext(JSContext*, JS::Handle<JSObject*>, void*, JSJitGetterCallArgs) /builds/worker/workspace/obj-build/dom/bindings/./FrameLoaderBinding.cpp:382:67 (libxul.so+0x5b159a4) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.875Z] 18:08:36     INFO - GECKO(1060) |     #31 bool mozilla::dom::binding_detail::GenericGetter<mozilla::dom::binding_detail::NormalThisPolicy, mozilla::dom::binding_detail::ThrowExceptions>(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/dom/bindings/BindingUtils.cpp:3150:13 (libxul.so+0x5c8dba6) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.875Z] 18:08:36     INFO - GECKO(1060) |     #32 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:479:13 (libxul.so+0x9ec7f89) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.878Z] 18:08:36     INFO - GECKO(1060) |     #33 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:573:12 (libxul.so+0x9ec7f89)
[task 2024-04-29T18:08:36.878Z] 18:08:36     INFO - GECKO(1060) |     #34 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:640:10 (libxul.so+0x9ec8cb7) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.879Z] 18:08:36     INFO - GECKO(1060) |     #35 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:672:8 (libxul.so+0x9ec8cb7)
[task 2024-04-29T18:08:36.881Z] 18:08:36     INFO - GECKO(1060) |     #36 js::CallGetter(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:794:10 (libxul.so+0x9ec9a3f) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.881Z] 18:08:36     INFO - GECKO(1060) |     #37 CallGetter /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2143:12 (libxul.so+0xa09ec7f) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.884Z] 18:08:36     INFO - GECKO(1060) |     #38 GetExistingProperty<(js::AllowGC)1> /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2171:12 (libxul.so+0xa09ec7f)
[task 2024-04-29T18:08:36.885Z] 18:08:36     INFO - GECKO(1060) |     #39 NativeGetPropertyInline<(js::AllowGC)1> /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2319:14 (libxul.so+0xa09ec7f)
[task 2024-04-29T18:08:36.885Z] 18:08:36     INFO - GECKO(1060) |     #40 js::NativeGetProperty(JSContext*, JS::Handle<js::NativeObject*>, JS::Handle<JS::Value>, JS::Handle<JS::PropertyKey>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2350:10 (libxul.so+0xa09ec7f)
[task 2024-04-29T18:08:36.885Z] 18:08:36     INFO - GECKO(1060) |     #41 GetProperty /builds/worker/checkouts/gecko/js/src/vm/ObjectOperations-inl.h:117:10 (libxul.so+0x9ee955b) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.888Z] 18:08:36     INFO - GECKO(1060) |     #42 GetProperty /builds/worker/checkouts/gecko/js/src/vm/ObjectOperations-inl.h:124:10 (libxul.so+0x9ee955b)
[task 2024-04-29T18:08:36.888Z] 18:08:36     INFO - GECKO(1060) |     #43 js::GetProperty(JSContext*, JS::Handle<JS::Value>, JS::Handle<js::PropertyName*>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:4504:10 (libxul.so+0x9ee955b)
[task 2024-04-29T18:08:36.888Z] 18:08:36     INFO - GECKO(1060) |     #44 GetPropertyOperation /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:245:10 (libxul.so+0x9ed51b3) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.891Z] 18:08:36     INFO - GECKO(1060) |     #45 js::Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:2715:12 (libxul.so+0x9ed51b3)
[task 2024-04-29T18:08:36.891Z] 18:08:36     INFO - GECKO(1060) |     #46 MaybeEnterInterpreterTrampoline /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:393:10 (libxul.so+0x9ec7576) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.893Z] 18:08:36     INFO - GECKO(1060) |     #47 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:451:13 (libxul.so+0x9ec7576)
[task 2024-04-29T18:08:36.893Z] 18:08:36     INFO - GECKO(1060) |     #48 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:605:13 (libxul.so+0x9ec8056) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.895Z] 18:08:36     INFO - GECKO(1060) |     #49 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:640:10 (libxul.so+0x9ec8cb7) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.895Z] 18:08:36     INFO - GECKO(1060) |     #50 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:672:8 (libxul.so+0x9ec8cb7)
[task 2024-04-29T18:08:36.897Z] 18:08:36     INFO - GECKO(1060) |     #51 js::CallGetter(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:794:10 (libxul.so+0x9ec9a3f) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.897Z] 18:08:36     INFO - GECKO(1060) |     #52 CallGetter /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2143:12 (libxul.so+0xa09ec7f) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.899Z] 18:08:36     INFO - GECKO(1060) |     #53 GetExistingProperty<(js::AllowGC)1> /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2171:12 (libxul.so+0xa09ec7f)
[task 2024-04-29T18:08:36.900Z] 18:08:36     INFO - GECKO(1060) |     #54 NativeGetPropertyInline<(js::AllowGC)1> /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2319:14 (libxul.so+0xa09ec7f)
[task 2024-04-29T18:08:36.901Z] 18:08:36     INFO - GECKO(1060) |     #55 js::NativeGetProperty(JSContext*, JS::Handle<js::NativeObject*>, JS::Handle<JS::Value>, JS::Handle<JS::PropertyKey>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/NativeObject.cpp:2350:10 (libxul.so+0xa09ec7f)
[task 2024-04-29T18:08:36.903Z] 18:08:36     INFO - GECKO(1060) |     #56 GetProperty /builds/worker/checkouts/gecko/js/src/vm/ObjectOperations-inl.h:117:10 (libxul.so+0x9ee955b) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.904Z] 18:08:36     INFO - GECKO(1060) |     #57 GetProperty /builds/worker/checkouts/gecko/js/src/vm/ObjectOperations-inl.h:124:10 (libxul.so+0x9ee955b)
[task 2024-04-29T18:08:36.905Z] 18:08:36     INFO - GECKO(1060) |     #58 js::GetProperty(JSContext*, JS::Handle<JS::Value>, JS::Handle<js::PropertyName*>, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:4504:10 (libxul.so+0x9ee955b)
[task 2024-04-29T18:08:36.906Z] 18:08:36     INFO - GECKO(1060) |     #59 GetPropertyOperation /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:245:10 (libxul.so+0x9ed51b3) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.907Z] 18:08:36     INFO - GECKO(1060) |     #60 js::Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:2715:12 (libxul.so+0x9ed51b3)
[task 2024-04-29T18:08:36.908Z] 18:08:36     INFO - GECKO(1060) |     #61 MaybeEnterInterpreterTrampoline /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:393:10 (libxul.so+0x9ec7576) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.909Z] 18:08:36     INFO - GECKO(1060) |     #62 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:451:13 (libxul.so+0x9ec7576)
[task 2024-04-29T18:08:36.910Z] 18:08:36     INFO - GECKO(1060) |     #63 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:605:13 (libxul.so+0x9ec8056) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.911Z] 18:08:36     INFO - GECKO(1060) |     #64 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:640:10 (libxul.so+0x9ec8cb7) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.912Z] 18:08:36     INFO - GECKO(1060) |     #65 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:672:8 (libxul.so+0x9ec8cb7)
[task 2024-04-29T18:08:36.914Z] 18:08:36     INFO - GECKO(1060) |     #66 JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CallAndConstruct.cpp:119:10 (libxul.so+0x9f927b3) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.915Z] 18:08:36     INFO - GECKO(1060) |     #67 mozilla::dom::LifecycleConnectedCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/./CustomElementRegistryBinding.cpp:1003:8 (libxul.so+0x58ff1d3) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.916Z] 18:08:36     INFO - GECKO(1060) |     #68 void mozilla::dom::LifecycleConnectedCallback::Call<RefPtr<mozilla::dom::Element>>(RefPtr<mozilla::dom::Element> const&, mozilla::ErrorResult&, char const*, mozilla::dom::CallbackObject::ExceptionHandling, JS::Realm*) /builds/worker/workspace/obj-build/dist/include/mozilla/dom/CustomElementRegistryBinding.h:942:12 (libxul.so+0x4a1daf8) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.918Z] 18:08:36     INFO - GECKO(1060) |     #69 Call<RefPtr<mozilla::dom::Element> > /builds/worker/workspace/obj-build/dist/include/mozilla/dom/CustomElementRegistryBinding.h:964:12 (libxul.so+0x49e9b9e) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.919Z] 18:08:36     INFO - GECKO(1060) |     #70 mozilla::dom::CustomElementCallback::Call() /builds/worker/checkouts/gecko/dom/base/CustomElementRegistry.cpp:219:13 (libxul.so+0x49e9b9e)
[task 2024-04-29T18:08:36.920Z] 18:08:36     INFO - GECKO(1060) |     #71 mozilla::dom::CustomElementCallbackReaction::Invoke(mozilla::dom::Element*, mozilla::ErrorResult&) /builds/worker/checkouts/gecko/dom/base/CustomElementRegistry.cpp:117:29 (libxul.so+0x4a1097f) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.930Z] 18:08:36     INFO - GECKO(1060) |     #72 mozilla::dom::CustomElementReactionsStack::InvokeReactions(AutoTArray<RefPtr<mozilla::dom::Element>, 3ul>*, nsIGlobalObject*) /builds/worker/checkouts/gecko/dom/base/CustomElementRegistry.cpp:1586:19 (libxul.so+0x49f1b3e) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.930Z] 18:08:36     INFO - GECKO(1060) |     #73 mozilla::dom::CustomElementReactionsStack::PopAndInvokeElementQueue() /builds/worker/checkouts/gecko/dom/base/CustomElementRegistry.cpp:1478:5 (libxul.so+0x49f182b) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.930Z] 18:08:36     INFO - GECKO(1060) |     #74 LeaveCEReactions /builds/worker/workspace/obj-build/dist/include/mozilla/dom/CustomElementRegistry.h:294:7 (libxul.so+0x41aa116) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.930Z] 18:08:36     INFO - GECKO(1060) |     #75 mozilla::dom::AutoCEReaction::~AutoCEReaction() /builds/worker/workspace/obj-build/dist/include/mozilla/dom/CustomElementRegistry.h:586:22 (libxul.so+0x41aa116)
[task 2024-04-29T18:08:36.930Z] 18:08:36     INFO - GECKO(1060) |     #76 ~MaybeStorage /builds/worker/workspace/obj-build/dist/include/mozilla/Maybe.h:274:25 (libxul.so+0x501f4d6) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:36.930Z] 18:08:36     INFO - GECKO(1060) |     #77 mozilla::dom::Node_Binding::appendChild(JSContext*, JS::Handle<JSObject*>, void*, JSJitMethodCallArgs const&) /builds/worker/workspace/obj-build/dom/bindings/./NodeBinding.cpp:969:1 (libxul.so+0x501f4d6)
<...>
[task 2024-04-29T18:08:37.013Z] 18:08:37     INFO - GECKO(1060) |     #28 nsAppStartup::Run() /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:296:30 (libxul.so+0x9bdb9d2) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.013Z] 18:08:37     INFO - GECKO(1060) |     #29 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5746:22 (libxul.so+0x9d49e90) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.014Z] 18:08:37     INFO - GECKO(1060) |     #30 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5958:8 (libxul.so+0x9d4aaf0) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.015Z] 18:08:37     INFO - GECKO(1060) |     #31 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:6015:21 (libxul.so+0x9d4b1a1) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.015Z] 18:08:37     INFO - GECKO(1060) |     #32 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x9d5a1f2) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.016Z] 18:08:37     INFO - GECKO(1060) |     #33 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:227:22 (firefox-bin+0x14ea00) (BuildId: 1576665b7dc497210df93fcbc55ef98086750dc7)
[task 2024-04-29T18:08:37.016Z] 18:08:37     INFO - GECKO(1060) |     #34 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:445:16 (firefox-bin+0x14ea00)
[task 2024-04-29T18:08:37.017Z] 18:08:37     INFO - GECKO(1060) |   Thread T41 'IPC Launch' (tid=1116, running) created by main thread at:
[task 2024-04-29T18:08:37.017Z] 18:08:37     INFO - GECKO(1060) |     #0 pthread_create /builds/worker/fetches/llvm-project/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:1020:3 (firefox-bin+0xc59bb) (BuildId: 1576665b7dc497210df93fcbc55ef98086750dc7)
[task 2024-04-29T18:08:37.018Z] 18:08:37     INFO - GECKO(1060) |     #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x42cee) (BuildId: bc31c74a45b1d0905acafb431fb8165a84a87c38)
[task 2024-04-29T18:08:37.018Z] 18:08:37     INFO - GECKO(1060) |     #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x37f84) (BuildId: bc31c74a45b1d0905acafb431fb8165a84a87c38)
[task 2024-04-29T18:08:37.019Z] 18:08:37     INFO - GECKO(1060) |     #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:620:20 (libxul.so+0x3223757) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.019Z] 18:08:37     INFO - GECKO(1060) |     #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, nsIThreadManager::ThreadCreationOptions, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:602:22 (libxul.so+0x322b7fd) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.020Z] 18:08:37     INFO - GECKO(1060) |     #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, already_AddRefed<nsIRunnable>, nsIThreadManager::ThreadCreationOptions) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:176:57 (libxul.so+0x32344b3) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.021Z] 18:08:37     INFO - GECKO(1060) |     #6 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, nsIRunnable*, nsIThreadManager::ThreadCreationOptions) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:168:10 (libxul.so+0x322eb9c) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.021Z] 18:08:37     INFO - GECKO(1060) |     #7 mozilla::ipc::GetIPCLauncher() /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:1018:19 (libxul.so+0x3d57a73) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.022Z] 18:08:37     INFO - GECKO(1060) |     #8 mozilla::ipc::BaseProcessLauncher::BaseProcessLauncher(mozilla::ipc::GeckoChildProcessHost*, std::vector<std::string, std::allocator<std::string>>&&) /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:179:45 (libxul.so+0x3d619a5) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.026Z] 18:08:37     INFO - GECKO(1060) |     #9 PosixProcessLauncher /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:286:9 (libxul.so+0x3d55fc6) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.026Z] 18:08:37     INFO - GECKO(1060) |     #10 LinuxProcessLauncher /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:349:9 (libxul.so+0x3d55fc6)
[task 2024-04-29T18:08:37.027Z] 18:08:37     INFO - GECKO(1060) |     #11 mozilla::ipc::GeckoChildProcessHost::AsyncLaunch(std::vector<std::string, std::allocator<std::string>>) /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:744:11 (libxul.so+0x3d55fc6)
[task 2024-04-29T18:08:37.027Z] 18:08:37     INFO - GECKO(1060) |     #12 mozilla::ipc::GeckoChildProcessHost::LaunchAndWaitForProcessHandle(std::vector<std::string, std::allocator<std::string>>) /builds/worker/checkouts/gecko/ipc/glue/GeckoChildProcessHost.cpp:908:8 (libxul.so+0x3d53191) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.027Z] 18:08:37     INFO - GECKO(1060) |     #13 mozilla::ipc::ForkServiceChild::StartForkServer() /builds/worker/checkouts/gecko/ipc/glue/ForkServiceChild.cpp:56:20 (libxul.so+0x3d52e1d) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.027Z] 18:08:37     INFO - GECKO(1060) |     #14 mozilla::ipc::ForkServerLauncher::Observe(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/ipc/glue/ForkServiceChild.cpp:160:7 (libxul.so+0x3d541d1) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.028Z] 18:08:37     INFO - GECKO(1060) |     #15 NotifyObservers /builds/worker/checkouts/gecko/xpcom/ds/nsObserverList.cpp:71:19 (libxul.so+0x317f285) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.028Z] 18:08:37     INFO - GECKO(1060) |     #16 nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/xpcom/ds/nsObserverService.cpp:289:19 (libxul.so+0x317f285)
[task 2024-04-29T18:08:37.028Z] 18:08:37     INFO - GECKO(1060) |     #17 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5642:21 (libxul.so+0x9d49ad8) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.029Z] 18:08:37     INFO - GECKO(1060) |     #18 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5958:8 (libxul.so+0x9d4aaf0) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.030Z] 18:08:37     INFO - GECKO(1060) |     #19 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:6015:21 (libxul.so+0x9d4b1a1) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.030Z] 18:08:37     INFO - GECKO(1060) |     #20 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x9d5a1f2) (BuildId: 76b8fbf8e47e472688e49b24e87a4ba12392ebfc)
[task 2024-04-29T18:08:37.030Z] 18:08:37     INFO - GECKO(1060) |     #21 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:227:22 (firefox-bin+0x14ea00) (BuildId: 1576665b7dc497210df93fcbc55ef98086750dc7)
[task 2024-04-29T18:08:37.030Z] 18:08:37     INFO - GECKO(1060) |     #22 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:445:16 (firefox-bin+0x14ea00)
[task 2024-04-29T18:08:37.030Z] 18:08:37     INFO - GECKO(1060) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/mfbt/UniquePtrExtensions.cpp:28:10 in mozilla::detail::FileHandleDeleter::operator()(mozilla::detail::FileHandleHelper)
[task 2024-04-29T18:08:37.030Z] 18:08:37     INFO - GECKO(1060) | ==================
[task 2024-04-29T18:08:37.031Z] 18:08:37     INFO - GECKO(1060) | Exiting due to channel error.
[task 2024-04-29T18:08:37.031Z] 18:08:37     INFO - GECKO(1060) | Exiting due to channel error.
[task 2024-04-29T18:08:37.032Z] 18:08:37     INFO - GECKO(1060) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: CompositorBridgeChild receives IPC close with reason=AbnormalShutdown (t=17.739) Exiting due to channel error.
[task 2024-04-29T18:08:37.032Z] 18:08:37     INFO - GECKO(1060) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: CompositorBridgeChild receives IPC close with reason=AbnormalShutdown (t=17.743) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: CompositorBridgeChild receives IPC close with reason=AbnormalShutdown (t=17.743) Exiting due to channel error.
[task 2024-04-29T18:08:37.033Z] 18:08:37     INFO - GECKO(1060) | Exiting due to channel error.
[task 2024-04-29T18:08:37.034Z] 18:08:37     INFO - GECKO(1060) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: CompositorBridgeChild receives IPC close with reason=AbnormalShutdown (t=17.759) Exiting due to channel error.
[task 2024-04-29T18:08:37.954Z] 18:08:37     INFO - GECKO(1060) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: CompositorBridgeChild receives IPC close with reason=AbnormalShutdown (t=17.759)
[task 2024-04-29T18:08:37.955Z] 18:08:37     INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2024-04-29T18:08:37.955Z] 18:08:37     INFO - Buffered messages finished
[task 2024-04-29T18:08:37.955Z] 18:08:37     INFO - runtests.py | Application ran for: 0:00:21.830469
[task 2024-04-29T18:08:37.956Z] 18:08:37     INFO - zombiecheck | Reading PID log: /tmp/tmpkv7uh6dkpidlog
[task 2024-04-29T18:08:37.962Z] 18:08:37     INFO - ==> process 1060 launched child process 1117
[task 2024-04-29T18:08:37.962Z] 18:08:37     INFO - ==> process 1117 launched child process 1154
[task 2024-04-29T18:08:37.962Z] 18:08:37     INFO - ==> process 1117 launched child process 1187
[task 2024-04-29T18:08:37.963Z] 18:08:37     INFO - ==> process 1117 launched child process 1242
[task 2024-04-29T18:08:37.963Z] 18:08:37     INFO - ==> process 1117 launched child process 1249
[task 2024-04-29T18:08:37.963Z] 18:08:37     INFO - ==> process 1117 launched child process 1294
[task 2024-04-29T18:08:37.963Z] 18:08:37     INFO - ==> process 1117 launched child process 1302
[task 2024-04-29T18:08:37.964Z] 18:08:37     INFO - ==> process 1117 launched child process 1324
[task 2024-04-29T18:08:37.964Z] 18:08:37     INFO - zombiecheck | Checking for orphan process with PID: 1249
[task 2024-04-29T18:08:37.964Z] 18:08:37     INFO - zombiecheck | Checking for orphan process with PID: 1154
[task 2024-04-29T18:08:37.965Z] 18:08:37     INFO - zombiecheck | Checking for orphan process with PID: 1187
[task 2024-04-29T18:08:37.965Z] 18:08:37     INFO - zombiecheck | Checking for orphan process with PID: 1324
[task 2024-04-29T18:08:37.965Z] 18:08:37     INFO - zombiecheck | Checking for orphan process with PID: 1294
[task 2024-04-29T18:08:37.966Z] 18:08:37     INFO - zombiecheck | Checking for orphan process with PID: 1302
[task 2024-04-29T18:08:37.966Z] 18:08:37     INFO - zombiecheck | Checking for orphan process with PID: 1242
[task 2024-04-29T18:08:37.967Z] 18:08:37     INFO - zombiecheck | Checking for orphan process with PID: 1117
[task 2024-04-29T18:08:37.967Z] 18:08:37     INFO -  Traceback (most recent call last):
[task 2024-04-29T18:08:37.967Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 3784, in doTests
[task 2024-04-29T18:08:37.968Z] 18:08:37     INFO -      ret, _ = self.runApp(
[task 2024-04-29T18:08:37.968Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 3043, in runApp
[task 2024-04-29T18:08:37.968Z] 18:08:37     INFO -      six.reraise(exc, value, tb)
[task 2024-04-29T18:08:37.969Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/six.py", line 696, in reraise
[task 2024-04-29T18:08:37.969Z] 18:08:37     INFO -      raise value
[task 2024-04-29T18:08:37.970Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2902, in runApp
[task 2024-04-29T18:08:37.970Z] 18:08:37     INFO -      self.marionette.start_session()
[task 2024-04-29T18:08:37.970Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 34, in _
[task 2024-04-29T18:08:37.971Z] 18:08:37     INFO -      m._handle_socket_failure()
[task 2024-04-29T18:08:37.971Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 820, in _handle_socket_failure
[task 2024-04-29T18:08:37.971Z] 18:08:37     INFO -      reraise(exc_cls, exc, tb)
[task 2024-04-29T18:08:37.972Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/six.py", line 696, in reraise
[task 2024-04-29T18:08:37.972Z] 18:08:37     INFO -      raise value
[task 2024-04-29T18:08:37.972Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2024-04-29T18:08:37.973Z] 18:08:37     INFO -      return func(*args, **kwargs)
[task 2024-04-29T18:08:37.973Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 1374, in start_session
[task 2024-04-29T18:08:37.973Z] 18:08:37     INFO -      resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2024-04-29T18:08:37.974Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 34, in _
[task 2024-04-29T18:08:37.974Z] 18:08:37     INFO -      m._handle_socket_failure()
[task 2024-04-29T18:08:37.974Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 820, in _handle_socket_failure
[task 2024-04-29T18:08:37.975Z] 18:08:37     INFO -      reraise(exc_cls, exc, tb)
[task 2024-04-29T18:08:37.975Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/six.py", line 696, in reraise
[task 2024-04-29T18:08:37.975Z] 18:08:37     INFO -      raise value
[task 2024-04-29T18:08:37.976Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2024-04-29T18:08:37.976Z] 18:08:37     INFO -      return func(*args, **kwargs)
[task 2024-04-29T18:08:37.976Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 769, in _send_message
[task 2024-04-29T18:08:37.977Z] 18:08:37     INFO -      msg = self.client.request(name, params)
[task 2024-04-29T18:08:37.977Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/transport.py", line 382, in request
[task 2024-04-29T18:08:37.977Z] 18:08:37     INFO -      return self.receive()
[task 2024-04-29T18:08:37.978Z] 18:08:37     INFO -    File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/transport.py", line 229, in receive
[task 2024-04-29T18:08:37.978Z] 18:08:37     INFO -      raise socket.error("No data received over socket")
[task 2024-04-29T18:08:37.978Z] 18:08:37     INFO -  OSError: No data received over socket
[task 2024-04-29T18:08:37.979Z] 18:08:37    ERROR - Automation Error: Received unexpected exception while running application
[task 2024-04-29T18:08:37.979Z] 18:08:37    ERROR - 
[task 2024-04-29T18:08:37.986Z] 18:08:37     INFO - Stopping web server
[task 2024-04-29T18:08:37.996Z] 18:08:37     INFO - Server shut down.
[task 2024-04-29T18:08:37.996Z] 18:08:37     INFO - Web server killed.
[task 2024-04-29T18:08:37.996Z] 18:08:37     INFO - Stopping web socket server
[task 2024-04-29T18:08:37.996Z] 18:08:37     INFO - Stopping ssltunnel
[task 2024-04-29T18:08:37.996Z] 18:08:37     INFO - Stopping gst for v4l2loopback
[task 2024-04-29T18:08:37.996Z] 18:08:37     INFO - runtests.py | Running tests: end.
[task 2024-04-29T18:08:38.033Z] 18:08:38     INFO - Buffered messages finished
[task 2024-04-29T18:08:38.034Z] 18:08:38     INFO - Running manifest: browser/components/enterprisepolicies/tests/browser/disable_forget_button/browser.toml
[task 2024-04-29T18:08:38.035Z] 18:08:38     INFO - The following extra prefs will be set:
[task 2024-04-29T18:08:38.035Z] 18:08:38     INFO -   browser.policies.alternatePath='<test-root>/browser/components/enterprisepolicies/tests/browser/disable_forget_button/forget_button.json'
[task 2024-04-29T18:08:38.104Z] 18:08:38     INFO -  Setting pipeline to PAUSED ...
[task 2024-04-29T18:08:38.105Z] 18:08:38     INFO -  Pipeline is PREROLLING ...
[task 2024-04-29T18:08:38.107Z] 18:08:38     INFO -  Pipeline is PREROLLED ...
[task 2024-04-29T18:08:38.108Z] 18:08:38     INFO -  Setting pipeline to PLAYING ...
[task 2024-04-29T18:08:38.108Z] 18:08:38     INFO -  New clock: GstSystemClock
[task 2024-04-29T18:08:38.128Z] 18:08:38     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2024-04-29T18:08:38.142Z] 18:08:38     INFO -  Got EOS from element "pipeline0".
[task 2024-04-29T18:08:38.142Z] 18:08:38     INFO -  Execution ended after 0:00:00.033473297
[task 2024-04-29T18:08:38.143Z] 18:08:38     INFO -  Setting pipeline to PAUSED ...
[task 2024-04-29T18:08:38.143Z] 18:08:38     INFO -  Setting pipeline to READY ...
[task 2024-04-29T18:08:38.145Z] 18:08:38     INFO -  (gst-launch-1.0:1366): GStreamer-CRITICAL **: 18:08:38.144: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2024-04-29T18:08:38.146Z] 18:08:38     INFO -  Setting pipeline to NULL ...
[task 2024-04-29T18:08:38.146Z] 18:08:38     INFO -  Freeing pipeline ...
[task 2024-04-29T18:08:38.921Z] 18:08:38     INFO - PID 1375 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2024-04-29T18:08:38.921Z] 18:08:38     INFO - 
[task 2024-04-29T18:08:38.947Z] 18:08:38     INFO - Increasing default timeout to 120 seconds (tsan)
[task 2024-04-29T18:08:38.949Z] 18:08:38     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2024-04-29T18:08:38.950Z] 18:08:38     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2024-04-29T18:08:38.953Z] 18:08:38     INFO - MochitestServer : launching ['/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-e', "const _PROFILE_PATH = '/tmp/tmp026_e454.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = '/builds/worker/workspace/build/tests/bin/components';", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2024-04-29T18:08:38.954Z] 18:08:38     INFO - runtests.py | Server pid: 1376
[task 2024-04-29T18:08:38.958Z] 18:08:38     INFO - runtests.py | Websocket server pid: 1377
[task 2024-04-29T18:08:38.960Z] 18:08:38     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2024-04-29T18:08:38.967Z] 18:08:38     INFO - runtests.py | SSL tunnel pid: 1378
[task 2024-04-29T18:08:39.371Z] 18:08:39     INFO - use http3 server: 0
[task 2024-04-29T18:08:39.372Z] 18:08:39     INFO - runtests.py | Running with scheme: http
[task 2024-04-29T18:08:39.372Z] 18:08:39     INFO - runtests.py | Running with e10s: True
[task 2024-04-29T18:08:39.372Z] 18:08:39     INFO - runtests.py | Running with fission: True
[task 2024-04-29T18:08:39.372Z] 18:08:39     INFO - runtests.py | Running with cross-origin iframes: False
[task 2024-04-29T18:08:39.372Z] 18:08:39     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2024-04-29T18:08:39.372Z] 18:08:39     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2024-04-29T18:08:39.372Z] 18:08:39     INFO - runtests.py | Running tests: start.
[task 2024-04-29T18:08:39.372Z] 18:08:39     INFO - 
[task 2024-04-29T18:08:39.390Z] 18:08:39     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmp026_e454.mozrunner
[task 2024-04-29T18:08:39.410Z] 18:08:39     INFO - runtests.py | Application pid: 1405
[task 2024-04-29T18:08:39.410Z] 18:08:39     INFO - TEST-INFO | started process GECKO(1405)
[task 2024-04-29T18:08:42.008Z] 18:08:42     INFO - GECKO(1405) | 1714414122007	Marionette	INFO	Marionette enabled
[task 2024-04-29T18:08:42.020Z] 18:08:42     INFO - GECKO(1405) | 1714414122019	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-04-29T18:08:42.462Z] 18:08:42     INFO - GECKO(1405) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2024-04-29T18:08:42.689Z] 18:08:42     INFO - GECKO(1405) | 1714414122688	Marionette	INFO	Listening on port 2828
[task 2024-04-29T18:08:42.706Z] 18:08:42     INFO - GECKO(1405) | 1714414122703	Marionette	DEBUG	Marionette is listening
[task 2024-04-29T18:08:42.803Z] 18:08:42     INFO - GECKO(1405) | 1714414122802	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:55556
[task 2024-04-29T18:08:42.933Z] 18:08:42     INFO - GECKO(1405) | 1714414122932	Marionette	DEBUG	Closed connection 0
[task 2024-04-29T18:08:43.190Z] 18:08:43     INFO - GECKO(1405) | 1714414123189	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:55558
[task 2024-04-29T18:08:44.630Z] 18:08:44     INFO - GECKO(1405) | 1714414124629	Marionette	DEBUG	Closed connection 1
[task 2024-04-29T18:08:44.654Z] 18:08:44     INFO - GECKO(1405) | 1714414124653	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:55560
[task 2024-04-29T18:08:44.748Z] 18:08:44     INFO - GECKO(1405) | 1714414124747	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:55562
[task 2024-04-29T18:08:44.753Z] 18:08:44     INFO - GECKO(1405) | 1714414124749	Marionette	DEBUG	Closed connection 2
[task 2024-04-29T18:08:44.930Z] 18:08:44     INFO - GECKO(1405) | 1714414124929	Marionette	DEBUG	3 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-04-29T18:08:44.962Z] 18:08:44     INFO - GECKO(1405) | 1714414124961	Marionette	DEBUG	Waiting for initial application window
[task 2024-04-29T18:08:45.035Z] 18:08:45     INFO - GECKO(1405) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp026_e454.mozrunner/runtests_leaks_tab_pid1470.log
[task 2024-04-29T18:08:46.149Z] 18:08:46     INFO - GECKO(1405) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp026_e454.mozrunner/runtests_leaks_tab_pid1502.log
[task 2024-04-29T18:08:49.135Z] 18:08:49     INFO - GECKO(1405) | console.error: ({})
[task 2024-04-29T18:08:55.170Z] 18:08:55     INFO - GECKO(1405) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp026_e454.mozrunner/runtests_leaks_tab_pid1542.log
[task 2024-04-29T18:08:55.512Z] 18:08:55     INFO - GECKO(1405) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp026_e454.mozrunner/runtests_leaks_tab_pid1560.log
[task 2024-04-29T18:08:57.380Z] 18:08:57     INFO - GECKO(1405) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp026_e454.mozrunner/runtests_leaks_utility_pid1592.log
[task 2024-04-29T18:08:57.389Z] 18:08:57     INFO - GECKO(1405) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-04-29T18:08:57.395Z] 18:08:57     INFO - GECKO(1405) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-04-29T18:08:57.558Z] 18:08:57     INFO - GECKO(1405) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp026_e454.mozrunner/runtests_leaks_tab_pid1600.log
[task 2024-04-29T18:08:58.121Z] 18:08:58     INFO - GECKO(1405) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp026_e454.mozrunner/runtests_leaks_tab_pid1624.log
[task 2024-04-29T18:08:58.192Z] 18:08:58     INFO - GECKO(1405) | 1714414138190	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2024-04-29T18:08:58.260Z] 18:08:58     INFO - GECKO(1405) | 1714414138258	RemoteAgent	TRACE	[9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2024-04-29T18:08:58.263Z] 18:08:58     INFO - GECKO(1405) | 1714414138262	RemoteAgent	TRACE	[9] ProgressListener Setting unload timer (40000ms)
[task 2024-04-29T18:08:58.266Z] 18:08:58     INFO - GECKO(1405) | 1714414138265	RemoteAgent	TRACE	[9] Document already finished loading: about:blank
[task 2024-04-29T18:08:58.267Z] 18:08:58     INFO - GECKO(1405) | 1714414138266	RemoteAgent	TRACE	[9] ProgressListener Stop: has error=false
[task 2024-04-29T18:08:58.415Z] 18:08:58     INFO - GECKO(1405) | 1714414138413	Marionette	DEBUG	3 <- [1,1,null,{"sessionId":"9d6d6336-b71c-4d4d-ba44-618f0ea9e111","capabilities":{"browserName":"firefox","browserVersion":"127.0a1","platformName":"linux","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:127.0) Gecko/20100101 Firefox/127.0","moz:accessibilityChecks":false,"moz:buildID":"20240429171925","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":1405,"moz:profile":"/tmp/tmp026_e454.mozrunner","moz:shutdownTimeout":360000,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-04-29T18:08:58.479Z] 18:08:58     INFO - GECKO(1405) | 1714414138478	Marionette	DEBUG	3 -> [0,2,"Addon:Install",{"path":"/tmp/tmp5ec0sm9l.zip","temporary":false}]
[task 2024-04-29T18:08:59.260Z] 18:08:59     INFO - GECKO(1405) | 1714414139259	Marionette	DEBUG	3 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2024-04-29T18:08:59.333Z] 18:08:59     INFO - GECKO(1405) | 1714414139332	Marionette	DEBUG	3 -> [0,3,"Addon:Install",{"path":"/tmp/tmpqwm_4_tf.zip","temporary":false}]
[task 2024-04-29T18:08:59.561Z] 18:08:59     INFO - GECKO(1405) | 1714414139560	Marionette	DEBUG	3 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2024-04-29T18:08:59.580Z] 18:08:59     INFO - GECKO(1405) | 1714414139579	Marionette	DEBUG	3 -> [0,4,"Marionette:GetContext",{}]
[task 2024-04-29T18:08:59.584Z] 18:08:59     INFO - GECKO(1405) | 1714414139583	Marionette	DEBUG	3 <- [1,4,null,{"value":"content"}]
[task 2024-04-29T18:08:59.592Z] 18:08:59     INFO - GECKO(1405) | 1714414139591	Marionette	DEBUG	3 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-04-29T18:08:59.595Z] 18:08:59     INFO - GECKO(1405) | 1714414139594	Marionette	DEBUG	3 <- [1,5,null,{"value":null}]
[task 2024-04-29T18:08:59.608Z] 18:08:59     INFO - GECKO(1405) | 1714414139606	Marionette	DEBUG	3 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, v. 2.0. If a copy of the MPL was not distr ... s which flavor and url to load.\nlet ev = new CustomEvent(\"mochitest-load\", { detail: [flavor, url] });\nwin.dispatchEvent(ev);","args":[{"flavor":"browser-chrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":2167,"filename":"tests/mochitest/runtests.py"}]
[task 2024-04-29T18:08:59.665Z] 18:08:59     INFO - GECKO(1405) | 1714414139664	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 1405
[task 2024-04-29T18:08:59.678Z] 18:08:59     INFO - GECKO(1405) | 1714414139676	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2024-04-29T18:08:59.929Z] 18:08:59     INFO - GECKO(1405) | 1714414139928	RemoteAgent	TRACE	Received observer notification domwindowopened
[task 2024-04-29T18:09:00.043Z] 18:09:00     INFO - GECKO(1405) | 1714414140042	Marionette	DEBUG	3 <- [1,6,null,{"value":null}]
[task 2024-04-29T18:09:00.289Z] 18:09:00     INFO - GECKO(1405) | 1714414140288	Marionette	DEBUG	3 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2024-04-29T18:09:00.291Z] 18:09:00     INFO - GECKO(1405) | 1714414140290	Marionette	DEBUG	3 <- [1,7,null,{"value":null}]
[task 2024-04-29T18:09:00.422Z] 18:09:00     INFO - GECKO(1405) | 1714414140422	Marionette	DEBUG	3 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2024-04-29T18:09:00.427Z] 18:09:00     INFO - GECKO(1405) | 1714414140425	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2024-04-29T18:09:00.437Z] 18:09:00     INFO - GECKO(1405) | 1714414140436	Marionette	DEBUG	3 <- [1,8,null,{"value":null}]
[task 2024-04-29T18:09:00.441Z] 18:09:00     INFO - runtests.py | Waiting for browser...
[task 2024-04-29T18:09:00.446Z] 18:09:00     INFO - GECKO(1405) | 1714414140445	Marionette	DEBUG	Closed connection 3
[task 2024-04-29T18:09:01.118Z] 18:09:01     INFO - *** Start BrowserChrome Test Results ***
[task 2024-04-29T18:09:01.346Z] 18:09:01     INFO - checking window state
[task 2024-04-29T18:09:01.675Z] 18:09:01     INFO - TEST-START | browser/components/enterprisepolicies/tests/browser/disable_forget_button/browser_policy_disable_forgetbutton.js

Andrew McCreight [:mccr8]

Comment 1

•

6 months ago

LaunchAppWithForkServer in the stack

Group: core-security → dom-core-security

Component: MFBT → IPC

Keywords: csectype-race, sec-moderate, regression

Regressed by: 1874689

BugBot [:suhaib / :marco/ :calixte]

Comment 2

•

6 months ago

:gcp, since you are the author of the regressor, bug 1874689, could you take a look? Also, could you set the severity field?

For more information, please visit BugBot documentation.

Flags: needinfo?(gpascutto)

Jed Davis [:jld] ⟨⏰|UTC-7⟩ ⟦he/him⟧

Updated

•

6 months ago

Assignee: nobody → jld

Flags: needinfo?(gpascutto)

Jed Davis [:jld] ⟨⏰|UTC-7⟩ ⟦he/him⟧

Comment 3

•

6 months ago

Something isn't quite right with ownership of file descriptors and content process launching — a file descriptor that needs to live until after LaunchApp has returned on the launch thread is being closed too soon on the main thread.

Jed Davis [:jld] ⟨⏰|UTC-7⟩ ⟦he/him⟧

Comment 4

•

6 months ago

I don't know what I'm missing, but I can't see how this is possible: the thread that calls dup is in this call to DoLaunch, so the process launch promise hasn't resolved/rejected yet, so mProcessState shouldn't have been set to PROCESS_CREATED or PROCESS_ERROR in Then functions (or PROCESS_CONNECTED, later when the IPC Hello message is received), so the other thread should still be blocked here in WaitForProcessHandle, and it shouldn't be able to get to this later point where TSan finds it calling close until later, and that's synchronized via mMonitor so TSan should be able to see the causality. But the multithreading here is complicated and has had subtle bugs in the past, so I may well be missing something.

Jed Davis [:jld] ⟨⏰|UTC-7⟩ ⟦he/him⟧

Comment 5

•

6 months ago

I'm convinced that this is a Thread Sanitizer false positive. It's uncommon but possible for a process to advance directly from CHANNEL_INITIALIZED to PROCESS_CONNECTED and skip PROCESS_CREATED, if the child process starts up enough to send the IPC Hello message and the parent process IPC thread receives it before the parent process launch thread can send a runnable back to the I/O thread to indicate success; indeed, there's a comment about this case. And TSan doesn't know what's going on in other processes; the sendmsg to the fork server is, as far as it knows, unrelated to the recvmsg on another thread on a seemingly unrelated fd. So, while there is a chain of causality from dup → sendmsg → recvmsg → changing mProcessState under mMonitor → close, TSan can't see all of it at once and thinks the dup is unordered relative to the close.

I have a small test program for this, where a forked child process reads from one pipe and writes to another, and TSan doesn't recognize sending data through that process as establishing a happens-before relation (i.e., it reports a race when there isn't one). Note that TSan tracks file descriptors and understands pipes within the same process (like the pipe-to-self idiom used with signal handlers), so multiple processes are necessary to demonstrate this.

Blocks: 1874689

Group: dom-core-security

Keywords: csectype-race, regression, sec-moderate

No longer regressed by: 1874689

Nika Layzell [:nika] (ni? for response)

Updated

•

6 months ago

Severity: -- → S3

Jed Davis [:jld] ⟨⏰|UTC-7⟩ ⟦he/him⟧

Comment 6

•

5 months ago

Attached file Bug 1894073 - Suppress TSan false positive during an edge case in process launch. — Details

:gerard-majax

Assignee

Updated

•

5 months ago

Assignee: jld → lissyx+mozillians

Pulsebot

Comment 7

•

5 months ago

Pushed by alissy@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/5582482f0376 Suppress TSan false positive during an edge case in process launch. r=decoder,ipc-reviewers,nika

pstanciu

Comment 8

•

5 months ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/5582482f0376

Status: NEW → RESOLVED

Closed: 5 months ago

status-firefox129: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → 129 Branch

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/mfbt/UniquePtrExtensions.cpp:28:10 in mozilla::detail::FileHandleDeleter::operator()(mozilla::detail::FileHandleHelper) | single tracking bug

Categories

(Core :: IPC, defect)

Tracking

()

People

(Reporter: intermittent-bug-filer, Assigned: gerard-majax)

References

Details

(Keywords: intermittent-failure, intermittent-testcase)

Crash Data

Security

(public)

User Story

Attachments

(1 file)

Description

Comment 1

Comment 2

Updated

Comment 3

Comment 4

Comment 5

Updated

Comment 6

Updated

Comment 7

Comment 8

Attachment

General

Description

File Name

Content Type