Closed Bug 1616245 Opened 5 years ago Closed 2 years ago

Intermittent toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js,browser_corrupt.js | Main Thread] ###!!! ASSERTION: Negative height passed to nsWindow::Resize: '(height >= 0)', file z:/build/build/src/widget/windows/nsWindow.cpp

Categories

(Core :: Widget: Win32, defect, P5)

Product:
Core
Component:
Widget: Win32
Platform:
Unspecified
Windows
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: aryx, Unassigned)

References

Details

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

Attachments

(4 files)

Bug 1616245 - Change a few nsIWidget functions to take strongly-typed arguments. r?jfkthame
(deleted), text/x-phabricator-request
Details
Bug 1616245 - Fix unit mismatches by applying an appropriate scaling factor. r?jfkthame
(deleted), text/x-phabricator-request
Details
Bug 1616245 - Use GetBounds instead of mBounds. r?jfkthame
(deleted), text/x-phabricator-request
Details
Bug 1616245 - Make better use of the rect/size structs. r?jfkthame
(deleted), text/x-phabricator-request
Details

This failure has been observed twice today on Windows 10 x64 debug (task 1, task 2) during the execution of toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js. It spends ~60 minutes in the test (until the task execution limit gets hit) and is stuck printing this assertion (each log growths to ~1.2GB):

[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO - GECKO(9700) | [Parent 4008, Main Thread] ###!!! ASSERTION: Negative height passed to nsWindow::Resize: '(height >= 0)', file z:/build/build/src/widget/windows/nsWindow.cpp, line 1834
[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO - GECKO(9700) | #01: nsView::DoResetWidgetBounds(bool, bool) [view/nsView.cpp:360]
[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO - GECKO(9700) | #02: nsViewManager::ProcessPendingUpdatesForView(nsView*, bool) [view/nsViewManager.cpp:382]
[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO - GECKO(9700) | #03: nsViewManager::ProcessPendingUpdates() [view/nsViewManager.cpp:1020]
[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO - GECKO(9700) | #04: nsViewManager::WillPaintWindow(nsIWidget*) [view/nsViewManager.cpp:668]
[task 2020-02-18T12:08:44.027Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO - GECKO(9700) | #05: nsView::WillPaintWindow(nsIWidget*) [view/nsView.cpp:1048]
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO - GECKO(9700) | #06: nsWindow::OnPaint(HDC__, unsigned int) [widget/windows/nsWindowGfx.cpp:286]
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO - GECKO(9700) | #07: nsWindow::ProcessMessage(unsigned int, unsigned long long&, long long&, long long) [widget/windows/nsWindow.cpp:5325]
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO - GECKO(9700) | #08: static nsWindow::WindowProcInternal(HWND__, unsigned int, unsigned long long, long long) [widget/windows/nsWindow.cpp:4811]
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO - GECKO(9700) | #09: CallWindowProcCrashProtected(long long ()(HWND__, unsigned int, unsigned long long, long long), HWND__, unsigned int, unsigned long long, long long) [xpcom/base/nsCrashOnException.cpp:32]
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO - GECKO(9700) | #10: static nsWindow::WindowProc(HWND__, unsigned int, unsigned long long, long long) [widget/windows/nsWindow.cpp:4764]
[task 2020-02-18T12:08:44.028Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO - GECKO(9700) | #11: USER32.dll + 0x16cc1
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO - GECKO(9700) | #12: USER32.dll + 0x1699c
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO - GECKO(9700) | #13: USER32.dll + 0x20453
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO - GECKO(9700) | #14: ntdll.dll + 0x9dc04
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO - GECKO(9700) | #15: win32u.dll + 0x1744
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO - GECKO(9700) | #16: USER32.dll + 0x16700
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.029Z] 12:08:44 INFO - GECKO(9700) | #17: nsAppShell::ProcessNextNativeEvent(bool) [widget/windows/nsAppShell.cpp:539]
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO - GECKO(9700) | #18: nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal, bool) [widget/nsBaseAppShell.cpp:242]
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO - GECKO(9700) | #19: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1127]
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO - GECKO(9700) | #20: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:481]
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO - GECKO(9700) | #21: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:87]
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO - GECKO(9700) | #22: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:309]
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.030Z] 12:08:44 INFO - GECKO(9700) | #23: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO - GECKO(9700) | #24: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO - GECKO(9700) | #25: nsAppShell::Run() [widget/windows/nsAppShell.cpp:406]
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO - GECKO(9700) | #26: nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:273]
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO - GECKO(9700) | #27: XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:4566]
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO - GECKO(9700) | #28: XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:4701]
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO - GECKO(9700) | #29: XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:4752]
[task 2020-02-18T12:08:44.031Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO - GECKO(9700) | #30: NS_internal_main(int, char**, char**) [browser/app/nsBrowserApp.cpp:331]
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO - GECKO(9700) | #31: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO - GECKO(9700) | #32: __scrt_common_main_seh() [f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO - GECKO(9700) | #33: KERNEL32.DLL + 0x13034
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO -
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO - GECKO(9700) | #34: ntdll.dll + 0x71461
[task 2020-02-18T12:08:44.032Z] 12:08:44 INFO -

This has failed 6 times yesterday. It was observed once during retriggers, first occurrence is https://treeherder.mozilla.org/#/jobs?repo=autoland&group_state=expanded&searchStr=windows%2Cx64%2Cdebug%2Cbrowser-chrome&fromchange=09688f0bad8aa4b25e6d40f6090c76f5e8141175&tochange=dc4f43236bcf4b320340e772264c22eb0d228885&selectedJob=289493000

Timothy, can you check the previous commits if any of them are able to trigger such an issue?

Flags: needinfo?(tnikkel)

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #1)

This has failed 6 times yesterday. It was observed once during retriggers, first occurrence is https://treeherder.mozilla.org/#/jobs?repo=autoland&group_state=expanded&searchStr=windows%2Cx64%2Cdebug%2Cbrowser-chrome&fromchange=09688f0bad8aa4b25e6d40f6090c76f5e8141175&tochange=dc4f43236bcf4b320340e772264c22eb0d228885&selectedJob=289493000

Timothy, can you check the previous commits if any of them are able to trigger such an issue?

I went through every changeset in that link, nothing seemed like it would cause this.

Flags: needinfo?(tnikkel)

Jim, can you get anybody to investigate this? These failures are a black box until the log gets downloaded and manually investigated with grep and vi which is time consuming.

Flags: needinfo?(jmathies)

There are 21 failures associated to this bug in the last 7 days. These are occurring on windows10-64-qr debug.

Kats, please have a look at this assertion which causes huge logs.

Component: Widget: Win32 → Graphics: WebRender
Flags: needinfo?(jmathies) → needinfo?(kats)

The stack indicates the call to resize is coming from nsView.cpp#360, which means it must be one of these two lines:

https://searchfox.org/mozilla-central/rev/070a000dd49aac4a26147e137efcd91a728d13b8/view/nsView.cpp#350,357

Both of these are actually calling ResizeClient function swhich also do negative-height checks here but those assertions are not getting triggered. So it stands to reason that the subtraction code inside ResizeClient is making the height negative before nsWindow::Resize gets called further down in those functions. Looking at what the code is doing most likely there's a units mismatch going on here.

This code seems particularly fishy because the only caller of ResizeClient (the one in nsView.cpp) passes in values in "desktop pixels" but the ResizeClient functions do their own assumption-making about whether the values are in desktop pixels or not. It seems like we should change the function signature to just take a DesktopSize or DesktopRect as appropriate and eliminate the assumptions.

If that doesn't fix the problem, maybe we should just max(0,height) the height after subtracting things from it. :jfkthame, you seem to be the one who last touched this desktop pixel conversion stuff, do you have any thoughts on what might be happening here?

Flags: needinfo?(kats) → needinfo?(jfkthame)

Bugbug thinks this bug is a regression, but please revert this change in case of error.

Keywords: regression

I'm looking into this. Looks like a pretty clear case of mismatched units.

Assignee: nobody → kats
Flags: needinfo?(jfkthame)

Totally untested patch! I couldn't even fully compile it due to bug 1623961.

https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&revision=fbbafe0502b8f984e68c737e4302b7955412fbe2 has retriggers of the intermittently asserting test. Maybe not enough retriggers, but it's all green.

https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&revision=6722bfc32af4b764b793e22bd97ea009bc392c66 has some sanity test runs.

These functions all have a single call site, and the call site clearly always
has rect in DesktopPixel units. So it makes sense to encode this in the API,
and propagate the strongly typed units.

The priority flag is not set for this bug.
:jbonisteel, could you have a look please?

For more information, please visit auto_nag documentation.

Flags: needinfo?(jbonisteel)

This seems to be a widget bug, not really a WR bug

Component: Graphics: WebRender → Widget
Flags: needinfo?(jbonisteel)

Over the past week there are 20 failures on this bug. These happen on windows10-64 and windows10-64-qr debug.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=295389579&repo=autoland

Flags: needinfo?(kats)

Patches are in Lando now.

Flags: needinfo?(kats)
Pushed by kgupta@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/aeb210f9a9da Change a few nsIWidget functions to take strongly-typed arguments. r=jfkthame https://hg.mozilla.org/integration/autoland/rev/1d7a69ff3793 Fix unit mismatches by applying an appropriate scaling factor. r=jfkthame

https://hg.mozilla.org/mozilla-central/rev/aeb210f9a9da
https://hg.mozilla.org/mozilla-central/rev/1d7a69ff3793

Status: NEW → RESOLVED
Closed: 5 years ago
status-firefox76: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla76
status-firefox76: fixed → ---
Target Milestone: mozilla76 → ---

I got back logging from here:

nsBaseWidget::Resize called with aSize=372.000000,131.000000 desktopDelta=0.000000,-143.000000 from mBounds=372,131 clientBounds=372,274 scale=1.000000

So it seems that mBounds is smaller than clientBounds which seems wrong? Looking at the functions here it seems like code should be either using mBounds for everything, or using GetBounds() and GetClientBounds(), which can be overridden. And in fact the windows widget does override those functions. So when nsBaseWidget::Resize mixes mBounds with GetClientBounds() that seems like it's using inconsistent values which might cause this. I can try using GetBounds() instead of using mBounds directly but maybe somebody who understands this code should comment on whether this makes sense or not.

Flags: needinfo?(jfkthame)

I also confirmed with additional logging that mBounds and GetBounds() return different values, and using GetBounds() would not result in the assertion failure.

I wrote a patch to make that change, but it seems to produce an unusually high rate of crashes in tests that seem to be window-related: https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&selectedJob=296464332&revision=971f4cab9d4a4d1f386c04c19790e94ace95abdc

Seems like a logical conclusion that my patch is causing these crashes, but the crash stacks in the logs are not particularly useful. I might try to build locally on windows and test there.

New try push rebased on master seems better: https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&revision=01eda799f77140455a84c6906d8fde3a6b48c449

There's still a couple of suspicious failures but I don't know if they're from my patch or not.

It doesn't make sense to mix mBounds with GetClientBounds(), as the windows
widget overrides both GetBounds() and GetClientBounds(). So if we're using
GetClientBounds() for the client bounds, we should be using GetBounds() for
the bounds.

No functional change here, but this improves readability by using the
Rect and Size structs' operators, rather than breaking out the x/y/width/height
components and doing operations directly.

Depends on D70232

Pushed by kgupta@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/69e9b40608e3 Use GetBounds instead of mBounds. r=jfkthame https://hg.mozilla.org/integration/autoland/rev/86458febcf2d Make better use of the rect/size structs. r=jfkthame
Backout by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/75323c5f1047 Backed out 2 changesets for causing a raise in browser chrome failures. CLOSED TREE

Here's the range between landing and backout that shows b-c failures: https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel%2Crunning%2Cpending%2Crunnable&fromchange=86458febcf2d1429d32e1c18d238b3232c7d4ad9&tochange=75323c5f1047c579989b6a0dabd8fa29c854c869&searchStr=windows%2Cbrowser-chrome

I don't really know what to do here, some of the failures are similar to ones that I saw on my try pushes but have no idea what's going on there. Given this is really outside my area of expertise I'm going to unassign; somebody more familiar with the Windows APIs should take a look.

Assignee: kats → nobody
Component: Widget → Widget: Win32
OS: Unspecified → Windows

This has hit again here: https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception&revision=109f3a4de56716d3e1162c2368fe37b8a377f72f&selectedTaskRun=eYFRo9s0SpywB8w2hzKUOw.0&searchStr=Windows%2C10%2Cx64%2CWebRender%2Cdebug%2CMochitests%2Ctest-windows10-64-qr%2Fdebug-mochitest-browser-chrome-e10s%2Cbc4

Raw failure log here: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eYFRo9s0SpywB8w2hzKUOw/runs/0/artifacts/public/logs/live_backing.log

Last test started was, TEST-START | browser/components/customizableui/test/browser_962069_drag_to_overflow_chevron.js:

[task 2020-08-27T19:35:34.411Z] 19:35:34     INFO - TEST-START | browser/components/customizableui/test/browser_962069_drag_to_overflow_chevron.js
[task 2020-08-27T19:35:34.514Z] 19:35:34     INFO - GECKO(9128) | [Child 9788, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-27T19:35:34.516Z] 19:35:34     INFO - GECKO(9128) | [Child 9788: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (00000220A8D0D000) [pid = 9788] [serial = 113] [outer = 00000220A64290E0]
[task 2020-08-27T19:35:34.658Z] 19:35:34     INFO - GECKO(9128) | [Child 10288, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp, line 6798
[task 2020-08-27T19:35:34.658Z] 19:35:34     INFO - GECKO(9128) | [Child 9788, [Child 10288, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp, line 6798
[task 2020-08-27T19:35:34.659Z] 19:35:34     INFO - GECKO(9128) | [Child 10288, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp, line 6798
[task 2020-08-27T19:35:34.659Z] 19:35:34     INFO - GECKO(9128) | Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp, line 6798
[task 2020-08-27T19:35:35.596Z] 19:35:35     INFO - GECKO(9128) | [Parent 2828, Main Thread] ###!!! ASSERTION: Negative height passed to nsWindow::Resize: '(height >= 0)', file /builds/worker/checkouts/gecko/widget/windows/nsWindow.cpp, line 1941
[task 2020-08-27T19:35:35.596Z] 19:35:35     INFO - GECKO(9128) | #01: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450587d]
[task 2020-08-27T19:35:35.597Z] 19:35:35     INFO - GECKO(9128) | #02: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450a475]
[task 2020-08-27T19:35:35.599Z] 19:35:35     INFO - GECKO(9128) | #03: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450c40d]
[task 2020-08-27T19:35:35.599Z] 19:35:35     INFO - GECKO(9128) | #04: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450816a]
[task 2020-08-27T19:35:35.599Z] 19:35:35     INFO - GECKO(9128) | #05: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4508055]
[task 2020-08-27T19:35:35.601Z] 19:35:35     INFO - GECKO(9128) | #06: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x464f2e5]
[task 2020-08-27T19:35:35.601Z] 19:35:35     INFO - GECKO(9128) | #07: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x464fa29]
[task 2020-08-27T19:35:35.602Z] 19:35:35     INFO - GECKO(9128) | #08: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x464250b]
[task 2020-08-27T19:35:35.602Z] 19:35:35     INFO - GECKO(9128) | #09: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x46412de]
[task 2020-08-27T19:35:35.603Z] 19:35:35     INFO - GECKO(9128) | #10: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x6e1af]
[task 2020-08-27T19:35:35.603Z] 19:35:35     INFO - GECKO(9128) | #11: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4636810]
[task 2020-08-27T19:35:35.603Z] 19:35:35     INFO -  fix-stacks error: failed to read breakpad symbols dir `Z:\task_1598553921\build\symbols\USER32.pdb` for `C:\Windows\System32\USER32.dll`
[task 2020-08-27T19:35:35.603Z] 19:35:35     INFO -  fix-stacks note:  this is expected and harmless for system libraries on debug automation runs
[task 2020-08-27T19:35:35.603Z] 19:35:35     INFO - GECKO(9128) | #12: CallWindowProcW [C:\Windows\System32\USER32.dll + 0x16cc1]
[task 2020-08-27T19:35:35.605Z] 19:35:35     INFO - GECKO(9128) | #13: CallWindowProcW [C:\Windows\System32\USER32.dll + 0x1699c]
[task 2020-08-27T19:35:35.605Z] 19:35:35     INFO - GECKO(9128) | #14: IsWindowVisible [C:\Windows\System32\USER32.dll + 0x20453]
[task 2020-08-27T19:35:35.605Z] 19:35:35     INFO - GECKO(9128) | #15: KiUserCallbackDispatcher [C:\Windows\SYSTEM32\ntdll.dll + 0x9dc04]
[task 2020-08-27T19:35:35.605Z] 19:35:35     INFO -  fix-stacks error: failed to read breakpad symbols dir `Z:\task_1598553921\build\symbols\win32u.pdb` for `C:\Windows\System32\win32u.dll`
[task 2020-08-27T19:35:35.606Z] 19:35:35     INFO -  fix-stacks note:  this is expected and harmless for system libraries on debug automation runs
[task 2020-08-27T19:35:35.606Z] 19:35:35     INFO - GECKO(9128) | #16: NtUserDispatchMessage [C:\Windows\System32\win32u.dll + 0x1744]
[task 2020-08-27T19:35:35.606Z] 19:35:35     INFO - GECKO(9128) | #17: DispatchMessageW [C:\Windows\System32\USER32.dll + 0x16700]
[task 2020-08-27T19:35:35.606Z] 19:35:35     INFO - GECKO(9128) | #18: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4607c91]
[task 2020-08-27T19:35:35.608Z] 19:35:35     INFO - GECKO(9128) | #19: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4554037]
[task 2020-08-27T19:35:35.608Z] 19:35:35     INFO - GECKO(9128) | #20: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x1a8c14]
[task 2020-08-27T19:35:35.608Z] 19:35:35     INFO - GECKO(9128) | #21: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x1af3ab]
[task 2020-08-27T19:35:35.610Z] 19:35:35     INFO - GECKO(9128) | #22: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa6970a]
[task 2020-08-27T19:35:35.610Z] 19:35:35     INFO - GECKO(9128) | #23: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa07080]
[task 2020-08-27T19:35:35.610Z] 19:35:35     INFO - GECKO(9128) | #24: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa06f32]
[task 2020-08-27T19:35:35.611Z] 19:35:35     INFO - GECKO(9128) | #25: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4553dd8]
[task 2020-08-27T19:35:35.613Z] 19:35:35     INFO - GECKO(9128) | #26: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x460740a]
[task 2020-08-27T19:35:35.613Z] 19:35:35     INFO - GECKO(9128) | #27: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x60461b2]
[task 2020-08-27T19:35:35.615Z] 19:35:35     INFO - GECKO(9128) | #28: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618af5f]
[task 2020-08-27T19:35:35.615Z] 19:35:35     INFO - GECKO(9128) | #29: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618d4d9]
[task 2020-08-27T19:35:35.615Z] 19:35:35     INFO - GECKO(9128) | #30: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618ddaa]
[task 2020-08-27T19:35:35.615Z] 19:35:35     INFO - GECKO(9128) | #31: NS_internal_main(int, char**, char**) [browser/app/nsBrowserApp.cpp:331]
[task 2020-08-27T19:35:35.616Z] 19:35:35     INFO - GECKO(9128) | #32: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2020-08-27T19:35:35.616Z] 19:35:35     INFO - GECKO(9128) | #33: __scrt_common_main_seh() [/builds/worker/workspace/obj-build/browser/app/f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2020-08-27T19:35:35.618Z] 19:35:35     INFO - GECKO(9128) | #34: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x13034]
[task 2020-08-27T19:35:35.618Z] 19:35:35     INFO - GECKO(9128) | #35: RtlUserThreadStart [C:\Windows\SYSTEM32\ntdll.dll + 0x71461]
[task 2020-08-27T19:35:35.618Z] 19:35:35     INFO - GECKO(9128) | [Parent 2828, Main Thread] ###!!! ASSERTION: Negative height passed to nsWindow::Resize: '(height >= 0)', file /builds/worker/checkouts/gecko/widget/windows/nsWindow.cpp, line 1941
[task 2020-08-27T19:35:35.620Z] 19:35:35     INFO - GECKO(9128) | #01: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450587d]
[task 2020-08-27T19:35:35.620Z] 19:35:35     INFO - GECKO(9128) | #02: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450a475]
[task 2020-08-27T19:35:35.621Z] 19:35:35     INFO - GECKO(9128) | #03: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450c40d]
[task 2020-08-27T19:35:35.621Z] 19:35:35     INFO - GECKO(9128) | #04: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450816a]
[task 2020-08-27T19:35:35.621Z] 19:35:35     INFO - GECKO(9128) | #05: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4508055]
[task 2020-08-27T19:35:35.621Z] 19:35:35     INFO - GECKO(9128) | #06: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x464f2e5]
[task 2020-08-27T19:35:35.622Z] 19:35:35     INFO - GECKO(9128) | #07: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x464250b]
[task 2020-08-27T19:35:35.622Z] 19:35:35     INFO - GECKO(9128) | #08: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x46412de]
[task 2020-08-27T19:35:35.624Z] 19:35:35     INFO - GECKO(9128) | #09: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x6e1af]
[task 2020-08-27T19:35:35.624Z] 19:35:35     INFO - GECKO(9128) | #10: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4636810]
[task 2020-08-27T19:35:35.626Z] 19:35:35     INFO - GECKO(9128) | #11: CallWindowProcW [C:\Windows\System32\USER32.dll + 0x16cc1]
[task 2020-08-27T19:35:35.626Z] 19:35:35     INFO - GECKO(9128) | #12: CallWindowProcW [C:\Windows\System32\USER32.dll + 0x1699c]
[task 2020-08-27T19:35:35.626Z] 19:35:35     INFO - GECKO(9128) | #13: IsWindowVisible [C:\Windows\System32\USER32.dll + 0x20453]
[task 2020-08-27T19:35:35.626Z] 19:35:35     INFO - GECKO(9128) | #14: KiUserCallbackDispatcher [C:\Windows\SYSTEM32\ntdll.dll + 0x9dc04]
[task 2020-08-27T19:35:35.626Z] 19:35:35     INFO - GECKO(9128) | #15: NtUserDispatchMessage [C:\Windows\System32\win32u.dll + 0x1744]
[task 2020-08-27T19:35:35.628Z] 19:35:35     INFO - GECKO(9128) | #16: DispatchMessageW [C:\Windows\System32\USER32.dll + 0x16700]
[task 2020-08-27T19:35:35.628Z] 19:35:35     INFO - GECKO(9128) | #17: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4607c91]
[task 2020-08-27T19:35:35.628Z] 19:35:35     INFO - GECKO(9128) | #18: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4554037]
[task 2020-08-27T19:35:35.629Z] 19:35:35     INFO - GECKO(9128) | #19: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x1a8c14]
[task 2020-08-27T19:35:35.629Z] 19:35:35     INFO - GECKO(9128) | #20: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x1af3ab]
[task 2020-08-27T19:35:35.631Z] 19:35:35     INFO - GECKO(9128) | #21: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa6970a]
[task 2020-08-27T19:35:35.632Z] 19:35:35     INFO - GECKO(9128) | #22: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa07080]
[task 2020-08-27T19:35:35.632Z] 19:35:35     INFO - GECKO(9128) | #23: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa06f32]
[task 2020-08-27T19:35:35.632Z] 19:35:35     INFO - GECKO(9128) | #24: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4553dd8]
[task 2020-08-27T19:35:35.632Z] 19:35:35     INFO - GECKO(9128) | #25: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x460740a]
[task 2020-08-27T19:35:35.632Z] 19:35:35     INFO - GECKO(9128) | #26: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x60461b2]
[task 2020-08-27T19:35:35.634Z] 19:35:35     INFO - GECKO(9128) | #27: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618af5f]
[task 2020-08-27T19:35:35.634Z] 19:35:35     INFO - GECKO(9128) | #28: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618d4d9]
[task 2020-08-27T19:35:35.636Z] 19:35:35     INFO - GECKO(9128) | #29: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618ddaa]
[task 2020-08-27T19:35:35.636Z] 19:35:35     INFO - GECKO(9128) | #30: NS_internal_main(int, char**, char**) [browser/app/nsBrowserApp.cpp:331]
[task 2020-08-27T19:35:35.636Z] 19:35:35     INFO - GECKO(9128) | #31: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2020-08-27T19:35:35.636Z] 19:35:35     INFO - GECKO(9128) | #32: __scrt_common_main_seh() [/builds/worker/workspace/obj-build/browser/app/f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2020-08-27T19:35:35.637Z] 19:35:35     INFO - GECKO(9128) | #33: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x13034]
[task 2020-08-27T19:35:35.639Z] 19:35:35     INFO - GECKO(9128) | #34: RtlUserThreadStart [C:\Windows\SYSTEM32\ntdll.dll + 0x71461]
[task 2020-08-27T19:35:35.639Z] 19:35:35     INFO - GECKO(9128) | [Parent 2828, Main Thread] ###!!! ASSERTION: Negative height passed to nsWindow::Resize: '(height >= 0)', file /builds/worker/checkouts/gecko/widget/windows/nsWindow.cpp, line 1941
[task 2020-08-27T19:35:35.639Z] 19:35:35     INFO - GECKO(9128) | #01: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450587d]
[task 2020-08-27T19:35:35.640Z] 19:35:35     INFO - GECKO(9128) | #02: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450a475]
[task 2020-08-27T19:35:35.640Z] 19:35:35     INFO - GECKO(9128) | #03: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450c40d]
[task 2020-08-27T19:35:35.640Z] 19:35:35     INFO - GECKO(9128) | #04: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x450816a]
[task 2020-08-27T19:35:35.642Z] 19:35:35     INFO - GECKO(9128) | #05: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4508055]
[task 2020-08-27T19:35:35.642Z] 19:35:35     INFO - GECKO(9128) | #06: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x464f2e5]
[task 2020-08-27T19:35:35.642Z] 19:35:35     INFO - GECKO(9128) | #07: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x464fa29]
[task 2020-08-27T19:35:35.643Z] 19:35:35     INFO - GECKO(9128) | #08: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x464250b]
[task 2020-08-27T19:35:35.643Z] 19:35:35     INFO - GECKO(9128) | #09: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x46412de]
[task 2020-08-27T19:35:35.645Z] 19:35:35     INFO - GECKO(9128) | #10: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x6e1af]
[task 2020-08-27T19:35:35.645Z] 19:35:35     INFO - GECKO(9128) | #11: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4636810]
[task 2020-08-27T19:35:35.645Z] 19:35:35     INFO - GECKO(9128) | #12: CallWindowProcW [C:\Windows\System32\USER32.dll + 0x16cc1]
[task 2020-08-27T19:35:35.645Z] 19:35:35     INFO - GECKO(9128) | #13: CallWindowProcW [C:\Windows\System32\USER32.dll + 0x1699c]
[task 2020-08-27T19:35:35.647Z] 19:35:35     INFO - GECKO(9128) | #14: IsWindowVisible [C:\Windows\System32\USER32.dll + 0x20453]
[task 2020-08-27T19:35:35.647Z] 19:35:35     INFO - GECKO(9128) | #15: KiUserCallbackDispatcher [C:\Windows\SYSTEM32\ntdll.dll + 0x9dc04]
[task 2020-08-27T19:35:35.649Z] 19:35:35     INFO - GECKO(9128) | #16: NtUserDispatchMessage [C:\Windows\System32\win32u.dll + 0x1744]
[task 2020-08-27T19:35:35.649Z] 19:35:35     INFO - GECKO(9128) | #17: DispatchMessageW [C:\Windows\System32\USER32.dll + 0x16700]
[task 2020-08-27T19:35:35.650Z] 19:35:35     INFO - GECKO(9128) | #18: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4607c91]
[task 2020-08-27T19:35:35.650Z] 19:35:35     INFO - GECKO(9128) | #19: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4554037]
[task 2020-08-27T19:35:35.650Z] 19:35:35     INFO - GECKO(9128) | #20: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x1a8c14]
[task 2020-08-27T19:35:35.650Z] 19:35:35     INFO - GECKO(9128) | #21: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0x1af3ab]
[task 2020-08-27T19:35:35.652Z] 19:35:35     INFO - GECKO(9128) | #22: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa6970a]
[task 2020-08-27T19:35:35.652Z] 19:35:35     INFO - GECKO(9128) | #23: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa07080]
[task 2020-08-27T19:35:35.652Z] 19:35:35     INFO - GECKO(9128) | #24: Ordinal0 [Z:\task_1598553921\build\application\firefox\xul.dll + 0xa06f32]
[task 2020-08-27T19:35:35.654Z] 19:35:35     INFO - GECKO(9128) | #25: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x4553dd8]
[task 2020-08-27T19:35:35.654Z] 19:35:35     INFO - GECKO(9128) | #26: soundtouch::SoundTouch::operator= [Z:\task_1598553921\build\application\firefox\xul.dll + 0x460740a]
[task 2020-08-27T19:35:35.654Z] 19:35:35     INFO - GECKO(9128) | #27: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x60461b2]
[task 2020-08-27T19:35:35.656Z] 19:35:35     INFO - GECKO(9128) | #28: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618af5f]
[task 2020-08-27T19:35:35.656Z] 19:35:35     INFO - GECKO(9128) | #29: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618d4d9]
[task 2020-08-27T19:35:35.656Z] 19:35:35     INFO - GECKO(9128) | #30: workerlz4_maxCompressedSize [Z:\task_1598553921\build\application\firefox\xul.dll + 0x618ddaa]
[task 2020-08-27T19:35:35.657Z] 19:35:35     INFO - GECKO(9128) | #31: NS_internal_main(int, char**, char**) [browser/app/nsBrowserApp.cpp:331]
Flags: needinfo?(jfkthame)

This got frequent again, this time during the execution of browser_corrupt.js: 23 Windows browser-chrome tasks which logs too big to parse on production trees since April 30. The logs I checked are ~1GB each.

Summary: Intermittent toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | Main Thread] ###!!! ASSERTION: Negative height passed to nsWindow::Resize: '(height >= 0)', file z:/build/build/src/widget/windows/nsWindow.cpp, line 1834 → Intermittent toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js,browser_corrupt.js | Main Thread] ###!!! ASSERTION: Negative height passed to nsWindow::Resize: '(height >= 0)', file z:/build/build/src/widget/windows/nsWindow.cpp
Status: REOPENED → RESOLVED
Closed: 5 years ago → 3 years ago
Resolution: --- → FIXED
Assignee: nobody → kats

Umm this is not fixed.

Assignee: kats → nobody
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Severity: normal → S4
Priority: -- → P5

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

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

Attachment

General

Creator:
Created:
Updated:
Size: