Closed Bug 1666952 Opened 4 years ago Closed 2 years ago

Intermittent toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | Test timed out - | Found a tab after previous test timed out:

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE
Tracking Status
firefox-esr78 --- unaffected
firefox81 --- unaffected
firefox82 --- unaffected
firefox83 --- fix-optional

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

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


[task 2020-09-24T00:39:43.709Z] 00:39:43 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js
[task 2020-09-24T00:41:13.766Z] 00:41:13 INFO - TEST-INFO | started process screentopng
[task 2020-09-24T00:41:14.312Z] 00:41:14 INFO - TEST-INFO | screentopng: exit 0
[task 2020-09-24T00:41:14.314Z] 00:41:14 INFO - Buffered messages logged at 00:39:43
[task 2020-09-24T00:41:14.315Z] 00:41:14 INFO - Entering test bound
[task 2020-09-24T00:41:14.316Z] 00:41:14 INFO - Buffered messages logged at 00:39:44
[task 2020-09-24T00:41:14.317Z] 00:41:14 INFO - Waiting for videos to be ready
[task 2020-09-24T00:41:14.318Z] 00:41:14 INFO - Buffered messages finished
[task 2020-09-24T00:41:14.319Z] 00:41:14 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | Test timed out -
[task 2020-09-24T00:41:14.320Z] 00:41:14 INFO - GECKO(5557) | MEMORY STAT | vsize 20975764MB | residentFast 1192MB
[task 2020-09-24T00:41:14.321Z] 00:41:14 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | took 90071ms
[task 2020-09-24T00:41:14.325Z] 00:41:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-09-24T00:41:14.326Z] 00:41:14 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-with-sound.html -
[task 2020-09-24T00:41:14.330Z] 00:41:14 INFO - checking window state
[task 2020-09-24T00:41:14.331Z] 00:41:14 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_thirdPartyIframe.js
[task 2020-09-24T00:41:14.332Z] 00:41:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-09-24T00:41:14.333Z] 00:41:14 INFO - Buffered messages logged at 00:41:13
[task 2020-09-24T00:41:14.334Z] 00:41:14 INFO - Entering test bound
[task 2020-09-24T00:41:14.335Z] 00:41:14 INFO - Testing with-controls case.
[task 2020-09-24T00:41:14.335Z] 00:41:14 INFO - Buffered messages finished
[task 2020-09-24T00:41:14.336Z] 00:41:14 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | Uncaught exception received from previously timed out test - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2020-09-24T00:41:15.595Z] 00:41:15 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_thirdPartyIframe.js | Got Picture-in-Picture window. -
[task 2020-09-24T00:41:15.611Z] 00:41:15 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_thirdPartyIframe.js | Should be able to find Picture-in-Picture overlay. - {} == true -
[task 2020-09-24T00:41:15.612Z] 00:41:15 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_thirdPartyIframe.js | Video should be showing the expected state. - true == true -
[task 2020-09-24T00:41:15.756Z] 00:41:15 INFO - Testing no-controls case.
[task 2020-09-24T00:41:16.639Z] 00:41:16 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_thirdPartyIframe.js | Got Picture-in-Picture window. -
[task 2020-09-24T00:41:16.659Z] 00:41:16 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_thirdPartyIframe.js | Should be able to find Picture-in-Picture overlay. - {} == true -
[task 2020-09-24T00:41:16.659Z] 00:41:16 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_thirdPartyIframe.js | Video should be showing the expected state. - true == true -
[task 2020-09-24T00:41:16.755Z] 00:41:16 INFO - Leaving test bound
[task 2020-09-24T00:41:16.811Z] 00:41:16 INFO - GECKO(5557) | MEMORY STAT | vsize 20975761MB | residentFast 1126MB

Bugbug thinks this bug should belong to this component, but please revert this change in case of error.

Component: General → Video/Audio Controls
Has Regression Range: --- → yes

Set release status flags based on info from the regressing bug 1612648

The changes in bug 1664867 to the ensureVideosReady helper will likely help here.

See Also: → 1664867
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

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=344773766&repo=mozilla-central&lineNumber=19704

[task 2021-07-09T10:23:48.472Z] 10:23:48 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js
[task 2021-07-09T10:23:48.488Z] 10:23:48 INFO - GECKO(5443) | [Child 5615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f3323fc8800 == 2 [pid = 5615] [id = 42]
[task 2021-07-09T10:23:48.489Z] 10:23:48 INFO - GECKO(5443) | [Child 5615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (7f3328c26580) [pid = 5615] [serial = 95] [outer = 0]
[task 2021-07-09T10:23:48.492Z] 10:23:48 INFO - GECKO(5443) | [Child 5615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (7f3323fca800) [pid = 5615] [serial = 96] [outer = 7f3328c26580]
[task 2021-07-09T10:23:48.561Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fe58487bc00 == 3 [pid = 5634] [id = 55] [url = about:blank]
[task 2021-07-09T10:23:48.634Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fe584358c00 == 2 [pid = 5634] [id = 53] [url = about:blank]
[task 2021-07-09T10:23:48.634Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fe584879800 == 1 [pid = 5634] [id = 54] [url = about:blank]
[task 2021-07-09T10:23:48.810Z] 10:23:48 INFO - GECKO(5443) | [Child 5615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (7f3328c65000) [pid = 5615] [serial = 97] [outer = 7f3328c26580]
[task 2021-07-09T10:23:48.818Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fe58435e800 == 0 [pid = 5634] [id = 52] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-with-sound.html]
[task 2021-07-09T10:23:48.827Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (7fe584872400) [pid = 5634] [serial = 119] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:48.828Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (7fe584359800) [pid = 5634] [serial = 115] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:48.828Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (7fe584879000) [pid = 5634] [serial = 113] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:48.828Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (7fe584873c00) [pid = 5634] [serial = 111] [outer = 0] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-with-sound.html]
[task 2021-07-09T10:23:48.828Z] 10:23:48 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7fe58435d800) [pid = 5634] [serial = 117] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:49.123Z] 10:23:49 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (7fe5857d8200) [pid = 5634] [serial = 123] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:49.123Z] 10:23:49 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (7fe5857d8040) [pid = 5634] [serial = 121] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:49.123Z] 10:23:49 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (7fe5857d8740) [pid = 5634] [serial = 125] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:49.291Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ffafd52a800 == 12 [pid = 5443] [id = 52] [url = chrome://global/content/pictureinpicture/player.xhtml]
[task 2021-07-09T10:23:49.293Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ffb19c93800 == 11 [pid = 5443] [id = 35] [url = chrome://browser/content/browser.xhtml]
[task 2021-07-09T10:23:49.295Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ffb1d05a800 == 10 [pid = 5443] [id = 39] [url = chrome://browser/content/browser.xhtml]
[task 2021-07-09T10:23:49.296Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ffb1ff5bc00 == 9 [pid = 5443] [id = 33] [url = chrome://browser/content/browser.xhtml]
[task 2021-07-09T10:23:49.298Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 56 (7ffb1d05d000) [pid = 5443] [serial = 101] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:49.299Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 55 (7ffb1ce47400) [pid = 5443] [serial = 99] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:49.301Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 54 (7ffafc9a2400) [pid = 5443] [serial = 103] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:49.301Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ffafc9a3400 == 8 [pid = 5443] [id = 53] [url = chrome://global/content/pictureinpicture/player.xhtml]
[task 2021-07-09T10:23:49.340Z] 10:23:49 INFO - GECKO(5443) | [Parent 5443: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ffafd520400 == 7 [pid = 5443] [id = 54] [url = chrome://global/content/pictureinpicture/player.xhtml]
[task 2021-07-09T10:23:49.561Z] 10:23:49 INFO - GECKO(5443) | [Child 5508: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f67bfc0ac00 == 3 [pid = 5508] [id = 45] [url = about:blank]
[task 2021-07-09T10:23:50.685Z] 10:23:50 INFO - GECKO(5443) | [Child 5615: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f3323fc3800 == 1 [pid = 5615] [id = 41] [url = about:blank]

[task 2021-07-09T10:23:59.199Z] 10:23:59 INFO - GECKO(5443) | [Child 5508: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (7f67bf9ab000) [pid = 5508] [serial = 105] [outer = 0] [url = about:blank]
[task 2021-07-09T10:23:59.200Z] 10:23:59 INFO - GECKO(5443) | [Child 5508: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f67bf9b2400) [pid = 5508] [serial = 106] [outer = 0] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2021-07-09T10:23:59.201Z] 10:23:59 INFO - GECKO(5443) | [Child 5508: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f67ca491800) [pid = 5508] [serial = 103] [outer = 0] [url = about:blank]
[task 2021-07-09T10:25:18.528Z] 10:25:18 INFO - TEST-INFO | started process screentopng
[task 2021-07-09T10:25:18.906Z] 10:25:18 INFO - TEST-INFO | screentopng: exit 0
[task 2021-07-09T10:25:18.907Z] 10:25:18 INFO - Buffered messages logged at 10:23:48
[task 2021-07-09T10:25:18.907Z] 10:25:18 INFO - Entering test bound
[task 2021-07-09T10:25:18.907Z] 10:25:18 INFO - Buffered messages logged at 10:23:49
[task 2021-07-09T10:25:18.908Z] 10:25:18 INFO - Waiting for videos to be ready
[task 2021-07-09T10:25:18.909Z] 10:25:18 INFO - Buffered messages logged at 10:23:50
[task 2021-07-09T10:25:18.909Z] 10:25:18 INFO - Waiting for 'canplaythrough' for 'no-controls'
[task 2021-07-09T10:25:18.910Z] 10:25:18 INFO - Buffered messages finished
[task 2021-07-09T10:25:18.911Z] 10:25:18 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | Test timed out -
[task 2021-07-09T10:25:18.912Z] 10:25:18 INFO - GECKO(5443) | MEMORY STAT | vsize 3394MB | residentFast 370MB | heapAllocated 106MB
[task 2021-07-09T10:25:18.912Z] 10:25:18 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | took 90066ms
[task 2021-07-09T10:25:18.912Z] 10:25:18 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-09T10:25:18.916Z] 10:25:18 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page-with-sound.html -
[task 2021-07-09T10:25:18.916Z] 10:25:18 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fe584353000 == 1 [pid = 5634] [id = 56]
[task 2021-07-09T10:25:18.916Z] 10:25:18 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fe5857d8040) [pid = 5634] [serial = 127] [outer = 0]
[task 2021-07-09T10:25:18.917Z] 10:25:18 INFO - GECKO(5443) | [Child 5634: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fe584354800) [pid = 5634] [serial = 128] [outer = 7fe5857d8040]
[task 2021-07-09T10:25:18.917Z] 10:25:18 INFO - checking window state
[task 2021-07-09T10:25:18.917Z] 10:25:18 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_thirdPartyIframe.js
[task 2021-07-09T10:25:18.917Z] 10:25:18 INFO - GECKO(5443) | [Child 5508: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f67bf9aa000 == 2 [pid = 5508] [id = 47]
[task 2021-07-09T10:25:18.918Z] 10:25:18 INFO - GECKO(5443) | [Child 5508: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f67c61f6040) [pid = 5508] [serial = 111] [outer = 0]
[task 2021-07-09T10:25:18.918Z] 10:25:18 INFO - GECKO(5443) | [Child 5508: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7f67bf9ab000) [pid = 5508] [serial = 112] [outer = 7f67c61f6040]
[task 2021-07-09T10:25:18.952Z] 10:25:18 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-09T10:25:18.952Z] 10:25:18 INFO - Buffered messages logged at 10:25:18
[task 2021-07-09T10:25:18.952Z] 10:25:18 INFO - Entering test bound
[task 2021-07-09T10:25:18.952Z] 10:25:18 INFO - Testing with-controls case.
[task 2021-07-09T10:25:18.952Z] 10:25:18 INFO - Buffered messages finished
[task 2021-07-09T10:25:18.953Z] 10:25:18 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_tabIconOverlayPiP.js | Uncaught exception received from previously timed out test - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2021-07-09T10:25:19.038Z] 10:25:19 INFO - GECKO(5443) | [Child 5508, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.