Closed Bug 1668919 Opened 4 years ago Closed 3 years ago

Intermittent [Fission] docshell/test/browser/browser_tab_touch_events.js | Test timed out - | Found a tab after previous test timed out

Categories

(Core :: DOM: Events, defect, P3)

defect

Tracking

()

RESOLVED FIXED
85 Branch
Fission Milestone M6c
Tracking Status
firefox83 --- disabled
firefox84 --- disabled
firefox85 --- fixed

People

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

Details

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

Attachments

(1 file)

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


[task 2020-10-02T18:05:01.940Z] 18:05:01     INFO - TEST-PASS | docshell/test/browser/browser_tab_touch_events.js | Newly created frames should use the new touchEventsOverride flag - 0 == 0 - 
[task 2020-10-02T18:05:01.940Z] 18:05:01     INFO - Buffered messages finished
[task 2020-10-02T18:05:01.941Z] 18:05:01     INFO - TEST-UNEXPECTED-FAIL | docshell/test/browser/browser_tab_touch_events.js | Test timed out - 
[task 2020-10-02T18:05:01.942Z] 18:05:01     INFO - GECKO(6943) | MEMORY STAT | vsize 3357MB | residentFast 359MB | heapAllocated 98MB
[task 2020-10-02T18:05:01.942Z] 18:05:01     INFO - TEST-OK | docshell/test/browser/browser_tab_touch_events.js | took 90068ms
[task 2020-10-02T18:05:01.943Z] 18:05:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-10-02T18:05:01.943Z] 18:05:01     INFO - TEST-UNEXPECTED-FAIL | docshell/test/browser/browser_tab_touch_events.js | Found a tab after previous test timed out: data:text/html;charset=utf-8,<iframe id='test-iframe'></iframe> - 
[task 2020-10-02T18:05:01.944Z] 18:05:01     INFO - GECKO(6943) | [Child 7197: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7ff1cc45e400 == 2 [pid = 7197] [id = 57] [url = about:blank]
[task 2020-10-02T18:05:01.945Z] 18:05:01     INFO - GECKO(6943) | [Child 7367: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fbad8bab400 == 1 [pid = 7367] [id = 11]
[task 2020-10-02T18:05:01.945Z] 18:05:01     INFO - GECKO(6943) | [Child 7367: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7fbaf6393180) [pid = 7367] [serial = 28] [outer = (nil)]
[task 2020-10-02T18:05:01.946Z] 18:05:01     INFO - GECKO(6943) | [Child 7367: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7fbad8bac400) [pid = 7367] [serial = 29] [outer = 0x7fbaf6393180]
[task 2020-10-02T18:05:01.946Z] 18:05:01     INFO - checking window state```
Summary: Intermittent docshell/test/browser/browser_tab_touch_events.js | Test timed out - | Found a tab after previous test timed out → Intermittent [Fission] docshell/test/browser/browser_tab_touch_events.js | Test timed out - | Found a tab after previous test timed out

Tracking for Fission Nightly milestone M6c.

Fission Milestone: --- → M6c

Masayuki, can you debug this?

Flags: needinfo?(masayuki)

I'm not familiar with touch events, edgar, how about you? If not, I'll take a look (feel free to say "do not have much time").

Flags: needinfo?(masayuki) → needinfo?(echen)

Not super familiar, but I could reproduce this locally, I will take a look.

Flags: needinfo?(echen)

This actually isn't related to the touch event, the test got timeout because it doesn't receive load event from iframe after calling reload(). Look at the entire history of this intermittent-failure, it starts happening from 2020-10-02 and only on Fission, I suspect there might be some changes cause this issue. Neha, do you know who is the best person to ni? Thanks!

Flags: needinfo?(nkochar)

(In reply to Edgar Chen [:edgar] from comment #7)

I could reproduce this locally.

I bisect down manually, it pointers at https://hg.mozilla.org/integration/autoland/pushloghtml?fromchange=ee7eeea3fb66321fabf86ffd3a1caf4fa3511e43&tochange=1aa67f749387d4d6d26f7d69511434ac71112110, seems to relate to SHIP.

Flags: needinfo?(peterv)
Flags: needinfo?(nkochar)
Flags: needinfo?(bugs)

This bug failed 34 time in the last 7 days.
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=320336191&repo=mozilla-central&lineNumber=18269

Olli, Peter, could you look into this?

This bug failed 39 times in the last 7 days. Fails on linux1804-64 and windows10-64-shippable on opt and debug build types.

Recent log:
https://treeherder.mozilla.org/logviewer?job_id=321011638&repo=autoland&lineNumber=11261

Olli, Peter: Any updates on this bug?

Assignee: nobody → bugs
Status: NEW → ASSIGNED
Flags: needinfo?(peterv)
Flags: needinfo?(bugs)
Priority: -- → P3

In the last 7 days there have been 23 occurrences, most on linux1804-64 debug and some on windows10-64-shippable opt.

Recent failure: https://treeherder.mozilla.org/logviewer?job_id=322550669&repo=mozilla-central&lineNumber=7374

As far as I see the test is racy. It adds event listener to a window object, which reload() will replace.
So if the load event for non-transient about:blank gets chance to fire, then the test passes without the patch.
The main thing the patch does is to listen for the load event on the iframe element, since that object isn't
replaced.

The intermittent failure happens quite rarely and not locally, so the patch is based on code inspection.
Locally the test was run successfully 100 times both with opt and debug builds.

https://treeherder.mozilla.org/#/jobs?repo=try&revision=8b3c65c89c55cd16bf57cf0b78db9ee99ba23df2

Pushed by opettay@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/4ab108ce38da
try to make browser_tab_touch_events.js less racy, r=edgar
Status: ASSIGNED → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 85 Branch

Since the status are different for nightly and release, what's the status for beta?
For more information, please visit auto_nag documentation.

You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: