Closed Bug 1700504 Opened 4 years ago Closed 3 years ago

Intermittent toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | Test timed out -

Categories

(Toolkit :: Video/Audio Controls, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1775879
Tracking Status
firefox-esr78 --- unaffected
firefox86 --- unaffected
firefox87 --- unaffected
firefox88 --- unaffected
firefox89 --- wontfix
firefox90 --- disabled
firefox91 --- fix-optional

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

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

Attachments

(2 files)

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


[task 2021-03-23T23:16:03.443Z] 23:16:03     INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js
[task 2021-03-23T23:16:48.481Z] 23:16:48     INFO - TEST-INFO | started process screentopng
[task 2021-03-23T23:16:48.925Z] 23:16:48     INFO - TEST-INFO | screentopng: exit 0
[task 2021-03-23T23:16:48.925Z] 23:16:48     INFO - Buffered messages logged at 23:16:03
[task 2021-03-23T23:16:48.925Z] 23:16:48     INFO - Entering test bound test_toggleButtonOnNanDuration
[task 2021-03-23T23:16:48.926Z] 23:16:48     INFO - Waiting for 'canplaythrough' for test-video
[task 2021-03-23T23:16:48.926Z] 23:16:48     INFO - Buffered messages finished
[task 2021-03-23T23:16:48.926Z] 23:16:48     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | Test timed out -```

Swapnik, can you investigate how to fix the failures in this new test? All test failures are on Linux x64 with optimized builds.

Flags: needinfo?(katkoor2)
Keywords: regression
Regressed by: 1697616
Has Regression Range: --- → yes

Yes, I can look into this.

Flags: needinfo?(katkoor2)

Adding to my investigation TODOs

Flags: needinfo?(mtigley)

I haven't gotten this failure to occur locally after a few hundred runs, but I've just noticed that all the failures the intermittent bot reported are on a platform called "linux1804", which I'm going to guess means Ubuntu 18.04, and I've been using 20.04, so I wonder if the failure only happens when some system package is an older version? I'll try on an 18.04 system as well.

Update:

There have been 41 failures within the last 7 days:

  • 1 failure on Linux 18.04 x64 tsan opt
  • 3 failures on Linux 18.04 x64 shippable opt
  • 5 failures on Linux 18.04 x64 asan opt
  • 32 failures on Linux 18.04 x64 opt/debug

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=336065150&repo=autoland&lineNumber=2376

[task 2021-04-10T10:15:58.381Z] 10:15:58 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js
[task 2021-04-10T10:17:28.439Z] 10:17:28 INFO - TEST-INFO | started process screentopng
[task 2021-04-10T10:17:29.032Z] 10:17:29 INFO - TEST-INFO | screentopng: exit 0
[task 2021-04-10T10:17:29.033Z] 10:17:29 INFO - Buffered messages logged at 10:15:58
[task 2021-04-10T10:17:29.034Z] 10:17:29 INFO - Entering test bound test_toggleButtonOnNanDuration
[task 2021-04-10T10:17:29.034Z] 10:17:29 INFO - Buffered messages logged at 10:15:59
[task 2021-04-10T10:17:29.035Z] 10:17:29 INFO - Waiting for 'canplaythrough' for test-video
[task 2021-04-10T10:17:29.035Z] 10:17:29 INFO - Buffered messages finished
[task 2021-04-10T10:17:29.036Z] 10:17:29 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | Test timed out -
[task 2021-04-10T10:17:29.038Z] 10:17:29 INFO - GECKO(1558) | MEMORY STAT | vsize 20975552MB | residentFast 1222MB
[task 2021-04-10T10:17:29.039Z] 10:17:29 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | took 90064ms

Whiteboard: [stockwell needswork:owner]
Assignee: nobody → malexandru
Status: NEW → ASSIGNED
Assignee: malexandru → nobody
Status: ASSIGNED → NEW

There are 25 total failures in the last 7 days on

task 2021-04-24T03:21:46.461Z] 03:21:46 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js
[task 2021-04-24T03:22:31.476Z] 03:22:31 INFO - TEST-INFO | started process screencapture
[task 2021-04-24T03:22:31.690Z] 03:22:31 INFO - TEST-INFO | screencapture: exit 0
[task 2021-04-24T03:22:31.690Z] 03:22:31 INFO - Buffered messages logged at 03:21:46
[task 2021-04-24T03:22:31.690Z] 03:22:31 INFO - Entering test bound test_toggleButtonOnNanDuration
[task 2021-04-24T03:22:31.690Z] 03:22:31 INFO - Waiting for 'canplaythrough' for test-video
[task 2021-04-24T03:22:31.691Z] 03:22:31 INFO - Buffered messages finished
[task 2021-04-24T03:22:31.691Z] 03:22:31 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | Test timed out -
[task 2021-04-24T03:22:31.693Z] 03:22:31 INFO - GECKO(3873) | MEMORY STAT | vsize 7943MB | residentFast 354MB | heapAllocated 98MB
[task 2021-04-24T03:22:31.693Z] 03:22:31 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | took 45022ms
[task 2021-04-24T03:22:31.694Z] 03:22:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-04-24T03:22:31.694Z] 03:22:31 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-with-nan-video-duration.html -
[task 2021-04-24T03:22:31.694Z] 03:22:31 INFO - checking window state
[task 2021-04-24T03:22:31.695Z] 03:22:31 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js
[task 2021-04-24T03:22:31.695Z] 03:22:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-04-24T03:22:31.696Z] 03:22:31 INFO - Buffered messages logged at 03:22:31
[task 2021-04-24T03:22:31.703Z] 03:22:31 INFO - Entering test bound
[task 2021-04-24T03:22:31.703Z] 03:22:31 INFO - Buffered messages finished
[task 2021-04-24T03:22:31.703Z] 03:22:31 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | Uncaught exception received from previously timed out test - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2021-04-24T03:22:31.946Z] 03:22:31 INFO - Waiting for videos to be ready
[task 2021-04-24T03:22:31.948Z] 03:22:31 INFO - Testing video with id: inserted
[task 2021-04-24T03:22:32.260Z] 03:22:32 INFO - Checking toggle policy
[task 2021-04-24T03:22:32.687Z] 03:22:32 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | No toggle policy should be set. - true == true -
[task 2021-04-24T03:22:32.687Z] 03:22:32 INFO - Waiting for toggle to become visible
[task 2021-04-24T03:22:32.688Z] 03:22:32 INFO - Testing toggle for stage hoverVideo in position right, has used: true
[task 2021-04-24T03:22:32.688Z] 03:22:32 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | Expected .pip-expanded to be hidden. - true == true -
[task 2021-04-24T03:22:32.820Z] 03:22:32 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | Toggle reached target opacity. - true == true -
[task 2021-04-24T03:22:32.820Z] 03:22:32 INFO - Hovering the toggle rect now.
[task 2021-04-24T03:22:32.820Z] 03:22:32 INFO - Waiting for toggle to reach full opacity
[task 2021-04-24T03:22:32.820Z] 03:22:32 INFO - Testing toggle for stage hoverToggle in position right, has used: true
[task 2021-04-24T03:22:32.821Z] 03:22:32 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | Expected .pip-expanded to be hidden. - true == true -
[task 2021-04-24T03:22:33.243Z] 03:22:33 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | Toggle reached target opacity. - true == true -
[task 2021-04-24T03:22:33.243Z] 03:22:33 INFO - Right-clicking on toggle.
[task 2021-04-24T03:22:33.244Z] 03:22:33 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup"] deepEqual ["pointerdown","mousedown","pointerup","mouseup"] -
[task 2021-04-24T03:22:33.244Z] 03:22:33 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | No Picture-in-Picture window found. -
[task 2021-04-24T03:22:33.244Z] 03:22:33 INFO - Clicking on toggle, and expecting a Picture-in-Picture window to open
[task 2021-04-24T03:22:33.320Z] 03:22:33 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | A Picture-in-Picture window opened. -
[task 2021-04-24T03:22:33.490Z] 03:22:33 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | Expected to get the right mouse events. - [] deepEqual [] -
[task 2021-04-24T03:22:33.490Z] 03:22:33 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup","click"] deepEqual ["pointerdown","mousedown","pointerup","mouseup","click"] -
[task 2021-04-24T03:22:33.491Z] 03:22:33 INFO - Leaving test bound
[task 2021-04-24T03:22:33.493Z] 03:22:33 INFO - GECKO(3873) | MEMORY STAT | vsize 7935MB | residentFast 345MB | heapAllocated 101MB
[task 2021-04-24T03:22:33.493Z] 03:22:33 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js | took 1950ms
[task 2021-04-24T03:22:33.525Z] 03:22:33 INFO - checking window state

Hi Micah, are there any updates here? We disabled the test since it reached the disable-recommended queue.

Flags: needinfo?(mtigley)
Flags: needinfo?(mtigley)
Assignee: nobody → malexandru
Status: NEW → ASSIGNED
Pushed by malexandru@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/f558392b5115 Disable browser_toggleButtonOnNanDuration.js on Linux optimized builds for frequent failures. r=intermittent-reviewers,jmaher
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Assignee: malexandru → nobody
Status: ASSIGNED → NEW

Update:
There have been 39 failures within the last 7 days:
• 13 failures on Linux 18.04 x64 WebRender asan opt
• 2 failures on Linux 18.04 x64 CCov WebRender opt
• 4 failures on Linux 18.04 x64 WebRender debug
• 11 failures on Linux 18.04 x64 WebRender opt
• 4 failures on Linux 18.04 x64 WebRender Shippable opt
• 2 failures on linux1804-64-tsan-qr opt
• 3 failures on OS X 10.15 WebRender debug
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=347905725&repo=autoland&lineNumber=2515

[task 2021-08-09T23:19:12.068Z] 23:19:12     INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js
[task 2021-08-09T23:20:42.155Z] 23:20:42     INFO - TEST-INFO | started process screentopng
[task 2021-08-09T23:20:42.845Z] 23:20:42     INFO - TEST-INFO | screentopng: exit 0
[task 2021-08-09T23:20:42.846Z] 23:20:42     INFO - Buffered messages logged at 23:19:12
[task 2021-08-09T23:20:42.847Z] 23:20:42     INFO - Entering test bound test_toggleButtonOnNanDuration
[task 2021-08-09T23:20:42.848Z] 23:20:42     INFO - Waiting for 'canplaythrough' for test-video
[task 2021-08-09T23:20:42.849Z] 23:20:42     INFO - Buffered messages finished
[task 2021-08-09T23:20:42.850Z] 23:20:42     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | Test timed out - 
[task 2021-08-09T23:20:42.850Z] 23:20:42     INFO - GECKO(1555) | MEMORY STAT | vsize 20975667MB | residentFast 1281MB
[task 2021-08-09T23:20:42.851Z] 23:20:42     INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | took 90097ms
[task 2021-08-09T23:20:42.852Z] 23:20:42     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-09T23:20:42.853Z] 23:20:42     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleButtonOnNanDuration.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-with-nan-video-duration.html - 
[task 2021-08-09T23:20:42.853Z] 23:20:42     INFO - checking window state
[task 2021-08-09T23:20:42.854Z] 23:20:42     INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleOnInsertedVideo.js
Whiteboard: [stockwell disabled] → [stockwell disabled][stockwell needswork:owner]

Jared, as the owner of this component, could you help us assign it to someone?
Thank you.

Flags: needinfo?(jaws)
Pushed by apavel@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/1f5219dacb91 update disabling condition to skip on qr too r=intermittent-reviewers,jmaher DONTBUILD

Removing needinfo on this since I haven't had time to investigate this.

Flags: needinfo?(mtigley)
Flags: needinfo?(jaws)
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: