Closed Bug 1636476 Opened 4 years ago Closed 1 year ago

Intermittent accessible/tests/browser/events/browser_test_scrolling.js | Test timed out -

Categories

(Core :: Disability Access APIs, defect, P5)

defect

Tracking

()

RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

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

Attachments

(2 files)

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


[task 2020-05-08T13:53:41.699Z] 13:53:41 INFO - TEST-START | accessible/tests/browser/events/browser_test_scrolling.js
[task 2020-05-08T13:54:26.737Z] 13:54:26 INFO - TEST-INFO | started process screenshot
[task 2020-05-08T13:54:26.796Z] 13:54:26 INFO - TEST-INFO | screenshot: exit 0
[task 2020-05-08T13:54:26.796Z] 13:54:26 INFO - Buffered messages logged at 13:53:41
[task 2020-05-08T13:54:26.796Z] 13:54:26 INFO - Entering test bound
[task 2020-05-08T13:54:26.796Z] 13:54:26 INFO - Buffered messages logged at 13:53:42
[task 2020-05-08T13:54:26.796Z] 13:54:26 INFO - TEST-PASS | accessible/tests/browser/events/browser_test_scrolling.js | e10s enabled -
[task 2020-05-08T13:54:26.797Z] 13:54:26 INFO - TEST-PASS | accessible/tests/browser/events/browser_test_scrolling.js | Actually remote browser -
[task 2020-05-08T13:54:26.797Z] 13:54:26 INFO - Buffered messages finished
[task 2020-05-08T13:54:26.797Z] 13:54:26 INFO - TEST-UNEXPECTED-FAIL | accessible/tests/browser/events/browser_test_scrolling.js | Test timed out -
[task 2020-05-08T13:54:26.797Z] 13:54:26 INFO - GECKO(8060) | MEMORY STAT | vsize 2104224MB | vsizeMaxContiguous 67324315MB | residentFast 273MB | heapAllocated 86MB
[task 2020-05-08T13:54:26.797Z] 13:54:26 INFO - TEST-OK | accessible/tests/browser/events/browser_test_scrolling.js | took 45096ms

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

In the past week, we had 44 occurrences for this failure all on windows10-64
Recent failure: https://treeherder.mozilla.org/logviewer?job_id=339769907&repo=autoland&lineNumber=2387

[task 2021-05-15T04:20:23.756Z] 04:20:23 INFO - TEST-START | accessible/tests/browser/events/browser_test_scrolling.js
[task 2021-05-15T04:21:08.803Z] 04:21:08 INFO - TEST-INFO | started process screenshot
[task 2021-05-15T04:21:08.856Z] 04:21:08 INFO - TEST-INFO | screenshot: exit 0
[task 2021-05-15T04:21:08.857Z] 04:21:08 INFO - Buffered messages logged at 04:20:23
[task 2021-05-15T04:21:08.857Z] 04:21:08 INFO - Entering test bound
[task 2021-05-15T04:21:08.858Z] 04:21:08 INFO - Buffered messages logged at 04:20:24
[task 2021-05-15T04:21:08.858Z] 04:21:08 INFO - TEST-PASS | accessible/tests/browser/events/browser_test_scrolling.js | Actually remote browser -
[task 2021-05-15T04:21:08.859Z] 04:21:08 INFO - Buffered messages finished
[task 2021-05-15T04:21:08.859Z] 04:21:08 INFO - TEST-UNEXPECTED-FAIL | accessible/tests/browser/events/browser_test_scrolling.js | Test timed out -
[task 2021-05-15T04:21:08.866Z] 04:21:08 INFO - GECKO(11040) | MEMORY STAT | vsize 2104149MB | vsizeMaxContiguous 65212664MB | residentFast 217MB | heapAllocated 76MB
[task 2021-05-15T04:21:08.867Z] 04:21:08 INFO - TEST-OK | accessible/tests/browser/events/browser_test_scrolling.js | took 45111ms
[task 2021-05-15T04:21:08.883Z] 04:21:08 INFO - checking window state

Whiteboard: [stockwell needswork]

James can you please assign someone to take a look?

Flags: needinfo?(jteh)
Whiteboard: [stockwell needswork] → [stockwell needswork:owner]

Looks like yet another instance of bug 1708336. We still don't have any clue what's going on there. :(

Morgan, would you mind describing the screenshot here in contrast to the other screenshots related to bug 1708336? is this another "blank page" case?

Depends on: 1708336
Flags: needinfo?(jteh) → needinfo?(mreschenberg)

Sure thing :)

All the screenshots in the most recent logset are the same: There are two tabs "New Tab" which is in the background, and "Accessibility Test" which is in the foreground. The web area of the foreground contains the text "one". The URL bar contains (presumably) the test URI and is shaded red

Flags: needinfo?(mreschenberg)

So the document is loading in this case and we're just not getting any a11y events for it. 😩

I looked into enabling a11y engine logging for this test, but that's going to be more complicated than I anticipated. Calling enableLogging (or accService.setLogging) only enables it for the current process, which in this case will be the parent process. We need it for the content process. Unfortunately, we can't get the remote browser until after we've loaded the page, which is too late; we need content process logging to start before that.

Keywords: leave-open

I can't reproduce this on try, so I'm landing this so I can get (hopefully) useful info next time it fails.

Despite A11YLOG being enabled (and the log shows that the environment variable got set), I don't see any a11y logging in the log. I guess the CI logs don't capture stdout or something? I don't understand how I'm supposed to get the logging I need to figure this out.

There are 48 total failures in the last 7 days on

[task 2021-05-24T22:10:13.667Z] 22:10:13 INFO - TEST-START | accessible/tests/browser/events/browser_test_scrolling.js
[task 2021-05-24T22:10:58.703Z] 22:10:58 INFO - TEST-INFO | started process screenshot
[task 2021-05-24T22:10:58.765Z] 22:10:58 INFO - TEST-INFO | screenshot: exit 0
[task 2021-05-24T22:10:58.765Z] 22:10:58 INFO - Buffered messages logged at 22:10:13
[task 2021-05-24T22:10:58.766Z] 22:10:58 INFO - Entering test bound
[task 2021-05-24T22:10:58.766Z] 22:10:58 INFO - TEST-PASS | accessible/tests/browser/events/browser_test_scrolling.js | Actually remote browser -
[task 2021-05-24T22:10:58.766Z] 22:10:58 INFO - Buffered messages finished
[task 2021-05-24T22:10:58.766Z] 22:10:58 INFO - TEST-UNEXPECTED-FAIL | accessible/tests/browser/events/browser_test_scrolling.js | Test timed out -
[task 2021-05-24T22:10:58.767Z] 22:10:58 INFO - GECKO(7568) | MEMORY STAT | vsize 2104147MB | vsizeMaxContiguous 65698486MB | residentFast 213MB | heapAllocated 75MB
[task 2021-05-24T22:10:58.767Z] 22:10:58 INFO - TEST-OK | accessible/tests/browser/events/browser_test_scrolling.js | took 45101ms
[task 2021-05-24T22:10:58.786Z] 22:10:58 INFO - checking window state

Pushed by abutkovits@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/0b1278440fb2
disable browser_test_scrolling.js on Win_64_opt for frequent failures. r=intermittent-reviewers,bhearsum DONTBUILD
Whiteboard: [stockwell needswork:owner] → [stockwell disabled]
Regressions: 1714067
See Also: → 1713886
Severity: normal → S3
Status: REOPENED → RESOLVED
Closed: 3 years ago1 year ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: