Closed Bug 1974208 Opened 1 year ago Closed 1 year ago

High frequency Assertion failure: leaf, at /builds/worker/checkouts/gecko/dom/events/EventStateManager.cpp:6670

Categories

(Core :: DOM: Events, defect)

defect

Tracking

()

RESOLVED FIXED
143 Branch
Tracking Status
firefox-esr128 --- unaffected
firefox-esr140 --- unaffected
firefox140 --- unaffected
firefox141 --- unaffected
firefox142 --- wontfix
firefox143 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: simonf, NeedInfo)

References

(Regression)

Details

(Keywords: assertion, intermittent-failure, regression, Whiteboard: [stockwell unknown])

Attachments

(2 files, 1 obsolete file)

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


NFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: '!mBoundFrame', file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:2373
[task 2025-06-26T11:32:21.998+00:00] 11:32:21     INFO - GECKO(1924) | console.log: "got contextmenu event: " ({get isTrusted() {
[task 2025-06-26T11:32:22.000+00:00] 11:32:22     INFO - GECKO(1924) |     [native code]
[task 2025-06-26T11:32:22.000+00:00] 11:32:22     INFO - GECKO(1924) | }})
[task 2025-06-26T11:32:22.154+00:00] 11:32:22     INFO - GECKO(1924) | [WARN  webrender::renderer::init] asking to enable_gpu_markers but no supporting extension was found
[task 2025-06-26T11:32:22.189+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fa129859800 == 4 [pid = 2125] [id = 1] [url = about:logins#%7B15742668-b119-4440-a644-95fcd6f22d26%7D]
[task 2025-06-26T11:32:22.190+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fa12820ec00 == 3 [pid = 2125] [id = 3] [url = about:logins]
[task 2025-06-26T11:32:22.190+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fa129864800 == 2 [pid = 2125] [id = 2] [url = about:logins#%7B3d71624f-e9cf-4989-8630-38b3c154b84d%7D]
[task 2025-06-26T11:32:22.228+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: '!mEditorBase', file /builds/worker/checkouts/gecko/extensions/spellcheck/src/mozInlineSpellChecker.cpp:1893
[task 2025-06-26T11:32:22.228+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: '!mEditorBase', file /builds/worker/checkouts/gecko/extensions/spellcheck/src/mozInlineSpellChecker.cpp:727
[task 2025-06-26T11:32:22.229+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: mozInlineSpellChecker::Cleanup() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/EditorBase.cpp:612
[task 2025-06-26T11:32:22.230+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1746
[task 2025-06-26T11:32:22.230+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: '!mBoundFrame', file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:2373
[task 2025-06-26T11:32:22.265+00:00] 11:32:22     INFO - GECKO(1924) | [Parent 1924: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (7fa0a1a9fb00) [pid = 1924] [serial = 4] [outer = 0] [url = about:blank]
[task 2025-06-26T11:32:22.655+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1746
[task 2025-06-26T11:32:22.671+00:00] 11:32:22     INFO - GECKO(1924) | console.log: "got contextmenu event: " ({get isTrusted() {
[task 2025-06-26T11:32:22.671+00:00] 11:32:22     INFO - GECKO(1924) |     [native code]
[task 2025-06-26T11:32:22.672+00:00] 11:32:22     INFO - GECKO(1924) | }})
[task 2025-06-26T11:32:22.704+00:00] 11:32:22     INFO - GECKO(1924) | [WARN  webrender::renderer::init] asking to enable_gpu_markers but no supporting extension was found
[task 2025-06-26T11:32:22.737+00:00] 11:32:22     INFO - GECKO(1924) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-06-26T11:32:22.738+00:00] 11:32:22     INFO - GECKO(1924) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-06-26T11:32:22.739+00:00] 11:32:22     INFO - GECKO(1924) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-06-26T11:32:22.760+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1746
[task 2025-06-26T11:32:22.764+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1746
[task 2025-06-26T11:32:22.766+00:00] 11:32:22     INFO - GECKO(1924) | [Child 2125, Main Thread] WARNING: '!mBoundFrame', file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:2373
[task 2025-06-26T11:32:22.789+00:00] 11:32:22     INFO - GECKO(1924) | [2125] Assertion failure: leaf, at /builds/worker/checkouts/gecko/dom/events/EventStateManager.cpp:6670
[task 2025-06-26T11:32:22.796+00:00] 11:32:22     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2025-06-26T11:32:42.543+00:00] 11:32:42     INFO - GECKO(1924) | #01: mozilla::EventStateManager::RemoveNodeFromChainIfNeeded(mozilla::dom::ElementState, nsIContent*, bool) [dom/events/EventStateManager.cpp:6670]
[task 2025-06-26T11:32:42.544+00:00] 11:32:42     INFO - GECKO(1924) | #02: mozilla::EventStateManager::ContentRemoved(mozilla::dom::Document*, nsIContent*) [dom/events/EventStateManager.cpp:6755]
[task 2025-06-26T11:32:42.544+00:00] 11:32:42     INFO - GECKO(1924) | #03: mozilla::PresShell::ContentWillBeRemoved(nsIContent*, BatchRemovalState const*) [layout/base/PresShell.cpp:4843]
[task 2025-06-26T11:32:42.544+00:00] 11:32:42     INFO - GECKO(1924) | #04: mozilla::dom::MutationObservers::NotifyContentWillBeRemoved(nsINode*, nsIContent*, BatchRemovalState const*) [dom/base/MutationObservers.cpp:186]
[task 2025-06-26T11:32:42.544+00:00] 11:32:42     INFO - GECKO(1924) | #05: nsINode::RemoveChildNode(nsIContent*, bool, BatchRemovalState const*) [dom/base/nsINode.cpp:0]
[task 2025-06-26T11:32:42.544+00:00] 11:32:42     INFO - GECKO(1924) | #06: nsINode::RemoveChild(nsINode&, mozilla::ErrorResult&) [dom/base/nsINode.cpp:1048]
[task 2025-06-26T11:32:42.544+00:00] 11:32:42     INFO - GECKO(1924) | #07: nsINode::Remove() [dom/base/nsINode.cpp:2188]
[task 2025-06-26T11:32:42.546+00:00] 11:32:42     INFO - GECKO(1924) | #08: mozilla::dom::Element_Binding::remove(JSContext*, JS::Handle<JSObject*>, void*, JSJitMethodCallArgs const&) [s3:gecko-generated-sources:5a8636bca0a0a79f42280b71e337f36b1b1701f495d9f39acecc02f7311290decdda293768f3f16f5d6877af475bab4ae4001384e5b0beb71196d226f0d86f87/dom/bindings/ElementBinding.cpp::11733]
[task 2025-06-26T11:32:42.546+00:00] 11:32:42     INFO - GECKO(1924) | #09: mozilla::dom::binding_detail::GenericMethod<mozilla::dom::binding_detail::NormalThisPolicy, mozilla::dom::binding_detail::ThrowExceptions>(JSContext*, unsigned int, JS::Value*) [dom/bindings/BindingUtils.cpp:3308]
[task 2025-06-26T11:32:42.548+00:00] 11:32:42     INFO - GECKO(1924) | #10: ??? (???:???)
[task 2025-06-26T11:32:42.548+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: Rejecting reply 'PBrowser::Reply_NotifyIMEFocus': resolver dropped without being called: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:830
[task 2025-06-26T11:32:42.549+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: Rejecting reply 'PBrowser::Reply_NotifyIMEFocus': resolver dropped without being called: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:830
[task 2025-06-26T11:32:42.556+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: Rejecting reply 'PBrowser::Reply_NotifyIMEFocus': resolver dropped without being called: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:830
[task 2025-06-26T11:32:42.556+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: Rejecting reply 'PBrowser::Reply_NotifyIMEFocus': resolver dropped without being called: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:830
[task 2025-06-26T11:32:42.557+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: Rejecting reply 'PBrowser::Reply_NotifyIMEFocus': resolver dropped without being called: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:830
[task 2025-06-26T11:32:42.557+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, IPC I/O Parent] WARNING: [1.1]: Dropping message '<null>'; no connection to unknown peer D1F633195281D4C0.4BBAF871B0BB9BD2: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2025-06-26T11:32:42.561+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: IPC message 'PBrowser::Msg_Destroy' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:513
[task 2025-06-26T11:32:42.561+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: No build ID mismatch: file /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3744
[task 2025-06-26T11:32:42.561+00:00] 11:32:42     INFO - GECKO(1924) | console.error:
[task 2025-06-26T11:32:42.561+00:00] 11:32:42     INFO - GECKO(1924) |   remote browser crashed while on
[task 2025-06-26T11:32:42.561+00:00] 11:32:42     INFO - GECKO(1924) |   about:newtab
[task 2025-06-26T11:32:42.563+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: IPC message 'PContent::Msg_CommitBrowsingContextTransaction' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:513
[task 2025-06-26T11:32:42.563+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, Main Thread] WARNING: IPC message 'PContent::Msg_DiscardBrowsingContext' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:513
[task 2025-06-26T11:32:42.563+00:00] 11:32:42     INFO - GECKO(1924) | [Parent 1924, IPC I/O Parent] WARNING: process 2125 exited on signal 11: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:134
[task 2025-06-26T11:32:42.564+00:00] 11:32:42     INFO - GECKO(1924) | MEMORY STAT | vsize 11775MB | residentFast 668MB | heapAllocated 321MB
[task 2025-06-26T11:32:42.564+00:00] 11:32:42     INFO - TEST-OK | browser/components/aboutlogins/tests/browser/browser_contextmenuFillLogins.js | took 2789ms
[task 2025-06-26T11:32:42.565+00:00] 11:32:42     INFO - GECKO(1924) | [Child 2112: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fa12c079c00 == 3 [pid = 2112] [id = 2]
[task 2025-06-26T11:32:42.566+00:00] 11:32:42     INFO - GECKO(1924) | [Child 2112: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (7fa14297cc00) [pid = 2112] [serial = 5] [outer = 0]
[task 2025-06-26T11:32:42.566+00:00] 11:32:42     INFO - GECKO(1924) | [Child 2112: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (7fa12b84b800) [pid = 2112] [serial = 6] [outer = 7fa14297cc00]
[task 2025-06-26T11:32:42.566+00:00] 11:32:42     INFO - checking window state
[task 2025-06-26T11:32:42.567+00:00] 11:32:42     INFO - GECKO(1924) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp0p_vxr1q.mozrunner/runtests_leaks_tab_pid2292.log
Keywords: regression
Regressed by: 1963025
Summary: Intermittent Assertion failure: leaf, at /builds/worker/checkouts/gecko/dom/events/EventStateManager.cpp:6670 → High frequency Assertion failure: leaf, at /builds/worker/checkouts/gecko/dom/events/EventStateManager.cpp:6670

:simonf, since you are the author of the regressor, bug 1963025, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(sfriedberger)
Flags: needinfo?(sfriedberger)

Set release status flags based on info from the regressing bug 1963025

Update:
There have been 86 total failures in the last 7 days.
All the failures are on linux2404-64 platform, debug build type.

Recent failure log.

Flags: needinfo?(sfriedberger)
Duplicate of this bug: 1976969
Assignee: nobody → sfriedberger
Status: NEW → ASSIGNED

The attached patch seems to fix the test but doesn't actually solve why there is a race condition here.

Flags: needinfo?(sfriedberger)
Status: ASSIGNED → RESOLVED
Closed: 1 year ago
Resolution: --- → FIXED
Target Milestone: --- → 143 Branch
Flags: needinfo?(sfriedberger)

I don't know why the internal state of the EventStateManager is broken and I probably won't have time to dive into that soon. Therefore, I am disabling the test and unassigning myself.

Component: about:logins → DOM: Events
Product: Firefox → Core
Assignee: sfriedberger → nobody
Flags: needinfo?(sfriedberger)
Assignee: nobody → sfriedberger
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → FIXED
Target Milestone: --- → 143 Branch

The patch landed in nightly and beta is affected.
:simonf, is this bug important enough to require an uplift?

For more information, please visit BugBot documentation.

Flags: needinfo?(sfriedberger)
Flags: needinfo?(sfriedberger)

(In reply to Simon Friedberger (:simonf) from comment #25)

I don't know why the internal state of the EventStateManager is broken and I probably won't have time to dive into that soon. Therefore, I am disabling the test and unassigning myself.

OK, but now this bug is closed and there is no link to any other bug to figure out the underlying problem? Olli, any ideas what is/was going wrong here?

Flags: needinfo?(smaug)

For context, I saw this on a trypush whose parent is too old to have the patch from here; unfortunately pernosco still couldn't repro the failure.

Simon, I don't suppose you filed a follow-up ticket and just forgot to link it here? :-)

Flags: needinfo?(sfriedberger)

Ah, sorry! I did indeed not file any bugs or link them here, I just asked on Matrix and left it to the other bugs:

Flags: needinfo?(sfriedberger)
See Also: → 1792061, 1780802
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: