Closed Bug 1778034 Opened 2 years ago Closed 2 years ago

Intermittent TV devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | leaked 2 window(s) until shutdown [url = about:devtools-toolbox]

Categories

(DevTools :: Inspector: Compatibility, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=383330140&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/buZo4gcNTEapAoj9rrIg4w/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/buZo4gcNTEapAoj9rrIg4w/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2022-07-04T23:46:03.459Z] 23:46:03     INFO - TEST-OK | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | took 2869ms
[task 2022-07-04T23:46:03.466Z] 23:46:03     INFO - GECKO(2752) | [Child 8216: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL bc02400 == 1 [pid = 8216] [id = 5]
[task 2022-07-04T23:46:03.467Z] 23:46:03     INFO - GECKO(2752) | [Child 8216: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (526c900) [pid = 8216] [serial = 11] [outer = 0]
[task 2022-07-04T23:46:03.467Z] 23:46:03     INFO - GECKO(2752) | [Child 8216: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (bc03f00) [pid = 8216] [serial = 12] [outer = 526c900]
[task 2022-07-04T23:46:03.474Z] 23:46:03     INFO - GECKO(2752) | [Child 7112, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-04T23:46:03.487Z] 23:46:03     INFO - checking window state
[task 2022-07-04T23:46:03.501Z] 23:46:03     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 28 (18883d00) [pid = 4468] [serial = 85] [outer = 1b2d9400]
[task 2022-07-04T23:46:03.567Z] 23:46:03     INFO - GECKO(2752) | [Child 8216: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (bc05400) [pid = 8216] [serial = 8] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:03.578Z] 23:46:03     INFO - GECKO(2752) | [Child 8216: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (bc05100) [pid = 8216] [serial = 10] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:03.591Z] 23:46:03     INFO - GECKO(2752) | [Child 7112: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 5651b00 == 1 [pid = 7112] [id = 4] [url = about:blank]
[task 2022-07-04T23:46:03.594Z] 23:46:03     INFO - GECKO(2752) | [Child 7112: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL a5ea200 == 0 [pid = 7112] [id = 5] [url = about:blank]
[task 2022-07-04T23:46:03.607Z] 23:46:03     INFO - GECKO(2752) | [Child 7112: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (a5e9600) [pid = 7112] [serial = 12] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:03.609Z] 23:46:03     INFO - GECKO(2752) | [Child 7112: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (566c700) [pid = 7112] [serial = 11] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:03.635Z] 23:46:03     INFO - GECKO(2752) | [Child 3556: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL a3ecc00 == 0 [pid = 3556] [id = 0] [url = about:newtab]
[task 2022-07-04T23:46:03.652Z] 23:46:03     INFO - GECKO(2752) | [Child 7112: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (566c600) [pid = 7112] [serial = 13] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:03.684Z] 23:46:03     INFO - GECKO(2752) | [Child 3556: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (566ed00) [pid = 3556] [serial = 1] [outer = 0] [url = about:newtab]
[task 2022-07-04T23:46:03.694Z] 23:46:03     INFO - GECKO(2752) | [Child 3556: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (a3eed00) [pid = 3556] [serial = 3] [outer = 0] [url = about:newtab]
[task 2022-07-04T23:46:03.722Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL a6f8a00 == 1 [pid = 6584] [id = 8] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:03.732Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (a6f7500) [pid = 6584] [serial = 32] [outer = 0] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:03.733Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (ed90100) [pid = 6584] [serial = 31] [outer = 0] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:03.777Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (c5b3600) [pid = 6584] [serial = 39] [outer = 0] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:03.791Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (c5b5a00) [pid = 6584] [serial = 36] [outer = 0] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:03.792Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (c588a00) [pid = 6584] [serial = 34] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:03.793Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (c5b3900) [pid = 6584] [serial = 35] [outer = 0] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:03.793Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (a6f8700) [pid = 6584] [serial = 38] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:03.794Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 5853d00 == 0 [pid = 6584] [id = 9] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:03.795Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (ed30b00) [pid = 6584] [serial = 33] [outer = 0] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:03.868Z] 23:46:03     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (586ce00) [pid = 6584] [serial = 37] [outer = 0] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:04.139Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 221fa300 == 7 [pid = 4468] [id = 35] [url = chrome://devtools/content/inspector/markup/markup.xhtml]
[task 2022-07-04T23:46:04.146Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1caeda00 == 6 [pid = 4468] [id = 34] [url = chrome://devtools/content/inspector/index.xhtml]
[task 2022-07-04T23:46:04.147Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 27 (18778500) [pid = 4468] [serial = 67] [outer = 0] [url = chrome://devtools/content/inspector/index.xhtml]
[task 2022-07-04T23:46:04.147Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 26 (14d1ac00) [pid = 4468] [serial = 64] [outer = 0] [url = about:devtools-toolbox]
[task 2022-07-04T23:46:04.148Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 25 (18804f00) [pid = 4468] [serial = 76] [outer = 0] [url = chrome://devtools/content/inspector/markup/markup.xhtml]
[task 2022-07-04T23:46:04.148Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 24 (1caec200) [pid = 4468] [serial = 66] [outer = 0] [url = about:devtools-toolbox]
[task 2022-07-04T23:46:04.149Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 23 (18883700) [pid = 4468] [serial = 79] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:04.149Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 14b02400 == 5 [pid = 4468] [id = 33] [url = about:devtools-toolbox]
[task 2022-07-04T23:46:04.150Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 22 (20053c00) [pid = 4468] [serial = 68] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:04.150Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (24924100) [pid = 4468] [serial = 77] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:04.492Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (18805e00) [pid = 4468] [serial = 83] [outer = 0] [url = chrome://devtools/content/inspector/markup/markup.xhtml]
[task 2022-07-04T23:46:04.495Z] 23:46:04     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (23bf6700) [pid = 4468] [serial = 84] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:04.513Z] 23:46:04     INFO - GECKO(2752) | [Child 7112: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (a5eb400) [pid = 7112] [serial = 14] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:04.595Z] 23:46:04     INFO - GECKO(2752) | [Child 6584: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (c5b4b00) [pid = 6584] [serial = 40] [outer = 0] [url = data:text/html;charset=utf-8,%0A%20%20%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20color%3A%20blue%3B%0A%20%20%20%20ruby-align%3A%20center%3B%0A%20%20%20%20user-modify%3A%20read-only%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20font-variant-alternates%3A%20historical-forms%3B%0A%20%20%7D%0A%20%20%3C%2Fstyle%3E%0A%20%20%3Cbody%3E%0A%20%20%20%20%3Cdiv%3Etest%3C%2Fdiv%3E%0A%20%20%3C%2Fbody%3E%0A]
[task 2022-07-04T23:46:05.614Z] 23:46:05     INFO - GECKO(2752) | Completed ShutdownLeaks collections in process 7112
[task 2022-07-04T23:46:05.627Z] 23:46:05     INFO - GECKO(2752) | Completed ShutdownLeaks collections in process 3556
[task 2022-07-04T23:46:05.633Z] 23:46:05     INFO - GECKO(2752) | Completed ShutdownLeaks collections in process 8216
[task 2022-07-04T23:46:05.646Z] 23:46:05     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (14d17400) [pid = 4468] [serial = 71] [outer = 0] [url = about:devtools-toolbox]
[task 2022-07-04T23:46:05.657Z] 23:46:05     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (18779200) [pid = 4468] [serial = 74] [outer = 0] [url = chrome://devtools/content/inspector/index.xhtml]
[task 2022-07-04T23:46:05.658Z] 23:46:05     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (1caed400) [pid = 4468] [serial = 73] [outer = 0] [url = about:devtools-toolbox]
[task 2022-07-04T23:46:05.658Z] 23:46:05     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (1caecb00) [pid = 4468] [serial = 9] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:05.659Z] 23:46:05     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (1f4f8900) [pid = 4468] [serial = 75] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:05.792Z] 23:46:05     INFO - GECKO(2752) | Completed ShutdownLeaks collections in process 7076
[task 2022-07-04T23:46:05.816Z] 23:46:05     INFO - GECKO(2752) | Completed ShutdownLeaks collections in process 6584
[task 2022-07-04T23:46:06.129Z] 23:46:06     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (14b04500) [pid = 4468] [serial = 86] [outer = 1b2d9400]
[task 2022-07-04T23:46:06.162Z] 23:46:06     INFO - GECKO(2752) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165697399732173\AppData\Local\Temp\tmpvf8gf7xi.mozrunner\runtests_leaks_tab_pid7580.log
[task 2022-07-04T23:46:06.166Z] 23:46:06     INFO - GECKO(2752) | [7580, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:340
[task 2022-07-04T23:46:06.179Z] 23:46:06     INFO - GECKO(2752) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165697399732173\AppData\Local\Temp\tmpvf8gf7xi.mozrunner\runtests_leaks_tab_pid7224.log
[task 2022-07-04T23:46:06.180Z] 23:46:06     INFO - GECKO(2752) | [7224, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:340
[task 2022-07-04T23:46:06.193Z] 23:46:06     INFO - GECKO(2752) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165697399732173\AppData\Local\Temp\tmpvf8gf7xi.mozrunner\runtests_leaks_tab_pid5732.log
[task 2022-07-04T23:46:06.194Z] 23:46:06     INFO - GECKO(2752) | [5732, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:340
[task 2022-07-04T23:46:08.105Z] 23:46:08     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (18883d00) [pid = 4468] [serial = 85] [outer = 0] [url = about:blank]
[task 2022-07-04T23:46:08.558Z] 23:46:08     INFO - GECKO(2752) | Completed ShutdownLeaks collections in process 4468
[task 2022-07-04T23:46:08.566Z] 23:46:08     INFO - TEST-START | Shutdown
[task 2022-07-04T23:46:08.566Z] 23:46:08     INFO - Browser Chrome Test Summary
[task 2022-07-04T23:46:08.567Z] 23:46:08     INFO - Passed:  260
[task 2022-07-04T23:46:08.567Z] 23:46:08     INFO - Failed:  0
[task 2022-07-04T23:46:08.568Z] 23:46:08     INFO - Todo:    0
[task 2022-07-04T23:46:08.568Z] 23:46:08     INFO - Mode:    e10s
[task 2022-07-04T23:46:08.569Z] 23:46:08     INFO - *** End BrowserChrome Test Results ***
[task 2022-07-04T23:46:08.598Z] 23:46:08     INFO - GECKO(2752) | [Child 7076, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-07-04T23:46:08.602Z] 23:46:08     INFO - GECKO(2752) | [Child 7076, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-07-04T23:46:08.605Z] 23:46:08     INFO - GECKO(2752) | [Child 7076, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-07-04T23:46:08.609Z] 23:46:08     INFO - GECKO(2752) | [Child 7076, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-07-04T23:46:08.615Z] 23:46:08     INFO - GECKO(2752) | [Child 7076, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-07-04T23:46:08.616Z] 23:46:08     INFO - GECKO(2752) | [Child 7076, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-07-04T23:46:08.629Z] 23:46:08     INFO - GECKO(2752) | [Child 7076, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-07-04T23:46:08.632Z] 23:46:08     INFO - GECKO(2752) | [Parent 4468, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-04T23:46:08.633Z] 23:46:08     INFO - GECKO(2752) | [Child 8216, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-04T23:46:08.648Z] 23:46:08     INFO - GECKO(2752) | [Parent 4468: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1886b700 == 4 [pid = 4468] [id = 2] [url = chrome://extensions/content/dummy.xhtml]
[task 2022-07-04T23:46:08.709Z] 23:46:08     INFO - GECKO(2752) | 1656978368714	Marionette	TRACE	Received observer notification quit-application
[task 2022-07-04T23:46:08.716Z] 23:46:08     INFO - GECKO(2752) | 1656978368715	Marionette	INFO	Stopped listening on port 2828
[task 2022-07-04T23:46:08.717Z] 23:46:08     INFO - GECKO(2752) | 1656978368715	Marionette	DEBUG	Marionette stopped listening
<...>
[task 2022-07-04T23:46:10.167Z] 23:46:10     INFO - GECKO(2752) | [Parent 4468, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-07-04T23:46:10.180Z] 23:46:10     INFO - GECKO(2752) | [Parent 4468, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-07-04T23:46:10.226Z] 23:46:10     INFO - GECKO(2752) | [Parent 4468, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4595
[task 2022-07-04T23:46:10.257Z] 23:46:10     INFO - TEST-INFO | Main app process: exit 0
[task 2022-07-04T23:46:10.258Z] 23:46:10     INFO - TEST-INFO | Confirming we saw 66 DOCSHELL created and 66 destroyed log strings.
[task 2022-07-04T23:46:10.258Z] 23:46:10     INFO - TEST-INFO | Confirming we saw 176 DOMWINDOW created and 176 destroyed log strings.
[task 2022-07-04T23:46:10.259Z] 23:46:10    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | leaked 2 window(s) until shutdown [url = about:devtools-toolbox]
[task 2022-07-04T23:46:10.259Z] 23:46:10    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | leaked 1 window(s) until shutdown [url = about:blank]
[task 2022-07-04T23:46:10.260Z] 23:46:10    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | leaked 1 window(s) until shutdown [url = chrome://devtools/content/inspector/index.xhtml]
[task 2022-07-04T23:46:10.262Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | windows(s) leaked: [pid = 4468] [serial = 82], [pid = 4468] [serial = 81], [pid = 4468] [serial = 80], [pid = 4468] [serial = 78]
[task 2022-07-04T23:46:10.263Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.263Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.264Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.264Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.264Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.265Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.265Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.266Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.266Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.267Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.267Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.267Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.268Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.268Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.269Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.269Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.269Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.270Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.270Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden window(s)
[task 2022-07-04T23:46:10.271Z] 23:46:10     INFO - TEST-INFO | devtools/client/inspector/compatibility/test/browser/browser_compatibility_event_document-reload.js | This test created 1 hidden docshell(s)
[task 2022-07-04T23:46:10.271Z] 23:46:10     INFO - runtests.py | Application ran for: 0:00:41.513442
[task 2022-07-04T23:46:10.272Z] 23:46:10     INFO - zombiecheck | Reading PID log: C:\Users\task_165697399732173\AppData\Local\Temp\tmpv1k0rsafpidlog
[task 2022-07-04T23:46:10.272Z] 23:46:10     INFO - ==> process 4468 launched child process 8724 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.0.698565834\1730915048" -parentBuildID 20220704225233 -prefsHandle 2164 -prefMapHandle 2156 -prefsLen 20257 -prefMapSize 241294 -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 2244 1133a9f8 gpu)
[task 2022-07-04T23:46:10.273Z] 23:46:10     INFO - ==> process 4468 launched child process 4480 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.1.2125706986\236667079" -childID 1 -isForBrowser -prefsHandle 2764 -prefMapHandle 2768 -prefsLen 21483 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 2800 1afef178 tab)
[task 2022-07-04T23:46:10.274Z] 23:46:10     INFO - ==> process 4468 launched child process 7076 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.2.959305015\472167033" -childID 2 -isForBrowser -prefsHandle 3236 -prefMapHandle 3232 -prefsLen 21590 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 3224 1c36ec78 tab)
[task 2022-07-04T23:46:10.275Z] 23:46:10     INFO - ==> process 4468 launched child process 7112 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.3.1244982193\2072816959" -childID 3 -isForBrowser -prefsHandle 3448 -prefMapHandle 3444 -prefsLen 21668 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 3232 1c9f97f8 tab)
[task 2022-07-04T23:46:10.276Z] 23:46:10     INFO - ==> process 4468 launched child process 3556 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.4.1951967918\1489748943" -childID 4 -isForBrowser -prefsHandle 2868 -prefMapHandle 3176 -prefsLen 22107 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 2876 58b2df8 tab)
[task 2022-07-04T23:46:10.276Z] 23:46:10     INFO - ==> process 4468 launched child process 8216 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.5.1563647304\817020690" -childID 5 -isForBrowser -prefsHandle 3972 -prefMapHandle 3964 -prefsLen 22107 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 4016 1afef178 tab)
[task 2022-07-04T23:46:10.277Z] 23:46:10     INFO - ==> process 4468 launched child process 3156 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.6.154260069\1609809972" -childID 6 -isForBrowser -prefsHandle 2868 -prefMapHandle 3980 -prefsLen 22107 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 3800 1afefbf8 tab)
[task 2022-07-04T23:46:10.278Z] 23:46:10     INFO - ==> process 4468 launched child process 6584 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.7.28464529\1049950472" -childID 7 -isForBrowser -prefsHandle 4700 -prefMapHandle 4684 -prefsLen 30128 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 4668 1cc30ef8 tab)
[task 2022-07-04T23:46:10.279Z] 23:46:10     INFO - ==> process 4468 launched child process 7580 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.8.1331071064\1207004566" -childID 8 -isForBrowser -prefsHandle 5004 -prefMapHandle 2780 -prefsLen 31434 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 2836 1c9f94f8 tab)
[task 2022-07-04T23:46:10.280Z] 23:46:10     INFO - ==> process 4468 launched child process 7224 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.9.949429004\1244969101" -childID 9 -isForBrowser -prefsHandle 3416 -prefMapHandle 4360 -prefsLen 31434 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 3180 1afefef8 tab)
[task 2022-07-04T23:46:10.281Z] 23:46:10     INFO - ==> process 4468 launched child process 5732 ("Z:\task_165697399732173\build\application\firefox\firefox.exe" -contentproc --channel="4468.10.232657027\742568710" -childID 10 -isForBrowser -prefsHandle 5196 -prefMapHandle 5200 -prefsLen 31434 -prefMapSize 241294 -jsInitHandle 1424 -jsInitLen 297212 -a11yResourceId 64 -parentBuildID 20220704225233 -win32kLockedDown -appDir "Z:\task_165697399732173\build\application\firefox\browser" - 4468 "\\.\pipe\gecko-crash-server-pipe.4468" 5172 1aff04f8 tab)
[task 2022-07-04T23:46:10.281Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 4480
[task 2022-07-04T23:46:10.281Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 3556
[task 2022-07-04T23:46:10.282Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 7076
[task 2022-07-04T23:46:10.282Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 6584
[task 2022-07-04T23:46:10.282Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 5732
[task 2022-07-04T23:46:10.283Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 7112
[task 2022-07-04T23:46:10.283Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 8724
[task 2022-07-04T23:46:10.283Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 3156
[task 2022-07-04T23:46:10.284Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 8216
[task 2022-07-04T23:46:10.284Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 7224
[task 2022-07-04T23:46:10.284Z] 23:46:10     INFO - zombiecheck | Checking for orphan process with PID: 7580
[task 2022-07-04T23:46:10.285Z] 23:46:10     INFO - Stopping web server
[task 2022-07-04T23:46:10.288Z] 23:46:10     INFO - Server shut down.
[task 2022-07-04T23:46:10.319Z] 23:46:10     INFO - Web server killed.
[task 2022-07-04T23:46:10.319Z] 23:46:10     INFO - Stopping web socket server
[task 2022-07-04T23:46:10.347Z] 23:46:10     INFO - Stopping ssltunnel
[task 2022-07-04T23:46:10.376Z] 23:46:10     INFO - leakcheck | Processing log file C:\Users\task_165697399732173\AppData\Local\Temp\tmpvf8gf7xi.mozrunner\runtests_leaks.log
[task 2022-07-04T23:46:10.378Z] 23:46:10     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2022-07-04T23:46:10.378Z] 23:46:10     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 1000 bytes
[task 2022-07-04T23:46:10.379Z] 23:46:10     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2022-07-04T23:46:10.379Z] 23:46:10     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2022-07-04T23:46:10.379Z] 23:46:10     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2022-07-04T23:46:10.380Z] 23:46:10     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2022-07-04T23:46:10.380Z] 23:46:10     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2022-07-04T23:46:10.380Z] 23:46:10     INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2022-07-04T23:46:10.381Z] 23:46:10     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2022-07-04T23:46:10.381Z] 23:46:10     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2022-07-04T23:46:10.381Z] 23:46:10     INFO - leakcheck | Processing leak log file C:\Users\task_165697399732173\AppData\Local\Temp\tmpvf8gf7xi.mozrunner\runtests_leaks.log
[task 2022-07-04T23:46:10.383Z] 23:46:10     INFO - TEST-PASS | leakcheck | default no leaks detected!
Regressed by: 1778009

:jdescottes, since you are the author of the regressor, bug 1778009, could you take a look?
For more information, please visit auto_nag documentation.

Flags: needinfo?(jdescottes)

Given that it's test-verify and that my push modified the test, it's likely that it's not a regression but simply that the test did not run in TV before. Will check

Running the test with --repeat 2 on an older central (https://hg.mozilla.org/mozilla-central/rev/f7c192231f50) I had the same error, so it's not a regression. See try at https://treeherder.mozilla.org/jobs?repo=try&revision=9dd4f890bca93e0c800a62fcef6653ce2e4f6378

However the failure is intermittent, so it's going to be hard to investigate efficiently. This could be related to the fact that this test performs a reload, but it's not clear what exactly creates a leak.

Flags: needinfo?(jdescottes)
No longer regressed by: 1778009
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.