Open Bug 1867732 Opened 6 months ago Updated 10 days ago

Intermittent Assertion failure: mLastContentProcessedEvent <= mLastAPZProcessedEvent, at /builds/worker/checkouts/gecko/gfx/layers/apz/src/FocusState.cpp:38

Categories

(Core :: Panning and Zooming, defect, P5)

defect

Tracking

()

Tracking Status
firefox-esr115 --- unaffected
firefox120 --- unaffected
firefox121 --- unaffected
firefox122 --- affected
firefox123 --- affected

People

(Reporter: intermittent-bug-filer, Assigned: ajakobi)

Details

(Keywords: assertion, intermittent-failure, test-verify-fail)

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=438427111&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NRJQgtaIQFuV-vrk1NPL2w/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NRJQgtaIQFuV-vrk1NPL2w/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2023-12-01T07:41:57.296Z] 07:41:57     INFO - GECKO(2225) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/j3/6b905y_x1nx57cyr46c9vq7w000014/T/tmppjunvs4q.mozrunner/runtests_leaks_tab_pid2267.log
[task 2023-12-01T07:41:57.311Z] 07:41:57     INFO - GECKO(2225) | PromiseApzRepaintsFlushed: APZ flush done
[task 2023-12-01T07:41:57.319Z] 07:41:57     INFO - GECKO(2225) | WaitUntilApzStable: all done
[task 2023-12-01T07:41:57.931Z] 07:41:57     INFO - GECKO(2225) | [Child 2260: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 12d40f800 == 2 [pid = 2260] [id = 1]
[task 2023-12-01T07:41:57.932Z] 07:41:57     INFO - GECKO(2225) | [Child 2260: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (101944f20) [pid = 2260] [serial = 4] [outer = 0]
[task 2023-12-01T07:41:57.934Z] 07:41:57     INFO - GECKO(2225) | [Child 2260: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (12d40fc00) [pid = 2260] [serial = 5] [outer = 101944f20]
[task 2023-12-01T07:41:58.080Z] 07:41:58     INFO - GECKO(2225) | must wait for focus
[task 2023-12-01T07:41:58.133Z] 07:41:58     INFO - GECKO(2225) | [Parent 2225, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3095
[task 2023-12-01T07:41:58.133Z] 07:41:58     INFO - GECKO(2225) | [Parent 2225, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3095
[task 2023-12-01T07:41:58.134Z] 07:41:58     INFO - GECKO(2225) | [Parent 2225, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3095
[task 2023-12-01T07:41:58.135Z] 07:41:58     INFO - GECKO(2225) | [Parent 2225, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3095
[task 2023-12-01T07:41:58.171Z] 07:41:58     INFO - GECKO(2225) | [Child 2240: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (139552f20) [pid = 2240] [serial = 19] [outer = 0] [url = about:newtab]
[task 2023-12-01T07:41:58.172Z] 07:41:58     INFO - GECKO(2225) | [Child 2240: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (139552200) [pid = 2240] [serial = 16] [outer = 0] [url = about:newtab]
[task 2023-12-01T07:41:58.223Z] 07:41:58     INFO - GECKO(2225) | Assertion failure: mLastContentProcessedEvent <= mLastAPZProcessedEvent, at /builds/worker/checkouts/gecko/gfx/layers/apz/src/FocusState.cpp:38
[task 2023-12-01T07:41:58.227Z] 07:41:58     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2023-12-01T07:42:24.633Z] 07:42:24     INFO - GECKO(2225) | #01: mozilla::layers::FocusState::CanIgnoreKeyboardShortcutMisses() const [gfx/layers/apz/src/FocusState.cpp:220]
[task 2023-12-01T07:42:24.634Z] 07:42:24     INFO - GECKO(2225) | #02: mozilla::layers::APZCTreeManager::ReceiveInputEvent(mozilla::InputData&, std::__1::function<void (unsigned long long, mozilla::layers::APZHandledResult const&)>&&) [gfx/layers/apz/src/APZCTreeManager.cpp:1803]
[task 2023-12-01T07:42:24.634Z] 07:42:24     INFO - GECKO(2225) | #03: {virtual override thunk({offset(-16)}, mozilla::layers::APZCTreeManager::ReceiveInputEvent(mozilla::InputData&, std::__1::function<void (unsigned long long, mozilla::layers::APZHandledResult const&)>&&))} [gfx/layers/apz/src/APZCTreeManager.cpp:0]
[task 2023-12-01T07:42:24.635Z] 07:42:24     INFO - GECKO(2225) | #04: mozilla::layers::APZInputBridge::ReceiveInputEvent(mozilla::WidgetInputEvent&, std::__1::function<void (unsigned long long, mozilla::layers::APZHandledResult const&)>&&) [gfx/layers/apz/src/APZInputBridge.cpp:340]
[task 2023-12-01T07:42:24.635Z] 07:42:24     INFO - GECKO(2225) | #05: nsBaseWidget::DispatchInputEvent(mozilla::WidgetInputEvent*) [widget/nsBaseWidget.cpp:1267]
[task 2023-12-01T07:42:24.636Z] 07:42:24     INFO - GECKO(2225) | #06: mozilla::widget::TextEventDispatcher::DispatchInputEvent(nsIWidget*, mozilla::WidgetInputEvent&, nsEventStatus&) [widget/TextEventDispatcher.cpp:291]
[task 2023-12-01T07:42:24.637Z] 07:42:24     INFO - GECKO(2225) | #07: mozilla::widget::TextEventDispatcher::DispatchKeyboardEventInternal(mozilla::EventMessage, mozilla::WidgetKeyboardEvent const&, nsEventStatus&, void*, unsigned int, bool) [widget/TextEventDispatcher.cpp:0]
[task 2023-12-01T07:42:24.637Z] 07:42:24     INFO - GECKO(2225) | #08: mozilla::widget::TextEventDispatcher::DispatchKeyboardEvent(mozilla::EventMessage, mozilla::WidgetKeyboardEvent const&, nsEventStatus&, void*) [widget/TextEventDispatcher.cpp:538]
[task 2023-12-01T07:42:24.638Z] 07:42:24     INFO - GECKO(2225) | #09: mozilla::widget::IMEInputHandler::MaybeDispatchCurrentKeydownEvent(bool) [widget/cocoa/TextInputHandler.mm:3957]
[task 2023-12-01T07:42:24.639Z] 07:42:24     INFO - GECKO(2225) | #10: mozilla::widget::TextInputHandler::HandleKeyDownEvent(NSEvent*, unsigned int) [widget/cocoa/TextInputHandler.mm:0]
[task 2023-12-01T07:42:24.639Z] 07:42:24     INFO - GECKO(2225) | #11: -[ChildView keyDown:] [widget/cocoa/nsChildView.mm:3718]
[task 2023-12-01T07:42:24.744Z] 07:42:24     INFO - GECKO(2225) | #12: -[NSWindow(NSEventRouting) _reallySendEvent:isDelayedEvent:] [/System/Library/Frameworks/AppKit.framework/Versions/C/AppKit + 0x1e7d5d]
[task 2023-12-01T07:42:24.744Z] 07:42:24     INFO - GECKO(2225) | #13: -[NSWindow(NSEventRouting) sendEvent:] [/System/Library/Frameworks/AppKit.framework/Versions/C/AppKit + 0x1e61c9]
[task 2023-12-01T07:42:24.745Z] 07:42:24     INFO - GECKO(2225) | #14: -[ToolbarWindow sendEvent:] [widget/cocoa/nsCocoaWindow.mm:0]
[task 2023-12-01T07:42:24.745Z] 07:42:24     INFO - GECKO(2225) | #15: -[NSApplication(NSEvent) sendEvent:] [/System/Library/Frameworks/AppKit.framework/Versions/C/AppKit + 0x1e4efe]
[task 2023-12-01T07:42:24.746Z] 07:42:24     INFO - GECKO(2225) | #16: -[GeckoNSApplication sendEvent:] [widget/cocoa/nsAppShell.mm:0]
[task 2023-12-01T07:42:24.747Z] 07:42:24     INFO - GECKO(2225) | #17: mozilla::widget::TextInputHandlerBase::SynthesizeNativeKeyEvent(int, int, unsigned int, nsTSubstring<char16_t> const&, nsTSubstring<char16_t> const&) [widget/cocoa/TextInputHandler.mm:0]
[task 2023-12-01T07:42:24.747Z] 07:42:24     INFO - GECKO(2225) | #18: nsChildView::SynthesizeNativeKeyEvent(int, int, unsigned int, nsTSubstring<char16_t> const&, nsTSubstring<char16_t> const&, nsIObserver*) [widget/cocoa/nsChildView.mm:0]
[task 2023-12-01T07:42:24.748Z] 07:42:24     INFO - GECKO(2225) | #19: mozilla::detail::RunnableMethodImpl<nsCOMPtr<nsIWidget>, nsresult (nsIWidget::*)(int, int, unsigned int, nsTSubstring<char16_t> const&, nsTSubstring<char16_t> const&, nsIObserver*), true, (mozilla::RunnableKind)0, int, int, unsigned int, nsTString<char16_t>, nsTString<char16_t>, nsIObserver*>::Run() [xpcom/threads/nsThreadUtils.h:1213]
[task 2023-12-01T07:42:24.748Z] 07:42:24     INFO - GECKO(2225) | #20: mozilla::RunnableTask::Run() [xpcom/threads/TaskController.cpp:550]
[task 2023-12-01T07:42:24.749Z] 07:42:24     INFO - GECKO(2225) | #21: mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) [xpcom/threads/TaskController.cpp:0]
[task 2023-12-01T07:42:24.750Z] 07:42:24     INFO - GECKO(2225) | #22: mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) [xpcom/threads/TaskController.cpp:0]
[task 2023-12-01T07:42:24.750Z] 07:42:24     INFO - GECKO(2225) | #23: mozilla::TaskController::ProcessPendingMTTask(bool) [xpcom/threads/TaskController.cpp:485]
[task 2023-12-01T07:42:24.751Z] 07:42:24     INFO - GECKO(2225) | #24: mozilla::detail::RunnableFunction<mozilla::TaskController::TaskController()::$_0>::Run() [xpcom/threads/nsThreadUtils.h:549]
[task 2023-12-01T07:42:24.751Z] 07:42:24     INFO - GECKO(2225) | #25: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1202]
[task 2023-12-01T07:42:24.752Z] 07:42:24     INFO - GECKO(2225) | #26: NS_ProcessPendingEvents(nsIThread*, unsigned int) [xpcom/threads/nsThreadUtils.cpp:445]
[task 2023-12-01T07:42:24.752Z] 07:42:24     INFO - GECKO(2225) | #27: nsBaseAppShell::NativeEventCallback() [widget/nsBaseAppShell.cpp:88]
[task 2023-12-01T07:42:24.753Z] 07:42:24     INFO - GECKO(2225) | #28: nsAppShell::ProcessGeckoEvents(void*) [widget/cocoa/nsAppShell.mm:542]
[task 2023-12-01T07:42:24.798Z] 07:42:24     INFO - GECKO(2225) | #29: __CFRUNLOOP_IS_CALLING_OUT_TO_A_SOURCE0_PERFORM_FUNCTION__ [/System/Library/Frameworks/CoreFoundation.framework/Versions/A/CoreFoundation + 0x83d52]
[task 2023-12-01T07:42:24.799Z] 07:42:24     INFO - GECKO(2225) | #30: __CFRunLoopDoSource0 [/System/Library/Frameworks/CoreFoundation.framework/Versions/A/CoreFoundation + 0x83cf1]
[task 2023-12-01T07:42:24.799Z] 07:42:24     INFO - GECKO(2225) | #31: __CFRunLoopDoSources0 [/System/Library/Frameworks/CoreFoundation.framework/Versions/A/CoreFoundation + 0x83b0b]
[task 2023-12-01T07:42:24.800Z] 07:42:24     INFO - GECKO(2225) | #32: __CFRunLoopRun [/System/Library/Frameworks/CoreFoundation.framework/Versions/A/CoreFoundation + 0x8283a]
[task 2023-12-01T07:42:24.800Z] 07:42:24     INFO - GECKO(2225) | #33: CFRunLoopRunSpecific [/System/Library/Frameworks/CoreFoundation.framework/Versions/A/CoreFoundation + 0x81e3e]
[task 2023-12-01T07:42:24.820Z] 07:42:24     INFO - GECKO(2225) | #34: RunCurrentEventLoopInMode [/System/Library/Frameworks/Carbon.framework/Versions/A/Frameworks/HIToolbox.framework/Versions/A/HIToolbox + 0x2fabd]
[task 2023-12-01T07:42:24.820Z] 07:42:24     INFO - GECKO(2225) | #35: ReceiveNextEventCommon [/System/Library/Frameworks/Carbon.framework/Versions/A/Frameworks/HIToolbox.framework/Versions/A/HIToolbox + 0x2f6f4]
[task 2023-12-01T07:42:24.821Z] 07:42:24     INFO - GECKO(2225) | #36: _BlockUntilNextEventMatchingListInModeWithFilter [/System/Library/Frameworks/Carbon.framework/Versions/A/Frameworks/HIToolbox.framework/Versions/A/HIToolbox + 0x2f579]
[task 2023-12-01T07:42:24.822Z] 07:42:24     INFO - GECKO(2225) | #37: _DPSNextEvent [/System/Library/Frameworks/AppKit.framework/Versions/C/AppKit + 0x41039]
[task 2023-12-01T07:42:24.822Z] 07:42:24     INFO - GECKO(2225) | #38: -[NSApplication(NSEvent) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] [/System/Library/Frameworks/AppKit.framework/Versions/C/AppKit + 0x3f880]
[task 2023-12-01T07:42:24.823Z] 07:42:24     INFO - GECKO(2225) | #39: -[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [widget/cocoa/nsAppShell.mm:196]
[task 2023-12-01T07:42:24.823Z] 07:42:24     INFO - GECKO(2225) | #40: -[NSApplication run] [/System/Library/Frameworks/AppKit.framework/Versions/C/AppKit + 0x3158e]
[task 2023-12-01T07:42:24.824Z] 07:42:24     INFO - GECKO(2225) | #41: -[GeckoNSApplication run] [widget/cocoa/nsAppShell.mm:0]
[task 2023-12-01T07:42:24.824Z] 07:42:24     INFO - GECKO(2225) | #42: nsAppShell::Run() [widget/cocoa/nsAppShell.mm:0]
[task 2023-12-01T07:42:24.825Z] 07:42:24     INFO - GECKO(2225) | #43: nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:297]
[task 2023-12-01T07:42:24.825Z] 07:42:24     INFO - GECKO(2225) | #44: XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:5673]
[task 2023-12-01T07:42:24.826Z] 07:42:24     INFO - GECKO(2225) | #45: XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:5882]
[task 2023-12-01T07:42:24.827Z] 07:42:24     INFO - GECKO(2225) | #46: XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:5938]
[task 2023-12-01T07:42:24.827Z] 07:42:24     INFO - GECKO(2225) | #47: main [browser/app/nsBrowserApp.cpp:445]
[task 2023-12-01T07:42:24.828Z] 07:42:24     INFO - GECKO(2225) | [Child 2240, IPC I/O Child] WARNING: [D0BC14F81AC7573D.C4812A363CFA48B6]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:352

:ajakobi, since you are the author of the regressor, bug 1683776, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(ajakobi)

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

Summary: Intermittent TV Assertion failure: mLastContentProcessedEvent <= mLastAPZProcessedEvent, at /builds/worker/checkouts/gecko/gfx/layers/apz/src/FocusState.cpp:38 → Intermittent Assertion failure: mLastContentProcessedEvent <= mLastAPZProcessedEvent, at /builds/worker/checkouts/gecko/gfx/layers/apz/src/FocusState.cpp:38

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

Assignee: nobody → ajakobi
Flags: needinfo?(ajakobi)

Per discussion with Frank, removing the regression keyword so this does not show up on our regression dashboard.

The rationale for this is:

  • We have no reason to believe this indicates a user-visible issue.
  • The very low failure rate makes this basically not actionable.

(It looks like I also need to clear the "regressed by" field to be able to remove the keyword.)

Keywords: regression
No longer regressed by: 1683776
You need to log in before you can comment on or make changes to this bug.