Open Bug 1605546 Opened 4 years ago Updated 2 years ago

Intermittent toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js | Uncaught exception - Waiting for the hovering state to be set on the video. - timed out after 50 tries.

Categories

(Toolkit :: Picture-in-Picture, defect, P5)

defect

Tracking

()

ASSIGNED

People

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

References

(Blocks 1 open bug, Regression)

Details

(Keywords: intermittent-failure, leave-open, regression, Whiteboard: [stockwell disabled][stockwell needswork:owner] [pip-needs-eng-triage])

Attachments

(1 file)

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


[task 2019-12-21T04:19:47.934Z] 04:19:47 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js
[task 2019-12-21T04:19:53.682Z] 04:19:53 INFO - TEST-INFO | started process screentopng
[task 2019-12-21T04:19:54.088Z] 04:19:54 INFO - TEST-INFO | screentopng: exit 0
[task 2019-12-21T04:19:54.089Z] 04:19:54 INFO - Buffered messages logged at 04:19:47
[task 2019-12-21T04:19:54.089Z] 04:19:54 INFO - Entering test bound
[task 2019-12-21T04:19:54.090Z] 04:19:54 INFO - Buffered messages logged at 04:19:48
[task 2019-12-21T04:19:54.090Z] 04:19:54 INFO - Testing with-controls case.
[task 2019-12-21T04:19:54.090Z] 04:19:54 INFO - Checking toggle policy
[task 2019-12-21T04:19:54.091Z] 04:19:54 INFO - Buffered messages finished
[task 2019-12-21T04:19:54.092Z] 04:19:54 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js | Uncaught exception - Waiting for the hovering state to be set on the video. - timed out after 50 tries.
[task 2019-12-21T04:19:54.092Z] 04:19:54 INFO - Leaving test bound
[task 2019-12-21T04:19:54.093Z] 04:19:54 INFO - GECKO(13844) | MEMORY STAT | vsize 3204MB | residentFast 357MB | heapAllocated 125MB
[task 2019-12-21T04:19:54.093Z] 04:19:54 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js | took 5761ms
[task 2019-12-21T04:19:54.093Z] 04:19:54 INFO - checking window state
[task 2019-12-21T04:19:54.094Z] 04:19:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-21T04:19:54.094Z] 04:19:54 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js | Found an unexpected browser window at the end of test run -
[task 2019-12-21T04:19:54.095Z] 04:19:54 INFO - GECKO(13844) | must wait for focus

Regressed by: 1592286
Has Regression Range: --- → yes
Keywords: regression
Blocks: videopip
Component: General → Video/Audio Controls

This is showing up in the disable recommended filter.
Mike, should we disable this?

Flags: needinfo?(mconley)
Assignee: nobody → shindli
Status: NEW → ASSIGNED
Flags: needinfo?(mconley)
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]

:mconley - fyi, this test is being disabled on some platforms for failing too frequently; the bug is left open in hopes of follow-up.

Flags: needinfo?(mconley)
Pushed by shindli@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/8b24b57e5180
disable browser_toggleAfterTabTearOutIn.js for frequent failures. r=gbrown
Pushed by shindli@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/c3f18d3c14e6
added missing = to patch CLOSED TREE

Okay, thanks for letting me know.

Flags: needinfo?(mconley)

There are 28 failures in the last 7 days on

  • windows10-64-qr
  • windows7-32 debug

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=304348518&repo=autoland&lineNumber=50149

[task 2020-05-30T00:45:16.920Z] 00:45:16 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js
[task 2020-05-30T00:45:16.943Z] 00:45:16 INFO - GECKO(3120) | [Child 6576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0EACE400 == 8 [pid = 6576] [id = {4acfc9ef-42db-4d03-805d-c4c1ad598677}]
[task 2020-05-30T00:45:16.943Z] 00:45:16 INFO - GECKO(3120) | [Child 6576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 28 (0B634CE0) [pid = 6576] [serial = 96] [outer = 00000000]
[task 2020-05-30T00:45:16.943Z] 00:45:16 INFO - GECKO(3120) | [Child 6576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 29 (14CE5C00) [pid = 6576] [serial = 97] [outer = 0B634CE0]
[task 2020-05-30T00:45:17.103Z] 00:45:17 INFO - GECKO(3120) | [Child 6576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 30 (151E5C00) [pid = 6576] [serial = 98] [outer = 0B634CE0]
[task 2020-05-30T00:45:17.305Z] 00:45:17 INFO - GECKO(3120) | [Parent 6160: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1896CC00 == 10 [pid = 6160] [id = {3a885ad7-51f3-4306-8609-55dde6e8d988}]
[task 2020-05-30T00:45:17.305Z] 00:45:17 INFO - GECKO(3120) | [Parent 6160: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 53 (142EC9A0) [pid = 6160] [serial = 68] [outer = 00000000]
[task 2020-05-30T00:45:17.305Z] 00:45:17 INFO - GECKO(3120) | [Parent 6160: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 54 (1ABA7C00) [pid = 6160] [serial = 69] [outer = 142EC9A0]

[task 2020-05-30T00:45:23.404Z] 00:45:23 INFO - TEST-INFO | screenshot: exit 0
[task 2020-05-30T00:45:23.404Z] 00:45:23 INFO - Buffered messages logged at 00:45:16
[task 2020-05-30T00:45:23.404Z] 00:45:23 INFO - Entering test bound
[task 2020-05-30T00:45:23.407Z] 00:45:23 INFO - Buffered messages logged at 00:45:18
[task 2020-05-30T00:45:23.407Z] 00:45:23 INFO - Testing with-controls case.
[task 2020-05-30T00:45:23.408Z] 00:45:23 INFO - Checking toggle policy
[task 2020-05-30T00:45:23.408Z] 00:45:23 INFO - Buffered messages finished
[task 2020-05-30T00:45:23.408Z] 00:45:23 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js | Uncaught exception - Waiting for the hovering state to be set on the video. - timed out after 50 tries.
[task 2020-05-30T00:45:23.409Z] 00:45:23 INFO - Leaving test bound
[task 2020-05-30T00:45:23.409Z] 00:45:23 INFO - GECKO(3120) | MEMORY STAT | vsize 916MB | vsizeMaxContiguous 401MB | residentFast 304MB | heapAllocated 116MB
[task 2020-05-30T00:45:23.409Z] 00:45:23 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js | took 6364ms
[task 2020-05-30T00:45:23.410Z] 00:45:23 INFO - GECKO(3120) | [Child 7036: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 06AD4800 == 2 [pid = 7036] [id = {78e86734-e63a-42b9-8758-486387cd277c}]
[task 2020-05-30T00:45:23.410Z] 00:45:23 INFO - GECKO(3120) | [Child 7036: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (06AF1020) [pid = 7036] [serial = 55] [outer = 00000000]
[task 2020-05-30T00:45:23.410Z] 00:45:23 INFO - GECKO(3120) | [Child 7036: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (06ADC000) [pid = 7036] [serial = 56] [outer = 06AF1020]
[task 2020-05-30T00:45:23.410Z] 00:45:23 INFO - checking window state
[task 2020-05-30T00:45:23.410Z] 00:45:23 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-30T00:45:23.410Z] 00:45:23 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleAfterTabTearOutIn.js | Found an unexpected browser window at the end of test run -
[task 2020-05-30T00:45:23.410Z] 00:45:23 INFO - GECKO(3120) | [Child 7036: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0B7F0000) [pid = 7036] [serial = 57] [outer = 06AF1020]
[task 2020-05-30T00:45:23.410Z] 00:45:23 INFO - GECKO(3120) | must wait for focus
[task 2020-05-30T00:45:23.411Z] 00:45:23 INFO - GECKO(3120) | [Child 7036: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 06AD9000 == 1 [pid = 7036] [id = {8c2ee302-e41e-48c9-ada8-ed228f8d3ac2}] [url = about:blank]
[task 2020-05-30T00:45:23.411Z] 00:45:23 INFO - GECKO(3120) | [Child 7036: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0CBC6400) [pid = 7036] [serial = 50] [outer = 00000000] [url = about:blank]

Jared can you take a look?

Flags: needinfo?(jaws)
Whiteboard: [stockwell disabled] → [stockwell disabled][stockwell needswork:owner]
Flags: needinfo?(jaws) → needinfo?(mconley)
Assignee: shindli → mconley
Flags: needinfo?(mconley)
Component: Video/Audio Controls → Picture-in-Picture
Version: unspecified → Trunk
Whiteboard: [stockwell disabled][stockwell needswork:owner] → [stockwell disabled][stockwell needswork:owner] [pip-need-eng-triage]
Whiteboard: [stockwell disabled][stockwell needswork:owner] [pip-need-eng-triage] → [stockwell disabled][stockwell needswork:owner] [pip-needs-eng-triage]
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: