Intermittent layout/generic/test/test_scroll_position_restore.html | undefined assertion name - got 54560, expected 348260
Categories
(Core :: Layout, defect, P5)
Tracking
()
Tracking | Status | |
---|---|---|
firefox84 | --- | fixed |
People
(Reporter: intermittent-bug-filer, Assigned: ccoroiu)
References
(Regression)
Details
(Keywords: intermittent-failure, regression, Whiteboard: [retriggered])
Attachments
(1 file, 1 obsolete file)
Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=317469379&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aHhGdrn-TqS-TTdgX3qVgQ/runs/0/artifacts/public/logs/live_backing.log
[task 2020-10-03T10:06:52.944Z] 10:06:52 INFO - TEST-START | layout/generic/test/test_scroll_position_restore.html
[task 2020-10-03T10:06:52.949Z] 10:06:52 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:06:52.949Z] 10:06:52 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:06:52.949Z] 10:06:52 INFO - GECKO(1116) | [GPU 9040, Compositor] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:06:52.974Z] 10:06:52 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:06:52.974Z] 10:06:52 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:06:52.974Z] 10:06:52 INFO - GECKO(1116) | [GPU 9040, Compositor] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:06:53.036Z] 10:06:53 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:06:53.036Z] 10:06:53 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:06:53.036Z] 10:06:53 INFO - GECKO(1116) | [GPU 9040, Compositor] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.634Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.634Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.635Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Compositor] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.653Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.653Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.653Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Compositor] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.672Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.672Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.672Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Compositor] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.683Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.683Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Renderer] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.684Z] 10:07:18 INFO - GECKO(1116) | [GPU 9040, Compositor] WARNING: Failed to duplicate file handle for current process!: file /builds/worker/checkouts/gecko/ipc/glue/FileDescriptor.cpp, line 124
[task 2020-10-03T10:07:18.702Z] 10:07:18 INFO - TEST-INFO | started process screenshot
[task 2020-10-03T10:07:18.782Z] 10:07:18 INFO - TEST-INFO | screenshot: exit 0
[task 2020-10-03T10:07:18.782Z] 10:07:18 INFO - TEST-UNEXPECTED-FAIL | layout/generic/test/test_scroll_position_restore.html | undefined assertion name - got 54560, expected 348260```
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 4•4 years ago
|
||
This seems pretty frequent, if we did some retriggers of past pushes it should be possible to find the commit that regressed this.
Comment 5•4 years ago
|
||
Possibly related to Bug 1609291 ?
On already done backfills and retriggers the failures stop here https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=Windows%2C10%2Cx64%2CWebRender%2Cdebug%2CMochitests%2Ctest-windows10-64-qr%2Fdebug-mochitest-plain-e10s%2C4&tochange=32a2e59ab7260118d8d5f430e503967fad6993cf&fromchange=a9213deea8e646493c35077011fc25648ddbf95a&group_state=expanded&selectedTaskRun=ceAUd-MWSgWWrrqDYDABbA.0
However, OF shows that it fails on different mochitest plain jobs, so filtered by the test group: https://treeherder.mozilla.org/#/jobs?repo=autoland&group_state=expanded&tochange=32a2e59ab7260118d8d5f430e503967fad6993cf&fromchange=22be77d681b64b8c04fb53e86737cb195f5ccc5c&test_paths=layout%2Fgeneric%2Ftest%2Fmochitest.ini .
Comment hidden (Intermittent Failures Robot) |
Comment 7•4 years ago
|
||
I did a bunch of try pushes, from those it's pretty clear that this is caused by bug 1665196.
Updated•4 years ago
|
Comment 8•4 years ago
|
||
There are 47 total failures in the last 7 days on windows10-64 debug, windows10-64-qr debug, windows7-32 debug
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=318926946&repo=autoland&lineNumber=28645
[task 2020-10-17T16:35:00.296Z] 16:35:00 INFO - TEST-START | layout/generic/test/test_scroll_position_restore.html
[task 2020-10-17T16:35:05.913Z] 16:35:05 INFO - GECKO(6552) | [Child 8188, Main Thread] WARNING: flushing shaped-word cache: file /builds/worker/checkouts/gecko/gfx/thebes/gfxFont.cpp:2742
[task 2020-10-17T16:35:15.358Z] 16:35:15 INFO - GECKO(6552) | [Child 8188, Main Thread] WARNING: flushing shaped-word cache: file /builds/worker/checkouts/gecko/gfx/thebes/gfxFont.cpp:2742
[task 2020-10-17T16:35:16.828Z] 16:35:16 INFO - GECKO(6552) | [Child 4480, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8492
[task 2020-10-17T16:35:16.828Z] 16:35:16 INFO - GECKO(6552) | [Child 4480, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8492
[task 2020-10-17T16:35:16.829Z] 16:35:16 INFO - GECKO(6552) | [Child 4480, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8492
[task 2020-10-17T16:35:16.829Z] 16:35:16 INFO - GECKO(6552) | [Child 4480, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8492
[task 2020-10-17T16:35:16.834Z] 16:35:16 INFO - GECKO(6552) | [Child 4480, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8492
[task 2020-10-17T16:35:16.834Z] 16:35:16 INFO - GECKO(6552) | [Child 4480, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8492
[task 2020-10-17T16:35:17.034Z] 16:35:17 INFO - GECKO(6552) | [Child 8188, Main Thread] WARNING: flushing shaped-word cache: file /builds/worker/checkouts/gecko/gfx/thebes/gfxFont.cpp:2742
[task 2020-10-17T16:35:22.658Z] 16:35:22 INFO - GECKO(6552) | [Child 8188, Main Thread] WARNING: flushing shaped-word cache: file /builds/worker/checkouts/gecko/gfx/thebes/gfxFont.cpp:2742
[task 2020-10-17T16:35:24.763Z] 16:35:24 INFO - TEST-INFO | started process screenshot
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - TEST-INFO | screenshot: exit 0
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - TEST-UNEXPECTED-FAIL | layout/generic/test/test_scroll_position_restore.html | undefined assertion name - got 306240, expected 348260
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - handleLoad/<@layout/generic/test/test_scroll_position_restore.html:32:9
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - waitForPaints@SimpleTest/paint_listener.js:90:14
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - window.waitForAllPaintsFlushed@SimpleTest/paint_listener.js:94:18
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - handleLoad@layout/generic/test/test_scroll_position_restore.html:30:14
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - onload@layout/generic/test/file_scroll_position_restore.html:1:15
[task 2020-10-17T16:35:24.825Z] 16:35:24 INFO - GECKO(6552) | MEMORY STAT | vsize 2104338MB | vsizeMaxContiguous 71147857MB | residentFast 320MB | heapAllocated 164MB
[task 2020-10-17T16:35:25.977Z] 16:35:25 INFO - GECKO(6552) | [Parent 6200, Main Thread] WARNING: '!aObserver', file /builds/worker/checkouts/gecko/xpcom/ds/nsObserverService.cpp:236
[task 2020-10-17T16:35:25.982Z] 16:35:25 INFO - GECKO(6552) | [NPAPI 2132, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-17T16:35:25.992Z] 16:35:25 INFO - TEST-OK | layout/generic/test/test_scroll_position_restore.html | took 25698ms
Jonathan can you please take a look?
Updated•4 years ago
|
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 10•4 years ago
|
||
Updated•4 years ago
|
Comment 11•4 years ago
|
||
The regressing bug adds a pref (gfx.font_loader.delay=0) to one mochitests.ini for one dir of tests so that the timing of the font loading doesn't mess up some tests in that dir presumably. I want to try adding that pref for this test dir because I would prefer to keep this test enabled as this code is tricky and we do touch it decently often and it's hard to get right (easy to regress it) so I want to keep it enabled everywhere possible.
Comment 12•4 years ago
|
||
Comment 13•4 years ago
|
||
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Updated•4 years ago
|
Assignee | ||
Comment 15•4 years ago
|
||
Timothy, thank you for the patch! I'll mark as obsolete the disable patch.
Comment 16•4 years ago
|
||
bugherder |
Updated•4 years ago
|
Updated•4 years ago
|
Updated•4 years ago
|
Updated•2 years ago
|
Description
•