Closed Bug 1830238 Opened 2 years ago Closed 2 years ago

Perma release OSX shippable dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | Test timed out -

Categories

(Core :: DOM: Core & HTML, defect, P5)

defect

Tracking

()

RESOLVED FIXED

People

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

References

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2023-04-27T05:50:49.240Z] 05:50:49     INFO - TEST-START | dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js
[task 2023-04-27T05:50:49.260Z] 05:50:49     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1075bcc00 == 2 [pid = 4749] [id = 20]
[task 2023-04-27T05:50:49.260Z] 05:50:49     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (1034147d0) [pid = 4749] [serial = 54] [outer = 0]
[task 2023-04-27T05:50:49.261Z] 05:50:49     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 22 (1075bd000) [pid = 4749] [serial = 55] [outer = 1034147d0]
[task 2023-04-27T05:50:49.292Z] 05:50:49     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 23 (119b07c00) [pid = 4749] [serial = 56] [outer = 1034147d0]
[task 2023-04-27T05:50:49.299Z] 05:50:49     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 119b09000 == 3 [pid = 4749] [id = 21]
[task 2023-04-27T05:50:49.299Z] 05:50:49     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 24 (11a13c010) [pid = 4749] [serial = 57] [outer = 0]
[task 2023-04-27T05:50:49.300Z] 05:50:49     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 25 (119b09400) [pid = 4749] [serial = 58] [outer = 11a13c010]
[task 2023-04-27T05:50:49.338Z] 05:50:49     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1101c5000 == 1 [pid = 4911] [id = 0]
[task 2023-04-27T05:50:49.339Z] 05:50:49     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (10fa145e0) [pid = 4911] [serial = 1] [outer = 0]
[task 2023-04-27T05:50:49.340Z] 05:50:49     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (1101c9400) [pid = 4911] [serial = 2] [outer = 10fa145e0]
[task 2023-04-27T05:50:49.369Z] 05:50:49     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (1101cb400) [pid = 4911] [serial = 3] [outer = 10fa145e0]
[task 2023-04-27T05:50:49.377Z] 05:50:49     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1101cf400 == 2 [pid = 4911] [id = 1]
[task 2023-04-27T05:50:49.378Z] 05:50:49     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (10fa16100) [pid = 4911] [serial = 4] [outer = 0]
[task 2023-04-27T05:50:49.378Z] 05:50:49     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (1101cf800) [pid = 4911] [serial = 5] [outer = 10fa16100]
[task 2023-04-27T05:50:49.395Z] 05:50:49     INFO - GECKO(4747) | [Child 4912: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1131c1000 == 1 [pid = 4912] [id = 0]
[task 2023-04-27T05:50:49.395Z] 05:50:49     INFO - GECKO(4747) | [Child 4912: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (1103145e0) [pid = 4912] [serial = 1] [outer = 0]
[task 2023-04-27T05:50:49.396Z] 05:50:49     INFO - GECKO(4747) | [Child 4912: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (1131c5400) [pid = 4912] [serial = 2] [outer = 1103145e0]
[task 2023-04-27T05:50:49.411Z] 05:50:49     INFO - GECKO(4747) | [Child 4911, Main Thread] WARNING: '!scrollbar', file /builds/worker/checkouts/gecko/widget/Theme.cpp:1103
[task 2023-04-27T05:50:49.421Z] 05:50:49     INFO - GECKO(4747) | [Child 4912: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (1131c7400) [pid = 4912] [serial = 3] [outer = 1103145e0]
[task 2023-04-27T05:50:49.753Z] 05:50:49     INFO - GECKO(4747) | JavaScript error: http://example.org/browser/dom/base/test/fullscreen/file_fullscreen-iframe-inner.html, line 1: TypeError: Fullscreen request denied
[task 2023-04-27T05:50:50.509Z] 05:50:50     INFO - GECKO(4747) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/1c/7gz26xcj1xb1vwr7p52qhtcm000014/T/tmpw0d1bfdk.mozrunner/runtests_leaks_tab_pid4919.log
[task 2023-04-27T05:50:50.509Z] 05:50:50     INFO - GECKO(4747) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/1c/7gz26xcj1xb1vwr7p52qhtcm000014/T/tmpw0d1bfdk.mozrunner/runtests_leaks_tab_pid4918.log
[task 2023-04-27T05:50:50.510Z] 05:50:50     INFO - GECKO(4747) | [4919, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-04-27T05:50:50.510Z] 05:50:50     INFO - GECKO(4747) | [4918, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-04-27T05:50:50.546Z] 05:50:50     INFO - GECKO(4747) | [Child 4919, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-04-27T05:50:50.546Z] 05:50:50     INFO - GECKO(4747) | [Child 4918, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-04-27T05:50:50.566Z] 05:50:50     INFO - GECKO(4747) | [Child 4919, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-04-27T05:50:50.566Z] 05:50:50     INFO - GECKO(4747) | [Child 4919, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-04-27T05:50:50.567Z] 05:50:50     INFO - GECKO(4747) | [Child 4918, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-04-27T05:50:50.567Z] 05:50:50     INFO - GECKO(4747) | [Child 4918, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-04-27T05:50:50.607Z] 05:50:50     INFO - GECKO(4747) | [Child 4918, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-04-27T05:50:50.608Z] 05:50:50     INFO - GECKO(4747) | [Child 4919, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-04-27T05:50:51.383Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 119b09000 == 2 [pid = 4749] [id = 21] [url = about:blank]
[task 2023-04-27T05:50:51.383Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 24 (1075bbc00) [pid = 4749] [serial = 27] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.384Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 23 (119b0a000) [pid = 4749] [serial = 36] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.384Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 22 (119b09800) [pid = 4749] [serial = 35] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.385Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (119b07000) [pid = 4749] [serial = 33] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.385Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (119b08000) [pid = 4749] [serial = 32] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.386Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (1075b5400) [pid = 4749] [serial = 29] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.386Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (1075bd400) [pid = 4749] [serial = 30] [outer = 0] [url = data:text/html,      <button id="button">Click here</button>      <script>        let button = document.getElementById("button");        button.addEventListener("click", function() {          button.requestFullscreen();        });      </script>]
[task 2023-04-27T05:50:51.387Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (119b11800) [pid = 4749] [serial = 50] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.387Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (119b0a800) [pid = 4749] [serial = 38] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.388Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (119b0fc00) [pid = 4749] [serial = 48] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.388Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (119b0f400) [pid = 4749] [serial = 47] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.389Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (119b0d400) [pid = 4749] [serial = 45] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.389Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (119b0e800) [pid = 4749] [serial = 44] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.390Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (1075bc000) [pid = 4749] [serial = 42] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.390Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (119b0b000) [pid = 4749] [serial = 39] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.391Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (119b0c000) [pid = 4749] [serial = 41] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.391Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1075b4800 == 1 [pid = 4749] [id = 19] [url = about:blank]
[task 2023-04-27T05:50:51.403Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (103417a30) [pid = 4749] [serial = 49] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.403Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (11a13c010) [pid = 4749] [serial = 57] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:51.404Z] 05:50:51     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (1034145e0) [pid = 4749] [serial = 52] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:52.120Z] 05:50:52     INFO - GECKO(4747) | [Child 4770: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (10efd5c00) [pid = 4770] [serial = 58] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:52.120Z] 05:50:52     INFO - GECKO(4747) | [Child 4770: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (10fae9400) [pid = 4770] [serial = 64] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:52.121Z] 05:50:52     INFO - GECKO(4747) | [Child 4770: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (10fae9000) [pid = 4770] [serial = 63] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:52.122Z] 05:50:52     INFO - GECKO(4747) | [Child 4770: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (10efddc00) [pid = 4770] [serial = 61] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:52.122Z] 05:50:52     INFO - GECKO(4747) | [Child 4770: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (10efd8800) [pid = 4770] [serial = 59] [outer = 0] [url = data:text/html,    <div name="div" id="div" style="width: 100px; height: 100px; background: red;">    <iframe id="iframe" allowfullscreen="yes"     src="http://mochi.test:8888/browser/dom/base/test/fullscreen/file_fullscreen-iframe-middle.html"></iframe>    </div>]
[task 2023-04-27T05:50:53.200Z] 05:50:53     INFO - GECKO(4747) | [Parent 4747: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 13e247c00 == 5 [pid = 4747] [id = 7] [url = chrome://browser/content/browser.xhtml]
[task 2023-04-27T05:50:53.206Z] 05:50:53     INFO - GECKO(4747) | [Child 4911, Main Thread] WARNING: '!scrollbar', file /builds/worker/checkouts/gecko/widget/Theme.cpp:1103
[task 2023-04-27T05:50:53.522Z] 05:50:53     INFO - GECKO(4747) | [Child 4749, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-04-27T05:50:53.912Z] 05:50:53     INFO - GECKO(4747) | [Parent 4747, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-04-27T05:50:53.923Z] 05:50:53     INFO - GECKO(4747) | [Parent 4747: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (185aa1470) [pid = 4747] [serial = 18] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:53.923Z] 05:50:53     INFO - GECKO(4747) | [Parent 4747: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (132356370) [pid = 4747] [serial = 16] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2023-04-27T05:50:54.068Z] 05:50:54     INFO - GECKO(4747) | [Child 4770, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-04-27T05:50:55.722Z] 05:50:55     INFO - GECKO(4747) | [Child 4751: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1085a6800 == 2 [pid = 4751] [id = 26] [url = about:blank]
[task 2023-04-27T05:50:55.723Z] 05:50:55     INFO - GECKO(4747) | [Child 4751: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1085a8800 == 1 [pid = 4751] [id = 27] [url = about:blank]
[task 2023-04-27T05:50:55.854Z] 05:50:55     INFO - GECKO(4747) | [Child 4751: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (1013145e0) [pid = 4751] [serial = 71] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:55.854Z] 05:50:55     INFO - GECKO(4747) | [Child 4751: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (1013147d0) [pid = 4751] [serial = 73] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:55.856Z] 05:50:55     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (119b09400) [pid = 4749] [serial = 58] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:55.856Z] 05:50:55     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (1075bd000) [pid = 4749] [serial = 55] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:55.857Z] 05:50:55     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (1075b5000) [pid = 4749] [serial = 53] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:55.857Z] 05:50:55     INFO - GECKO(4747) | [Child 4749: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (119b0f800) [pid = 4749] [serial = 51] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:57.524Z] 05:50:57     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1101cf400 == 1 [pid = 4911] [id = 1] [url = about:blank]
[task 2023-04-27T05:50:57.855Z] 05:50:57     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (10fa16100) [pid = 4911] [serial = 4] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:57.993Z] 05:50:57     INFO - GECKO(4747) | [Parent 4747: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (13eba5000) [pid = 4747] [serial = 19] [outer = 0] [url = about:blank]
[task 2023-04-27T05:50:57.993Z] 05:50:57     INFO - GECKO(4747) | [Parent 4747: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (13e24bc00) [pid = 4747] [serial = 17] [outer = 0] [url = about:blank]
[task 2023-04-27T05:51:00.234Z] 05:51:00     INFO - GECKO(4747) | [Child 4751: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (1085a6c00) [pid = 4751] [serial = 72] [outer = 0] [url = about:blank]
[task 2023-04-27T05:51:00.235Z] 05:51:00     INFO - GECKO(4747) | [Child 4751: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (1085aa000) [pid = 4751] [serial = 75] [outer = 0] [url = about:blank]
[task 2023-04-27T05:51:00.236Z] 05:51:00     INFO - GECKO(4747) | [Child 4751: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1085a9000) [pid = 4751] [serial = 74] [outer = 0] [url = about:blank]
[task 2023-04-27T05:51:02.353Z] 05:51:02     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (1101c9400) [pid = 4911] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-04-27T05:51:02.353Z] 05:51:02     INFO - GECKO(4747) | [Child 4911: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1101cf800) [pid = 4911] [serial = 5] [outer = 0] [url = about:blank]
[task 2023-04-27T05:51:02.548Z] 05:51:02     INFO - GECKO(4747) | [Child 4912: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1131c5400) [pid = 4912] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-04-27T05:53:49.328Z] 05:53:49     INFO - TEST-INFO | started process screencapture
[task 2023-04-27T05:53:49.409Z] 05:53:49     INFO - TEST-INFO | screencapture: exit 0
[task 2023-04-27T05:53:49.409Z] 05:53:49     INFO - Buffered messages logged at 05:50:49
[task 2023-04-27T05:53:49.410Z] 05:53:49     INFO - Entering setup bound 
[task 2023-04-27T05:53:49.410Z] 05:53:49     INFO - Leaving setup bound 
[task 2023-04-27T05:53:49.410Z] 05:53:49     INFO - Entering test bound 
[task 2023-04-27T05:53:49.411Z] 05:53:49     INFO - Test tab_close_toplevel, url: data:text/html,
[task 2023-04-27T05:53:49.411Z] 05:53:49     INFO -     <div name="div" id="div" style="width: 100px; height: 100px; background: red;">
[task 2023-04-27T05:53:49.411Z] 05:53:49     INFO -     <iframe id="iframe" allowfullscreen="yes"
[task 2023-04-27T05:53:49.411Z] 05:53:49     INFO -      src="http://mochi.test:8888/browser/dom/base/test/fullscreen/file_fullscreen-iframe-middle.html"></iframe>
[task 2023-04-27T05:53:49.411Z] 05:53:49     INFO -     </div>
[task 2023-04-27T05:53:49.412Z] 05:53:49     INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "data:text/html,    <div name="div" id="div" style="width: 100px; height: 100px; background: red;">    <iframe id="iframe" allowfullscreen="yes"     src="http://mochi.test:8888/browser/dom/base/test/fullscreen/file_fullscreen-iframe-middle.html"></iframe>    </div>" line: 0}]
[task 2023-04-27T05:53:49.413Z] 05:53:49     INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://mochi.test:8888/browser/dom/base/test/fullscreen/file_fullscreen-iframe-middle.html" line: 0}]
[task 2023-04-27T05:53:49.414Z] 05:53:49     INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://mochi.test:8888/browser/dom/base/test/fullscreen/file_fullscreen-iframe-middle.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2023-04-27T05:53:49.414Z] 05:53:49     INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://example.org/browser/dom/base/test/fullscreen/file_fullscreen-iframe-inner.html" line: 0}]
[task 2023-04-27T05:53:49.415Z] 05:53:49     INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “http://example.org/browser/dom/base/test/fullscreen/file_fullscreen-iframe-inner.html” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2023-04-27T05:53:49.415Z] 05:53:49     INFO - waitForFullscreenExit
[task 2023-04-27T05:53:49.416Z] 05:53:49     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "http://example.org/browser/dom/base/test/fullscreen/file_fullscreen-iframe-inner.html" line: 1}]
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied" {file: "http://example.org/browser/dom/base/test/fullscreen/file_fullscreen-iframe-inner.html" line: 1}]
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - onclick@http://example.org/browser/dom/base/test/fullscreen/file_fullscreen-iframe-inner.html:1:5
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - @chrome://mochitests/content/browser/dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js:47:56
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - setTimeout handler*@chrome://mochitests/content/browser/dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js:46:23
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - execute@resource://specialpowers/SpecialPowersSandbox.sys.mjs:139:12
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - _spawnTask@resource://specialpowers/SpecialPowersChild.sys.mjs:1648:15
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - receiveMessage@resource://specialpowers/SpecialPowersChild.sys.mjs:261:21
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - JSActor query*receiveMessage@resource://specialpowers/SpecialPowersParent.sys.mjs:1388:14
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - JSActor query*spawn@resource://specialpowers/SpecialPowersChild.sys.mjs:1579:17
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - startTests/</</<@chrome://mochitests/content/browser/dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js:41:25
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - withNewTab@resource://testing-common/BrowserTestUtils.sys.mjs:154:22
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - async*startTests/</<@chrome://mochitests/content/browser/dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js:32:30
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - handleTask@chrome://mochikit/content/browser-test.js:1133:26
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1205:18
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1347:14
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1122:14
[task 2023-04-27T05:53:49.418Z] 05:53:49     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13
[task 2023-04-27T05:53:49.419Z] 05:53:49     INFO - 
[task 2023-04-27T05:53:49.419Z] 05:53:49     INFO - Buffered messages logged at 05:52:19
[task 2023-04-27T05:53:49.419Z] 05:53:49     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2023-04-27T05:53:49.420Z] 05:53:49     INFO - Buffered messages finished
[task 2023-04-27T05:53:49.420Z] 05:53:49     INFO - TEST-UNEXPECTED-FAIL | dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | Test timed out - 
[task 2023-04-27T05:53:49.421Z] 05:53:49     INFO - GECKO(4747) | MEMORY STAT | vsize 9112MB | residentFast 399MB | heapAllocated 135MB
[task 2023-04-27T05:53:49.421Z] 05:53:49     INFO - TEST-OK | dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | took 180102ms
[task 2023-04-27T05:53:49.422Z] 05:53:49     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-04-27T05:53:49.422Z] 05:53:49     INFO - TEST-UNEXPECTED-FAIL | dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | Found a tab after previous test timed out: data:text/html,    <div name="div" id="div" style="width: 100px; height: 100px; background: red;">    <iframe id="iframe" allowfullscreen="yes"     src="http://mochi.test:8888/browser/dom/base/test/fullscreen/file_fullscreen-iframe-middle.html"></iframe>    </div> - 
[task 2023-04-27T05:53:49.423Z] 05:53:49     INFO - GECKO(4747) | [Child 4770: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 10efd5400 == 1 [pid = 4770] [id = 24]
[task 2023-04-27T05:53:49.423Z] 05:53:49     INFO - GECKO(4747) | [Child 4770: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (1076145e0) [pid = 4770] [serial = 65] [outer = 0]
[task 2023-04-27T05:53:49.424Z] 05:53:49     INFO - GECKO(4747) | [Child 4770: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (10efd5c00) [pid = 4770] [serial = 66] [outer = 1076145e0]
[task 2023-04-27T05:53:49.424Z] 05:53:49     INFO - checking window state

Since this is affecting beta mostly it might be from https://hg.mozilla.org/releases/mozilla-beta/rev/43e4aa86fa0b2dd4bc1b80548a4fdc6c59d0c727
Edgar, could you have a look over these timeouts? Thank you.

Flags: needinfo?(echen)

In addition to what Cosmin said, it looks like it's perma failling on beta, as it can be seen here and it didn't affect anything else (apart from beta) in these days.

Summary: Intermittent dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | single tracking bug → Perma beta dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | Test timed out -

Also https://treeherder.mozilla.org/logviewer?job_id=413997295&repo=mozilla-beta - Bug 1784875 is permafailing on OS X 10.15 WebRender Shippable.

See Also: → 1830585, 1784875
Summary: Perma beta dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | Test timed out - → Perma beta OSX devedition dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | Test timed out -

(In reply to Cosmin Sabou [:CosminS] from comment #1)

Since this is affecting beta mostly it might be from https://hg.mozilla.org/releases/mozilla-beta/rev/43e4aa86fa0b2dd4bc1b80548a4fdc6c59d0c727
Edgar, could you have a look over these timeouts? Thank you.

Yes, this is probably caused by bug 1823284.
I suspect we see this on beta only because some other fullscreen improvements aren't uplift to beta.
I will take a look, I think we could also consider disabling this test on beta temporary.

Assignee: nobody → echen
Severity: S4 → S3
Flags: needinfo?(echen)

Cosmin, now 114 is merged to beta, do we still see this perma timout?

Flags: needinfo?(csabou)

Green on beta, Perma on release. I guess it will be fixed coming next merge to release on May 29th.

Flags: needinfo?(csabou)
Summary: Perma beta OSX devedition dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | Test timed out - → Perma release OSX shippable dom/base/test/fullscreen/browser_fullscreen-tab-close-race.js | Test timed out -

Yes, it should be fixed after 114 is merged to release.

No further failures after merging to release so I think it's safe to close this.

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