Open Bug 1552288 Opened 5 years ago Updated 6 months ago

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

Categories

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

defect

Tracking

()

REOPENED

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

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

Attachments

(1 file, 1 obsolete file)

Filed by: ncsoregi [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=246859107&repo=mozilla-inbound
Full log: https://queue.taskcluster.net/v1/task/SIWOowW1TA-6TtqHhS6Dbw/runs/0/artifacts/public/logs/live_backing.log


[task 2019-05-16T18:51:11.886Z] 18:51:11 INFO - Hovering the toggle rect now.
[task 2019-05-16T18:51:11.888Z] 18:51:11 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Toggle reached target opacity. - true == true -
[task 2019-05-16T18:51:11.889Z] 18:51:11 INFO - Clicking on toggle, and expecting a Picture-in-Picture window to open
[task 2019-05-16T18:51:11.892Z] 18:51:11 INFO - Console message: [JavaScript Error: "NS_ERROR_NOT_INITIALIZED" {file: "resource://gre/actors/PictureInPictureChild.jsm" line: 653}]
[task 2019-05-16T18:51:11.894Z] 18:51:11 INFO - Buffered messages finished
[task 2019-05-16T18:51:11.896Z] 18:51:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Test timed out -
[task 2019-05-16T18:51:11.897Z] 18:51:11 INFO - GECKO(4744) | MEMORY STAT | vsize 20975122MB | residentFast 1057MB
[task 2019-05-16T18:51:11.899Z] 18:51:11 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | took 90091ms
[task 2019-05-16T18:51:11.900Z] 18:51:11 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-05-16T18:51:11.902Z] 18:51:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html -
[task 2019-05-16T18:51:11.904Z] 18:51:11 INFO - checking window state
[task 2019-05-16T18:51:13.384Z] 18:51:13 INFO - GECKO(4744) | Completed ShutdownLeaks collections in process 4867
[task 2019-05-16T18:51:13.420Z] 18:51:13 INFO - GECKO(4744) | Completed ShutdownLeaks collections in process 4896
[task 2019-05-16T18:51:13.456Z] 18:51:13 INFO - GECKO(4744) | Completed ShutdownLeaks collections in process 4924
[task 2019-05-16T18:51:13.460Z] 18:51:13 INFO - GECKO(4744) | Completed ShutdownLeaks collections in process 4812
[task 2019-05-16T18:51:13.518Z] 18:51:13 INFO - GECKO(4744) | Completed ShutdownLeaks collections in process 4791
[task 2019-05-16T18:51:14.240Z] 18:51:14 INFO - GECKO(4744) | Completed ShutdownLeaks collections in process 4744

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

This is still happening.

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

[task 2019-07-19T08:13:00.134Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Toggle reached target opacity. - true == true -
[task 2019-07-19T08:13:00.135Z] 08:13:00 INFO - Right-clicking on toggle.
[task 2019-07-19T08:13:00.138Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup"] deepEqual ["pointerdown","mousedown","pointerup","mouseup"] -
[task 2019-07-19T08:13:00.138Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | No Picture-in-Picture window found. -
[task 2019-07-19T08:13:00.139Z] 08:13:00 INFO - Clicking on toggle, and expecting a Picture-in-Picture window to open
[task 2019-07-19T08:13:00.140Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | A Picture-in-Picture window opened. -
[task 2019-07-19T08:13:00.141Z] 08:13:00 INFO - Console message: [JavaScript Warning: "pictureinpicture.opened_method - Unknown scalar."]
[task 2019-07-19T08:13:00.144Z] 08:13:00 INFO - Console message: [JavaScript Error: "TypeError: state.hideToggleDeferredTask is null" {file: "resource://gre/actors/PictureInPictureChild.jsm" line: 599}]
[task 2019-07-19T08:13:00.144Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - [] deepEqual [] -
[task 2019-07-19T08:13:00.149Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup","click"] deepEqual ["pointerdown","mousedown","pointerup","mouseup","click"] -
[task 2019-07-19T08:13:00.149Z] 08:13:00 INFO - Testing video with id: video-alpha-background
[task 2019-07-19T08:13:00.151Z] 08:13:00 INFO - Waiting for toggle to become visible
[task 2019-07-19T08:13:00.162Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Toggle reached target opacity. - true == true -
[task 2019-07-19T08:13:00.167Z] 08:13:00 INFO - Hovering the toggle rect now.
[task 2019-07-19T08:13:00.169Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Toggle reached target opacity. - true == true -
[task 2019-07-19T08:13:00.170Z] 08:13:00 INFO - Right-clicking on toggle.
[task 2019-07-19T08:13:00.180Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup"] deepEqual ["pointerdown","mousedown","pointerup","mouseup"] -
[task 2019-07-19T08:13:00.181Z] 08:13:00 INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | No Picture-in-Picture window found. -
[task 2019-07-19T08:13:00.182Z] 08:13:00 INFO - Clicking on toggle, and expecting a Picture-in-Picture window to open
[task 2019-07-19T08:13:00.182Z] 08:13:00 INFO - Console message: [JavaScript Warning: "pictureinpicture.opened_method - Unknown scalar."]
[task 2019-07-19T08:13:00.183Z] 08:13:00 INFO - Console message: [JavaScript Error: "TypeError: state.hideToggleDeferredTask is null" {file: "resource://gre/actors/PictureInPictureChild.jsm" line: 599}]
[task 2019-07-19T08:13:00.184Z] 08:13:00 INFO - Console message: [JavaScript Error: "NS_ERROR_XPC_BAD_CONVERT_NATIVE: Component returned failure code: 0x8057000a (NS_ERROR_XPC_BAD_CONVERT_NATIVE) [xpcIJSWeakReference.get]" {file: "resource://gre/actors/PictureInPictureChild.jsm" line: 678}]
[task 2019-07-19T08:13:00.184Z] 08:13:00 INFO - Buffered messages finished
[task 2019-07-19T08:13:00.185Z] 08:13:00 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Test timed out -
[task 2019-07-19T08:13:00.185Z] 08:13:00 INFO - GECKO(6196) | MEMORY STAT | vsize 3126MB | residentFast 320MB | heapAllocated 87MB
[task 2019-07-19T08:13:00.186Z] 08:13:00 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | took 90129ms
[task 2019-07-19T08:13:00.187Z] 08:13:00 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-07-19T08:13:00.196Z] 08:13:00 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html -
[task 2019-07-19T08:13:00.197Z] 08:13:00 INFO - GECKO(6196) | [Child 6273, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-19T08:13:00.198Z] 08:13:00 INFO - GECKO(6196) | [Parent 6196, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-19T08:13:00.200Z] 08:13:00 INFO - GECKO(6196) | [Parent 6196, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-19T08:13:00.203Z] 08:13:00 INFO - GECKO(6196) | ++DOCSHELL 0x7fc3df51c800 == 1 [pid = 6377] [id = {47d5c0a9-c48d-482a-8d2b-18123f64da53}]
[task 2019-07-19T08:13:00.204Z] 08:13:00 INFO - GECKO(6196) | ++DOMWINDOW == 1 (0x7fc3dfecd020) [pid = 6377] [serial = 22] [outer = (nil)]
[task 2019-07-19T08:13:00.204Z] 08:13:00 INFO - GECKO(6196) | [Child 6377, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-19T08:13:00.205Z] 08:13:00 INFO - GECKO(6196) | ++DOMWINDOW == 2 (0x7fc3df5dec00) [pid = 6377] [serial = 23] [outer = 0x7fc3dfecd020]
[task 2019-07-19T08:13:00.206Z] 08:13:00 INFO - checking window state
[task 2019-07-19T08:13:00.207Z] 08:13:00 INFO - GECKO(6196) | ++DOMWINDOW == 3 (0x7fc3df5e4400) [pid = 6377] [serial = 24] [outer = 0x7fc3dfecd020]
[task 2019-07-19T08:13:00.210Z] 08:13:00 INFO - GECKO(6196) | [Child 6377, Main Thread] WARNING: NS_ENSURE_SUCCESS(mStatus, *this) failed with result 0x80004005: file /builds/worker/workspace/build/src/obj-firefox/dist/include/nsIURIMutator.h, line 489
[task 2019-07-19T08:13:00.211Z] 08:13:00 INFO - GECKO(6196) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 112: uncaught exception: Object
[task 2019-07-19T08:13:00.212Z] 08:13:00 INFO - GECKO(6196) | [Child 6377, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-19T08:13:00.217Z] 08:13:00 INFO - GECKO(6196) | [Parent 6196, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/nsFrameLoader.cpp, line 664
[task 2019-07-19T08:13:00.218Z] 08:13:00 INFO - GECKO(6196) | ++DOMWINDOW == 13 (0x7f6158574400) [pid = 6196] [serial = 33] [outer = 0x7f61602a5020]
[task 2019-07-19T08:13:00.220Z] 08:13:00 INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
[task 2019-07-19T08:13:00.221Z] 08:13:00 INFO - GECKO(6196) | ++DOMWINDOW == 14 (0x7f61580ac800) [pid = 6196] [serial = 34] [outer = 0x7f61602a5020]
[task 2019-07-19T08:13:00.231Z] 08:13:00 INFO - GECKO(6196) | --DOCSHELL 0x7f95732a5800 == 0 [pid = 6273] [id = {b70df9fa-89b0-44c5-8ac6-40eb580fe665}] [url = about:blank]
[task 2019-07-19T08:13:00.369Z] 08:13:00 INFO - GECKO(6196) | --DOCSHELL 0x7f490190e800 == 0 [pid = 6325] [id = {2a18fd5d-26f8-494d-8470-c8b9d2717bbf}] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html]
[task 2019-07-19T08:13:00.389Z] 08:13:00 INFO - GECKO(6196) | --DOMWINDOW == 2 (0x7fc3df5dec00) [pid = 6377] [serial = 23] [outer = (nil)] [url = about:blank]
[task 2019-07-19T08:13:00.674Z] 08:13:00 INFO - GECKO(6196) | --DOCSHELL 0x7f0d7f6c7000 == 0 [pid = 6348] [id = {a62047ec-d436-4503-82c3-bebea3104281}] [url = about:newtab]
[task 2019-07-19T08:13:00.675Z] 08:13:00 INFO - GECKO(6196) | --DOMWINDOW == 1 (0x7f0d7f67bd40) [pid = 6348] [serial = 1] [outer = (nil)] [url = about:newtab]
[task 2019-07-19T08:13:00.676Z] 08:13:00 INFO - GECKO(6196) | --DOMWINDOW == 0 (0x7f0d7ffd9c00) [pid = 6348] [serial = 3] [outer = (nil)] [url = about:newtab]
[task 2019-07-19T08:13:02.792Z] 08:13:02 INFO - GECKO(6196) | --DOMWINDOW == 1 (0x7f95739ccd40) [pid = 6273] [serial = 19] [outer = (nil)] [url = about:blank]
[task 2019-07-19T08:13:02.793Z] 08:13:02 INFO - GECKO(6196) | --DOMWINDOW == 0 (0x7f9573976400) [pid = 6273] [serial = 21] [outer = (nil)] [url = about:blank]
[task 2019-07-19T08:13:02.869Z] 08:13:02 INFO - GECKO(6196) | --DOMWINDOW == 1 (0x7f4907c05200) [pid = 6325] [serial = 20] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html]
[task 2019-07-19T08:13:02.869Z] 08:13:02 INFO - GECKO(6196) | --DOMWINDOW == 0 (0x7f49005cc400) [pid = 6325] [serial = 22] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html]
[task 2019-07-19T08:13:03.479Z] 08:13:03 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6474
[task 2019-07-19T08:13:03.495Z] 08:13:03 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6533
[task 2019-07-19T08:13:03.773Z] 08:13:03 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6404
[task 2019-07-19T08:13:03.890Z] 08:13:03 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6436
[task 2019-07-19T08:13:03.986Z] 08:13:03 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6488
[task 2019-07-19T08:13:04.143Z] 08:13:04 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6377
[task 2019-07-19T08:13:04.301Z] 08:13:04 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6273
[task 2019-07-19T08:13:04.337Z] 08:13:04 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6325
[task 2019-07-19T08:13:04.756Z] 08:13:04 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6348
[task 2019-07-19T08:13:04.772Z] 08:13:04 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6251
[task 2019-07-19T08:13:04.823Z] 08:13:04 INFO - GECKO(6196) | --DOMWINDOW == 13 (0x7f615f63b400) [pid = 6196] [serial = 9] [outer = (nil)] [url = about:blank]
[task 2019-07-19T08:13:07.384Z] 08:13:07 INFO - GECKO(6196) | --DOMWINDOW == 12 (0x7f6158574400) [pid = 6196] [serial = 33] [outer = (nil)] [url = about:blank]
[task 2019-07-19T08:13:07.844Z] 08:13:07 INFO - GECKO(6196) | Completed ShutdownLeaks collections in process 6196
[task 2019-07-19T08:13:07.844Z] 08:13:07 INFO - TEST-START | Shutdown

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE

This is still happening: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=282245049&repo=mozilla-central&lineNumber=23963

[task 2019-12-21T10:44:39.601Z] 10:44:39 INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js
[task 2019-12-21T10:44:39.637Z] 10:44:39 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fcbbee27000 == 3 [pid = 5752] [id = {06e474e4-6289-4545-9b8f-73b62d90a114}]
[task 2019-12-21T10:44:39.639Z] 10:44:39 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7fcbbe2e9980) [pid = 5752] [serial = 71] [outer = (nil)]
[task 2019-12-21T10:44:39.640Z] 10:44:39 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7fcbbe2b1400) [pid = 5752] [serial = 72] [outer = 0x7fcbbe2e9980]
[task 2019-12-21T10:44:39.674Z] 10:44:39 INFO - GECKO(5557) | [Parent 5557, Main Thread] WARNING: NS_ENSURE_TRUE(GetWrapper()) failed: file /builds/worker/workspace/build/src/dom/ipc/JSWindowActor.cpp, line 61
[task 2019-12-21T10:44:39.718Z] 10:44:39 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7fcbbdc67800) [pid = 5752] [serial = 61] [outer = (nil)] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:39.721Z] 10:44:39 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fcbbdc5ac00) [pid = 5752] [serial = 63] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:39.722Z] 10:44:39 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7fcbbdc5f800) [pid = 5752] [serial = 66] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:39.739Z] 10:44:39 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fcbbee26800 == 2 [pid = 5752] [id = {4ba8bb6c-dfbe-467f-9220-597f63fc1e69}] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:39.926Z] 10:44:39 INFO - GECKO(5557) | [Parent 5557, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-21T10:44:39.934Z] 10:44:39 INFO - GECKO(5557) | [Child 5752, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-21T10:44:39.954Z] 10:44:39 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0x7fcbbdc63400) [pid = 5752] [serial = 73] [outer = 0x7fcbbe2e9980]
[task 2019-12-21T10:44:39.970Z] 10:44:39 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 35 (0x7fec1a487800) [pid = 5557] [serial = 72] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:39.972Z] 10:44:39 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 34 (0x7fec1534c400) [pid = 5557] [serial = 66] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:39.972Z] 10:44:39 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 33 (0x7fec185a6000) [pid = 5557] [serial = 68] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:39.973Z] 10:44:39 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 32 (0x7fec18859400) [pid = 5557] [serial = 70] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:40.058Z] 10:44:40 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7fcbbe2e93e0) [pid = 5752] [serial = 62] [outer = (nil)] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:40.437Z] 10:44:40 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 31 (0x7fec15187f20) [pid = 5557] [serial = 73] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:40.438Z] 10:44:40 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 30 (0x7fec1886c020) [pid = 5557] [serial = 75] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:40.438Z] 10:44:40 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 29 (0x7fec1ba2a020) [pid = 5557] [serial = 77] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:40.438Z] 10:44:40 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 28 (0x7fec1ba2a5c0) [pid = 5557] [serial = 79] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:40.821Z] 10:44:40 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7fc986749800) [pid = 5719] [serial = 64] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:40.822Z] 10:44:40 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7fc9866ae000) [pid = 5719] [serial = 66] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:40.838Z] 10:44:40 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fc987765000 == 1 [pid = 5719] [id = {d1781198-fb1c-4b46-b817-f84af1757aa9}] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:40.962Z] 10:44:40 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7fc98f1adf20) [pid = 5719] [serial = 65] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:43.896Z] 10:44:43 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7fcbbe2b3000) [pid = 5752] [serial = 69] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:43.896Z] 10:44:43 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7fcbbdc65000) [pid = 5752] [serial = 64] [outer = (nil)] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:43.896Z] 10:44:43 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fcbbee21800 == 1 [pid = 5752] [id = {245b5f80-52df-4530-a748-00a8a774548a}] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:43.972Z] 10:44:43 INFO - GECKO(5557) | [Child 5752, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 6995
[task 2019-12-21T10:44:43.973Z] 10:44:43 INFO - GECKO(5557) | [Child 5752, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 6995
[task 2019-12-21T10:44:43.973Z] 10:44:43 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7fcbbe2e97a0) [pid = 5752] [serial = 65] [outer = (nil)] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:43.973Z] 10:44:43 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7fcbbe2e95c0) [pid = 5752] [serial = 68] [outer = (nil)] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:44.330Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fec162b4800 == 10 [pid = 5557] [id = {87d86933-904d-40c8-8c83-95015a5bf22a}] [url = about:blank]
[task 2019-12-21T10:44:44.330Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fec162be000 == 9 [pid = 5557] [id = {92b11372-1f64-4eab-9127-073fcf36f32f}] [url = about:blank]
[task 2019-12-21T10:44:44.331Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fec162ae800 == 8 [pid = 5557] [id = {5f715124-3c39-4c4f-a9d3-0d626272bc68}] [url = about:blank]
[task 2019-12-21T10:44:44.332Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fec162af000 == 7 [pid = 5557] [id = {08f9fa63-4edf-4d70-ad2c-5912ce500300}] [url = about:blank]
[task 2019-12-21T10:44:44.333Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 27 (0x7fec16211000) [pid = 5557] [serial = 78] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.333Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 26 (0x7fec15352800) [pid = 5557] [serial = 76] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.334Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 25 (0x7fec151dc800) [pid = 5557] [serial = 74] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.335Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 24 (0x7fec16c2bc00) [pid = 5557] [serial = 80] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.336Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fec162b2000 == 6 [pid = 5557] [id = {90f71b85-4dd2-4ec9-98c6-71b490b47439}] [url = about:blank]
[task 2019-12-21T10:44:44.337Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fec162ad000 == 5 [pid = 5557] [id = {c42e9177-5d34-48c0-8c40-eff81b0ceb24}] [url = about:blank]
[task 2019-12-21T10:44:44.574Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 23 (0x7fec1f9cc7a0) [pid = 5557] [serial = 85] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.575Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 22 (0x7fec1f9ccd40) [pid = 5557] [serial = 89] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.575Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (0x7fec1d2b44c0) [pid = 5557] [serial = 87] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.576Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (0x7fec1f9cc200) [pid = 5557] [serial = 83] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.577Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (0x7fec1ba2b4c0) [pid = 5557] [serial = 81] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.577Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (0x7fec1f9cd880) [pid = 5557] [serial = 91] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.577Z] 10:44:44 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (0x7fec1f9cde20) [pid = 5557] [serial = 93] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.900Z] 10:44:44 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7fc9866adc00) [pid = 5719] [serial = 69] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:44.900Z] 10:44:44 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7fc9866b8400) [pid = 5719] [serial = 67] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:44.961Z] 10:44:44 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7fc98f1ad7a0) [pid = 5719] [serial = 68] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:47.620Z] 10:44:47 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fa5f6608800 == 0 [pid = 5691] [id = {d7e3901a-bb63-4906-b5ac-b6404a473b1f}] [url = about:blank]
[task 2019-12-21T10:44:47.660Z] 10:44:47 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7fa5ff0e65c0) [pid = 5691] [serial = 42] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:47.660Z] 10:44:47 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fa6011182e0) [pid = 5691] [serial = 6] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:47.982Z] 10:44:47 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7fcbc6805800) [pid = 5752] [serial = 67] [outer = (nil)] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:47.983Z] 10:44:47 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7fcbbe2b1400) [pid = 5752] [serial = 72] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:47.986Z] 10:44:47 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fcbbee17400) [pid = 5752] [serial = 70] [outer = (nil)] [url = http://example.org/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:48.685Z] 10:44:48 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (0x7fec1620dc00) [pid = 5557] [serial = 94] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:48.686Z] 10:44:48 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (0x7fec1859e000) [pid = 5557] [serial = 92] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:48.687Z] 10:44:48 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0x7fec16c8f000) [pid = 5557] [serial = 82] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:48.687Z] 10:44:48 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0x7fec169b9800) [pid = 5557] [serial = 84] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:48.688Z] 10:44:48 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0x7fec15354400) [pid = 5557] [serial = 88] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:48.689Z] 10:44:48 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7fec1620ec00) [pid = 5557] [serial = 90] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:48.689Z] 10:44:48 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fec16217400) [pid = 5557] [serial = 86] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:48.991Z] 10:44:48 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7fc9866b4000) [pid = 5719] [serial = 72] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:44:48.991Z] 10:44:48 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fc9866b8000) [pid = 5719] [serial = 70] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:51.684Z] 10:44:51 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fa601451800) [pid = 5691] [serial = 8] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-page.html]
[task 2019-12-21T10:44:51.685Z] 10:44:51 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fa5f7166c00) [pid = 5691] [serial = 43] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:09.679Z] 10:46:09 INFO - TEST-INFO | started process screentopng
[task 2019-12-21T10:46:11.004Z] 10:46:11 INFO - TEST-INFO | screentopng: exit 0
[task 2019-12-21T10:46:11.004Z] 10:46:11 INFO - Buffered messages logged at 10:44:39
[task 2019-12-21T10:46:11.005Z] 10:46:11 INFO - Entering test bound
[task 2019-12-21T10:46:11.005Z] 10:46:11 INFO - Buffered messages logged at 10:44:40
[task 2019-12-21T10:46:11.005Z] 10:46:11 INFO - Waiting for videos to be ready
[task 2019-12-21T10:46:11.008Z] 10:46:11 INFO - Buffered messages finished
[task 2019-12-21T10:46:11.008Z] 10:46:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Test timed out -
[task 2019-12-21T10:46:11.008Z] 10:46:11 INFO - GECKO(5557) | MEMORY STAT | vsize 3318MB | residentFast 361MB | heapAllocated 109MB
[task 2019-12-21T10:46:11.009Z] 10:46:11 INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | took 90089ms
[task 2019-12-21T10:46:11.009Z] 10:46:11 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-21T10:46:11.010Z] 10:46:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html -
[task 2019-12-21T10:46:11.014Z] 10:46:11 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fa5f6603800 == 1 [pid = 5691] [id = {34e035be-8052-4d46-bbf6-d658aba14ca5}]
[task 2019-12-21T10:46:11.014Z] 10:46:11 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7fa5ff4a57a0) [pid = 5691] [serial = 44] [outer = (nil)]
[task 2019-12-21T10:46:11.014Z] 10:46:11 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7fa5f7162c00) [pid = 5691] [serial = 45] [outer = 0x7fa5ff4a57a0]
[task 2019-12-21T10:46:11.014Z] 10:46:11 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fa5f7168800) [pid = 5691] [serial = 46] [outer = 0x7fa5ff4a57a0]
[task 2019-12-21T10:46:11.015Z] 10:46:11 INFO - checking window state
[task 2019-12-21T10:46:11.015Z] 10:46:11 INFO - GECKO(5557) | [Parent 5557, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/nsFrameLoader.cpp, line 665
[task 2019-12-21T10:46:11.015Z] 10:46:11 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7fec15147000) [pid = 5557] [serial = 95] [outer = 0x7fec275946a0]
[task 2019-12-21T10:46:11.019Z] 10:46:11 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-21T10:46:11.019Z] 10:46:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Uncaught exception received from previously timed out test - [Exception... "(null)" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "<unknown>" data: no]
[task 2019-12-21T10:46:11.020Z] 10:46:11 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7fec151cdc00) [pid = 5557] [serial = 96] [outer = 0x7fec275946a0]
[task 2019-12-21T10:46:11.022Z] 10:46:11 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fc98776b000 == 0 [pid = 5719] [id = {4ba22f29-0975-4c0b-a759-24ca9475b1b2}] [url = about:blank]
[task 2019-12-21T10:46:11.022Z] 10:46:11 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fcbbee27000 == 0 [pid = 5752] [id = {06e474e4-6289-4545-9b8f-73b62d90a114}] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html]
[task 2019-12-21T10:46:11.023Z] 10:46:11 INFO - GECKO(5557) | [Child 5780: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fa73ac57400) [pid = 5780] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:11.023Z] 10:46:11 INFO - GECKO(5557) | [Child 5780: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fa73ac5cc00) [pid = 5780] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:11.024Z] 10:46:11 INFO - GECKO(5557) | [Child 5780: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fa73abfd100) [pid = 5780] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:11.025Z] 10:46:11 INFO - GECKO(5557) | [Child 5691: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fa5f7162c00) [pid = 5691] [serial = 45] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:11.041Z] 10:46:11 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fcbbe2e9980) [pid = 5752] [serial = 71] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html]
[task 2019-12-21T10:46:11.041Z] 10:46:11 INFO - GECKO(5557) | [Child 5752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fcbbdc63400) [pid = 5752] [serial = 73] [outer = (nil)] [url = http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html]
[task 2019-12-21T10:46:11.259Z] 10:46:11 INFO - GECKO(5557) | [Child 5629: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fd0454a3000 == 0 [pid = 5629] [id = {2e3ad214-4ad8-4fe4-9530-e47e7ffa5d6e}] [url = about:newtab]
[task 2019-12-21T10:46:11.260Z] 10:46:11 INFO - GECKO(5557) | [Child 5629: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fd0468a9100) [pid = 5629] [serial = 4] [outer = (nil)] [url = about:newtab]
[task 2019-12-21T10:46:11.260Z] 10:46:11 INFO - GECKO(5557) | [Child 5629: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fd0466ca400) [pid = 5629] [serial = 6] [outer = (nil)] [url = about:newtab]
[task 2019-12-21T10:46:13.290Z] 10:46:13 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fc98f1aea60) [pid = 5719] [serial = 71] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:13.291Z] 10:46:13 INFO - GECKO(5557) | [Child 5719: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fc98f399400) [pid = 5719] [serial = 73] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:14.172Z] 10:46:14 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5848
[task 2019-12-21T10:46:14.208Z] 10:46:14 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5817
[task 2019-12-21T10:46:14.509Z] 10:46:14 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5831
[task 2019-12-21T10:46:14.590Z] 10:46:14 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5780
[task 2019-12-21T10:46:14.850Z] 10:46:14 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5719
[task 2019-12-21T10:46:14.865Z] 10:46:14 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5691
[task 2019-12-21T10:46:14.907Z] 10:46:14 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5629
[task 2019-12-21T10:46:14.928Z] 10:46:14 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5752
[task 2019-12-21T10:46:15.185Z] 10:46:15 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5608
[task 2019-12-21T10:46:15.468Z] 10:46:15 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7fec1e2eec00) [pid = 5557] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:18.336Z] 10:46:18 INFO - GECKO(5557) | [Parent 5557: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fec15147000) [pid = 5557] [serial = 95] [outer = (nil)] [url = about:blank]
[task 2019-12-21T10:46:18.856Z] 10:46:18 INFO - GECKO(5557) | Completed ShutdownLeaks collections in process 5557
[task 2019-12-21T10:46:18.856Z] 10:46:18 INFO - TEST-START | Shutdown
[task 2019-12-21T10:46:18.856Z] 10:46:18 INFO - Browser Chrome Test Summary
[task 2019-12-21T10:46:18.856Z] 10:46:18 INFO - Passed: 279
[task 2019-12-21T10:46:18.856Z] 10:46:18 INFO - Failed: 3
[task 2019-12-21T10:46:18.856Z] 10:46:18 INFO - Todo: 0
[task 2019-12-21T10:46:18.856Z] 10:46:18 INFO - Mode: e10s
[task 2019-12-21T10:46:18.856Z] 10:46:18 INFO - *** End BrowserChrome Test Results ***

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Flags: needinfo?(dolske) → needinfo?(mconley)

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

Component: General → Video/Audio Controls

This bug failed 43 times in the last 7 days. Occurrences are on linux1804-64 debug, linux1804-64-asan opt and a few on linux1804-64-shippable
opt build types.

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

Whiteboard: [stockwell unknown][stockwell needworks:owner]

In the last 7 days, there are 37 total failures on linux1804-64, linux1804-64-asan, linux1804-64-asan and linux1804-64-shippable.

Recent failure logs: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=307768006&repo=mozilla-central&lineNumber=7141

Jared, as you are the triage owner on this bug, can you please take a look?

Flags: needinfo?(jaws)
Whiteboard: [stockwell unknown][stockwell needworks:owner] → [stockwell unknown][stockwell needwork:owner]

In the last 7 days there were 47 failures. These happend on: linux1804-64, linux1804-64-asan, linux1804-64-shippable on opt and debug builds.

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

There are 39 failures in the last 7 days. I've added a patch to disable the test on those platforms in case we'll need it.
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-07-18&endday=2020-07-25&tree=trunk&bug=1552288
linux1804-64-asan - 10 failures
linux1804-64-shippable - 19 failures
linux1804-64 - 9 failures

Assignee: nobody → cbrindusan
Attachment #9166055 - Attachment is obsolete: true

No failures since the 23rd of July.

Assignee: cbrindusan → nobody
Whiteboard: [stockwell disable-recommended] → [stockwell unknown]

Failures have dropped down again. Clearing my needinfo.

Flags: needinfo?(jaws)

Update:
There have been 40 failures within the last 7 days:
• 7 failures on Linux 18.04 x64 WebRender asan opt
• 4 failures on Linux 18.04 x64 CCov WebRender opt
• 17 failures on Linux 18.04 x64 WebRender opt
• 1 failures on Linux 18.04 x64 WebRender debug
• 11 failures on Linux 18.04 x64 WebRender Shippable opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=347777033&repo=mozilla-central&lineNumber=10123

[task 2021-08-08T10:59:08.289Z] 10:59:08     INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js
[task 2021-08-08T10:59:53.304Z] 10:59:53     INFO - TEST-INFO | started process screentopng
[task 2021-08-08T10:59:53.869Z] 10:59:53     INFO - TEST-INFO | screentopng: exit 0
[task 2021-08-08T10:59:53.871Z] 10:59:53     INFO - Buffered messages logged at 10:59:08
[task 2021-08-08T10:59:53.872Z] 10:59:53     INFO - Entering test bound 
[task 2021-08-08T10:59:53.872Z] 10:59:53     INFO - Waiting for videos to be ready
[task 2021-08-08T10:59:53.873Z] 10:59:53     INFO - Waiting for 'canplaythrough' for 'video-alpha-background'
[task 2021-08-08T10:59:53.874Z] 10:59:53     INFO - Buffered messages finished
[task 2021-08-08T10:59:53.875Z] 10:59:53     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Test timed out - 
[task 2021-08-08T10:59:53.876Z] 10:59:53     INFO - GECKO(16096) | MEMORY STAT | vsize 3537MB | residentFast 334MB | heapAllocated 194MB
[task 2021-08-08T10:59:53.876Z] 10:59:53     INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | took 45026ms
[task 2021-08-08T10:59:53.877Z] 10:59:53     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-08T10:59:53.878Z] 10:59:53     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html - 
[task 2021-08-08T10:59:53.879Z] 10:59:53     INFO - checking window state
[task 2021-08-08T10:59:53.879Z] 10:59:53     INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-2.js
Whiteboard: [stockwell unknown] → [stockwell needswork:owner]

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

Flags: needinfo?(jaws)

Update:
There have been 52 failures within the last 7 days:
• 15 failures on Linux 18.04 x64 WebRender asan opt
• 3 failures on Linux 18.04 x64 CCov WebRender opt
• 3 failures on Linux 18.04 x64 WebRender debug
• 19 failures on Linux 18.04 x64 WebRender opt
• 12 failures on Linux 18.04 x64 WebRender Shippable opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=348458256&repo=autoland&lineNumber=5510

[task 2021-08-15T00:39:18.060Z] 00:39:18     INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js
[task 2021-08-15T00:39:18.526Z] 00:39:18     INFO - GECKO(6692) | must wait for focus in content
[task 2021-08-15T00:39:19.650Z] 00:39:19     INFO - GECKO(6692) | must wait for focus in content
[task 2021-08-15T00:40:48.121Z] 00:40:48     INFO - TEST-INFO | started process screentopng
[task 2021-08-15T00:40:48.842Z] 00:40:48     INFO - TEST-INFO | screentopng: exit 0
[task 2021-08-15T00:40:48.843Z] 00:40:48     INFO - Buffered messages logged at 00:39:18
[task 2021-08-15T00:40:48.843Z] 00:40:48     INFO - Entering test bound 
[task 2021-08-15T00:40:48.850Z] 00:40:48     INFO - Waiting for videos to be ready
[task 2021-08-15T00:40:48.851Z] 00:40:48     INFO - Testing video with id: video-transparent-background
[task 2021-08-15T00:40:48.852Z] 00:40:48     INFO - Checking toggle policy
[task 2021-08-15T00:40:48.853Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | No toggle policy should be set. - true == true - 
[task 2021-08-15T00:40:48.854Z] 00:40:48     INFO - Waiting for toggle to become visible
[task 2021-08-15T00:40:48.857Z] 00:40:48     INFO - Testing toggle for stage hoverVideo in position right, has used: true
[task 2021-08-15T00:40:48.857Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected .pip-expanded to be hidden. - true == true - 
[task 2021-08-15T00:40:48.857Z] 00:40:48     INFO - Buffered messages logged at 00:39:19
[task 2021-08-15T00:40:48.859Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Toggle reached target opacity. - true == true - 
[task 2021-08-15T00:40:48.859Z] 00:40:48     INFO - Hovering the toggle rect now.
[task 2021-08-15T00:40:48.859Z] 00:40:48     INFO - Waiting for toggle to reach full opacity
[task 2021-08-15T00:40:48.859Z] 00:40:48     INFO - Testing toggle for stage hoverToggle in position right, has used: true
[task 2021-08-15T00:40:48.859Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected .pip-expanded to be hidden. - true == true - 
[task 2021-08-15T00:40:48.871Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Toggle reached target opacity. - true == true - 
[task 2021-08-15T00:40:48.871Z] 00:40:48     INFO - Right-clicking on toggle.
[task 2021-08-15T00:40:48.871Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup"] deepEqual ["pointerdown","mousedown","pointerup","mouseup"] - 
[task 2021-08-15T00:40:48.871Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | No Picture-in-Picture window found. - 
[task 2021-08-15T00:40:48.872Z] 00:40:48     INFO - Clicking on toggle, and expecting a Picture-in-Picture window to open
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | A Picture-in-Picture window opened. - 
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - [] deepEqual [] - 
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup","click"] deepEqual ["pointerdown","mousedown","pointerup","mouseup","click"] - 
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - Testing video with id: video-alpha-background
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - Checking toggle policy
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - Buffered messages logged at 00:39:20
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | No toggle policy should be set. - true == true - 
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - Waiting for toggle to become visible
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - Testing toggle for stage hoverVideo in position right, has used: true
[task 2021-08-15T00:40:48.873Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected .pip-expanded to be hidden. - true == true - 
[task 2021-08-15T00:40:48.874Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Toggle reached target opacity. - true == true - 
[task 2021-08-15T00:40:48.875Z] 00:40:48     INFO - Hovering the toggle rect now.
[task 2021-08-15T00:40:48.875Z] 00:40:48     INFO - Waiting for toggle to reach full opacity
[task 2021-08-15T00:40:48.875Z] 00:40:48     INFO - Testing toggle for stage hoverToggle in position right, has used: true
[task 2021-08-15T00:40:48.876Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected .pip-expanded to be hidden. - true == true - 
[task 2021-08-15T00:40:48.877Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Toggle reached target opacity. - true == true - 
[task 2021-08-15T00:40:48.878Z] 00:40:48     INFO - Right-clicking on toggle.
[task 2021-08-15T00:40:48.879Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup"] deepEqual ["pointerdown","mousedown","pointerup","mouseup"] - 
[task 2021-08-15T00:40:48.879Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | No Picture-in-Picture window found. - 
[task 2021-08-15T00:40:48.880Z] 00:40:48     INFO - Clicking on toggle, and expecting a Picture-in-Picture window to open
[task 2021-08-15T00:40:48.881Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | A Picture-in-Picture window opened. - 
[task 2021-08-15T00:40:48.882Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - [] deepEqual [] - 
[task 2021-08-15T00:40:48.884Z] 00:40:48     INFO - TEST-PASS | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Expected to get the right mouse events. - ["pointerdown","mousedown","pointerup","mouseup","click"] deepEqual ["pointerdown","mousedown","pointerup","mouseup","click"] - 
[task 2021-08-15T00:40:48.885Z] 00:40:48     INFO - Buffered messages logged at 00:39:21
[task 2021-08-15T00:40:48.886Z] 00:40:48     INFO - Waiting for videos to be ready
[task 2021-08-15T00:40:48.887Z] 00:40:48     INFO - Waiting for 'canplaythrough' for 'video-transparent-background'
[task 2021-08-15T00:40:48.887Z] 00:40:48     INFO - Buffered messages finished
[task 2021-08-15T00:40:48.888Z] 00:40:48     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Test timed out - 
[task 2021-08-15T00:40:48.889Z] 00:40:48     INFO - GECKO(6692) | MEMORY STAT | vsize 20975675MB | residentFast 1307MB
[task 2021-08-15T00:40:48.890Z] 00:40:48     INFO - TEST-OK | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | took 90072ms
[task 2021-08-15T00:40:48.891Z] 00:40:48     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-15T00:40:48.892Z] 00:40:48     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/pictureinpicture/tests/browser_toggleTransparentOverlay-1.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/components/pictureinpicture/tests/test-transparent-overlay-1.html - 
[task 2021-08-15T00:40:48.893Z] 00:40:48     INFO - checking window state
[task 2021-08-15T00:40:48.894Z] 00:40:48     INFO - TEST-START | toolkit/components/pictureinpicture/tests/browser_videoSelection.js
Severity: normal → --
Priority: P5 → --
Assignee: nobody → abutkovits
Pushed by abutkovits@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/9698b4b0b32f
disable browser_toggleTransparentOverlay-1.js on Linux_64_QR for frequent failures. r=intermittent-reviewers,jmaher DONTBUILD
Assignee: abutkovits → nobody
Keywords: leave-open
Whiteboard: [stockwell needswork:owner] → [stockwell disabled]
Severity: -- → N/A
Flags: needinfo?(mconley)
Priority: -- → P3
Flags: needinfo?(jaws)
Component: Video/Audio Controls → Picture-in-Picture
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: