Closed Bug 1701097 Opened 4 years ago Closed 3 years ago

Intermittent devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | The console is still scrolled to the top after clearing the filter - Got 1952, expected +0

Categories

(DevTools :: Console, defect, P5)

defect

Tracking

(firefox100 fixed)

RESOLVED FIXED
100 Branch
Tracking Status
firefox100 --- fixed

People

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

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

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


INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js
[task 2021-03-25T20:41:53.094Z] 20:41:53     INFO - TEST-INFO | started process screenshot
[task 2021-03-25T20:41:53.359Z] 20:41:53     INFO - TEST-INFO | screenshot: exit 0
[task 2021-03-25T20:41:53.359Z] 20:41:53     INFO - Buffered messages logged at 20:41:49
[task 2021-03-25T20:41:53.359Z] 20:41:53     INFO - Entering test bound 
[task 2021-03-25T20:41:53.359Z] 20:41:53     INFO - Adding a new tab with URL: data:text/html;charset=utf-8,
[task 2021-03-25T20:41:53.359Z] 20:41:53     INFO -   <p>Web Console test for scroll when filtering.</p>
[task 2021-03-25T20:41:53.359Z] 20:41:53     INFO -   <script>
[task 2021-03-25T20:41:53.359Z] 20:41:53     INFO -   for (let i = 0; i < 100; i++) {
[task 2021-03-25T20:41:53.360Z] 20:41:53     INFO -     console.log("init-" + i);
[task 2021-03-25T20:41:53.360Z] 20:41:53     INFO -   }
[task 2021-03-25T20:41:53.360Z] 20:41:53     INFO -   </script>
[task 2021-03-25T20:41:53.360Z] 20:41:53     INFO - 
[task 2021-03-25T20:41:53.360Z] 20:41:53     INFO - Tab added and finished loading
[task 2021-03-25T20:41:53.360Z] 20:41:53     INFO - Opening the toolbox
[task 2021-03-25T20:41:53.360Z] 20:41:53     INFO - Buffered messages logged at 20:41:51
[task 2021-03-25T20:41:53.360Z] 20:41:53     INFO - Toolbox opened and focused
[task 2021-03-25T20:41:53.361Z] 20:41:53     INFO - Console should be scrolled to bottom on initial load from page logs
[task 2021-03-25T20:41:53.361Z] 20:41:53     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | There is a vertical overflow - 
[task 2021-03-25T20:41:53.361Z] 20:41:53     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | The console is scrolled to the bottom - 
[task 2021-03-25T20:41:53.361Z] 20:41:53     INFO - Filter out some messages and check that the scroll position is not impacted
[task 2021-03-25T20:41:53.361Z] 20:41:53     INFO - Buffered messages logged at 20:41:52
[task 2021-03-25T20:41:53.361Z] 20:41:53     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | The console is still scrolled to the bottom after filtering - 
[task 2021-03-25T20:41:53.361Z] 20:41:53     INFO - Clear the text filter and check that the scroll position is not impacted
[task 2021-03-25T20:41:53.361Z] 20:41:53     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | The console is still scrolled to the bottom after clearing the filter - 
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - Scroll up
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | The console is still scrolled to the top after filtering - 
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - Clear the text filter and check that the scroll position is not impacted
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - Buffered messages finished
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | The console is still scrolled to the top after clearing the filter - Got 1952, expected +0
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - Stack trace:
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - chrome://mochikit/content/browser-test.js:test_is:1359
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js:null:75
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1089
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1129
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:949
[task 2021-03-25T20:41:53.362Z] 20:41:53     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:1037
[task 2021-03-25T20:41:53.363Z] 20:41:53     INFO - Leaving test bound 
INFO - Removing tab.
[task 2021-03-25T20:41:53.972Z] 20:41:53     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-03-25T20:41:53.991Z] 20:41:53     INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-03-25T20:41:54.001Z] 20:41:54     INFO - GECKO(6008) | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2021-03-25T20:41:54.026Z] 20:41:54     INFO - Tab removed and finished closing
[task 2021-03-25T20:41:54.126Z] 20:41:54     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-03-25T20:41:54.205Z] 20:41:54     INFO - GECKO(6008) | MEMORY STAT | vsize 19406457MB | vsizeMaxContiguous 65262416MB | residentFast 1558MB
[task 2021-03-25T20:41:54.205Z] 20:41:54     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_webconsole_filter_scroll.js | took 4681ms
[task 2021-03-25T20:41:54.244Z] 20:41:54     INFO - checking window state
[task 2021-03-25T20:41:54.283Z] 20:41:54     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_filters.js```
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

The test is using waitFor for something which doesn't really depend on timer.

But there is something else happening too. Even with very long interval it fails every now and then and one can visually see the scrollable area is scrolled down.

Bug 1755006 seems to make this happen even more likely. That bug modifies when refreshdriver ticks, so it may change the timing of scroll events and rAF and other things which are driven by a refreshdriver.

Blocks: 1755006
Assignee: nobody → bugs
Pushed by opettay@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/7496c38f08a9 stabilize layout in browser_webconsole_filter_scroll.js before quering scroll position, r=nchevobbe
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 100 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: