1754998 - Intermittent TV gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js | expect scroll position and visual scroll position to be the same: visual 20000 scroll 10000 (4,1) -

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=367556953&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YHNGZ8leTISsY1yrsWKCPA/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/YHNGZ8leTISsY1yrsWKCPA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2022-02-11T12:33:32.321Z] 12:33:32     INFO - TEST-START | gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js
[task 2022-02-11T12:33:32.545Z] 12:33:32     INFO - GECKO(1148) | Done loading background tab
[task 2022-02-11T12:33:35.726Z] 12:33:35     INFO - GECKO(1148) | Switched background tab to foreground
[task 2022-02-11T12:33:35.732Z] 12:33:35     INFO - GECKO(1148) | WaitUntilApzStable: flushed APZ repaints in parent proc, waiting for callback...
[task 2022-02-11T12:33:35.735Z] 12:33:35     INFO - GECKO(1148) | WaitUntilApzStable: APZ flush done in parent proc
[task 2022-02-11T12:33:35.735Z] 12:33:35     INFO - GECKO(1148) | WaitUntilApzStable: got apz-flush-done in child proc
[task 2022-02-11T12:33:35.736Z] 12:33:35     INFO - GECKO(1148) | WaitUntilApzStable: done promiseFocus
<...>
[task 2022-02-11T12:34:05.878Z] 12:34:05     INFO - GECKO(1148) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-02-11T12:34:05.879Z] 12:34:05     INFO - GECKO(1148) | WaitUntilApzStable: all done
[task 2022-02-11T12:34:05.907Z] 12:34:05     INFO - GECKO(1148) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-02-11T12:34:05.911Z] 12:34:05     INFO - GECKO(1148) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-02-11T12:34:05.924Z] 12:34:05     INFO - TEST-INFO | started process screenshot
[task 2022-02-11T12:34:06.014Z] 12:34:06     INFO - TEST-INFO | screenshot: exit 0
[task 2022-02-11T12:34:06.020Z] 12:34:06     INFO - Buffered messages logged at 12:33:32
[task 2022-02-11T12:34:06.020Z] 12:34:06     INFO - Entering test bound test_main
[task 2022-02-11T12:34:06.021Z] 12:34:06     INFO - Buffered messages logged at 12:33:35
[task 2022-02-11T12:34:06.021Z] 12:34:06     INFO - TEST-PASS | gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js | expect scroll position and visual scroll position to be the same: visual 10000 scroll 10000 (0,1) - 
[task 2022-02-11T12:34:06.021Z] 12:34:06     INFO - Buffered messages logged at 12:33:43
[task 2022-02-11T12:34:06.022Z] 12:34:06     INFO - TEST-PASS | gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js | expect scroll position and visual scroll position to be the same: visual 10000 scroll 10000 (1,1) - 
[task 2022-02-11T12:34:06.022Z] 12:34:06     INFO - Buffered messages logged at 12:33:58
[task 2022-02-11T12:34:06.023Z] 12:34:06     INFO - TEST-PASS | gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js | expect scroll position and visual scroll position to be the same: visual 10000 scroll 10000 (3,1) - 
[task 2022-02-11T12:34:06.023Z] 12:34:06     INFO - Buffered messages logged at 12:34:02
[task 2022-02-11T12:34:06.024Z] 12:34:06     INFO - TEST-PASS | gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js | expect scroll position and visual scroll position to be the same: visual 20000 scroll 20000 (3,2) - 
[task 2022-02-11T12:34:06.024Z] 12:34:06     INFO - Buffered messages finished
[task 2022-02-11T12:34:06.025Z] 12:34:06     INFO - TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js | expect scroll position and visual scroll position to be the same: visual 20000 scroll 10000 (4,1) - 
[task 2022-02-11T12:34:06.025Z] 12:34:06     INFO - Stack trace:
[task 2022-02-11T12:34:06.025Z] 12:34:06     INFO - chrome://mochikit/content/browser-test.js:test_ok:1394
[task 2022-02-11T12:34:06.025Z] 12:34:06     INFO - chrome://mochitests/content/browser/gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js:checkScrollPosInContent:79
[task 2022-02-11T12:34:09.928Z] 12:34:09     INFO - GECKO(1148) | Switched background tab to foreground
[task 2022-02-11T12:34:09.936Z] 12:34:09     INFO - GECKO(1148) | WaitUntilApzStable: flushed APZ repaints in parent proc, waiting for callback...
[task 2022-02-11T12:34:09.937Z] 12:34:09     INFO - GECKO(1148) | WaitUntilApzStable: APZ flush done in parent proc
[task 2022-02-11T12:34:09.937Z] 12:34:09     INFO - GECKO(1148) | WaitUntilApzStable: got apz-flush-done in child proc
[task 2022-02-11T12:34:09.938Z] 12:34:09     INFO - GECKO(1148) | WaitUntilApzStable: done promiseFocus
[task 2022-02-11T12:34:09.938Z] 12:34:09     INFO - GECKO(1148) | WaitUntilApzStable: done promiseAllPaintsDone
[task 2022-02-11T12:34:09.939Z] 12:34:09     INFO - GECKO(1148) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-02-11T12:34:09.939Z] 12:34:09     INFO - GECKO(1148) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-02-11T12:34:09.941Z] 12:34:09     INFO - GECKO(1148) | WaitUntilApzStable: all done
[task 2022-02-11T12:34:09.947Z] 12:34:09     INFO - GECKO(1148) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-02-11T12:34:09.947Z] 12:34:09     INFO - GECKO(1148) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-02-11T12:34:09.981Z] 12:34:09     INFO - TEST-PASS | gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js | expect scroll position and visual scroll position to be the same: visual 30000 scroll 30000 (4,2) - 
[task 2022-02-11T12:34:10.002Z] 12:34:10     INFO - Leaving test bound test_main
[task 2022-02-11T12:34:10.021Z] 12:34:10     INFO - GECKO(1148) | MEMORY STAT | vsize 2104107MB | vsizeMaxContiguous 69728579MB | residentFast 211MB | heapAllocated 82MB
[task 2022-02-11T12:34:10.035Z] 12:34:10     INFO - TEST-OK | gfx/layers/apz/test/mochitest/browser_test_background_tab_load_scroll.js | took 37714ms
[task 2022-02-11T12:34:10.047Z] 12:34:10     INFO - checking window state
[task 2022-02-11T12:34:10.056Z] 12:34:10     INFO - GECKO(1148) | Completed ShutdownLeaks collections in process 1948
[task 2022-02-11T12:34:10.057Z] 12:34:10     INFO - TEST-START | Shutdown

Comment 1

[Timothy Nikkel (:tnikkel)]

Huh, weird, despite extension TV runs on try I never saw this.

Comment 2

[Timothy Nikkel (:tnikkel)]

Anyways, if this keeps happening I expect bug 1753881 would fix this.

Comment 3

[Intermittent Failures Robot]

1 failures in 3711 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1754998&startday=2022-02-07&endday=2022-02-13&tree=all

Comment 4

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 5

[Timothy Nikkel (:tnikkel)]

Bug 1756762 should fix this.

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

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