Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=313855068&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CSqXCQ5fTsO2krLRcHgXPw/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/CSqXCQ5fTsO2krLRcHgXPw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2020-08-24T17:15:34.739Z] 17:15:34 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_submit_telemetry.js | Check the doorhanger is already open -
[task 2020-08-24T17:15:34.739Z] 17:15:34 INFO - setInputValue: on target: password-notification-username, value: doorhangerTypedUn
[task 2020-08-24T17:15:34.739Z] 17:15:34 INFO - setInputValue: current value: 'savedUn', setting new value 'doorhangerTypedUn'
[task 2020-08-24T17:15:34.739Z] 17:15:34 INFO - Opening doorhanger suggestion popup
[task 2020-08-24T17:15:34.739Z] 17:15:34 INFO - Buffered messages finished
[task 2020-08-24T17:15:34.739Z] 17:15:34 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_doorhanger_submit_telemetry.js | Test timed out -
[task 2020-08-24T17:15:34.739Z] 17:15:34 INFO - GECKO(5100) | MEMORY STAT | vsize 833MB | vsizeMaxContiguous 652MB | residentFast 220MB | heapAllocated 69MB
[task 2020-08-24T17:15:34.740Z] 17:15:34 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_doorhanger_submit_telemetry.js | took 90094ms
[task 2020-08-24T17:15:34.740Z] 17:15:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-24T17:15:34.740Z] 17:15:34 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_doorhanger_submit_telemetry.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/components/passwordmgr/test/browser/form_basic.html?username=savedUn&password=pagePw -
[task 2020-08-24T17:15:34.779Z] 17:15:34 INFO - GECKO(5100) | [Child 3160: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 01197800 == 1 [pid = 3160] [id = {38a0b525-fbc0-4eff-b81f-242cc5a758d1}]
[task 2020-08-24T17:15:34.779Z] 17:15:34 INFO - GECKO(5100) | [Child 3160: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0110B8A0) [pid = 3160] [serial = 9] [outer = 00000000]
[task 2020-08-24T17:15:34.779Z] 17:15:34 INFO - GECKO(5100) | [Child 3160: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (01199400) [pid = 3160] [serial = 10] [outer = 0110B8A0]
[task 2020-08-24T17:15:34.799Z] 17:15:34 INFO - checking window state
[task 2020-08-24T17:15:34.838Z] 17:15:34 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_doorhanger_submit_telemetry.js
[task 2020-08-24T17:15:34.885Z] 17:15:34 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 018B7000 == 2 [pid = 5896] [id = {df115b96-7801-4a3f-ac94-98263e2089ae}]
[task 2020-08-24T17:15:34.885Z] 17:15:34 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0180BDF0) [pid = 5896] [serial = 35] [outer = 00000000]
[task 2020-08-24T17:15:34.885Z] 17:15:34 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (075B2400) [pid = 5896] [serial = 36] [outer = 0180BDF0]
[task 2020-08-24T17:15:34.992Z] 17:15:34 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (075BC800) [pid = 5896] [serial = 37] [outer = 0180BDF0]
[task 2020-08-24T17:15:35.315Z] 17:15:35 INFO - GECKO(5100) | [Child 3160, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:35.350Z] 17:15:35 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:35.505Z] 17:15:35 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0A8B2000) [pid = 5896] [serial = 38] [outer = 0180BDF0]
[task 2020-08-24T17:15:35.820Z] 17:15:35 INFO - GECKO(5100) | Waiting for 1 events, got 1
[task 2020-08-24T17:15:35.839Z] 17:15:35 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 075B3000 == 3 [pid = 5896] [id = {748b43f3-0ff9-4caa-a900-19e7cbd930d5}]
[task 2020-08-24T17:15:35.839Z] 17:15:35 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0180BF00) [pid = 5896] [serial = 39] [outer = 00000000]
[task 2020-08-24T17:15:35.839Z] 17:15:35 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (075BD400) [pid = 5896] [serial = 40] [outer = 0180BF00]
[task 2020-08-24T17:15:35.938Z] 17:15:35 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0A8B7400) [pid = 5896] [serial = 41] [outer = 0180BF00]
[task 2020-08-24T17:15:36.185Z] 17:15:36 INFO - GECKO(5100) | [Child 3160, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:36.224Z] 17:15:36 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:36.319Z] 17:15:36 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0A8B9000) [pid = 5896] [serial = 42] [outer = 0180BF00]
[task 2020-08-24T17:15:36.586Z] 17:15:36 INFO - GECKO(5100) | [Child 4196: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 00BBD800 == 2 [pid = 4196] [id = {d36611a7-8f75-4b4c-ae52-f4028fbb7923}]
[task 2020-08-24T17:15:36.586Z] 17:15:36 INFO - GECKO(5100) | [Child 4196: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (00B0B8A0) [pid = 4196] [serial = 30] [outer = 00000000]
[task 2020-08-24T17:15:36.586Z] 17:15:36 INFO - GECKO(5100) | [Child 4196: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (06CBD000) [pid = 4196] [serial = 31] [outer = 00B0B8A0]
[task 2020-08-24T17:15:36.706Z] 17:15:36 INFO - GECKO(5100) | [Child 4196: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (06CC8400) [pid = 4196] [serial = 32] [outer = 00B0B8A0]
[task 2020-08-24T17:15:36.781Z] 17:15:36 INFO - GECKO(5100) | [Child 4196, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001 (NS_ERROR_NOT_INITIALIZED): file /builds/worker/checkouts/gecko/layout/forms/nsTextControlFrame.cpp, line 313
[task 2020-08-24T17:15:36.936Z] 17:15:36 INFO - GECKO(5100) | [Child 4196, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:36.936Z] 17:15:36 INFO - GECKO(5100) | [Child 3160, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:37.055Z] 17:15:37 INFO - GECKO(5100) | [Child 4196: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (09FDC800) [pid = 4196] [serial = 33] [outer = 00B0B8A0]
[task 2020-08-24T17:15:37.130Z] 17:15:37 INFO - GECKO(5100) | [Child 4196, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001 (NS_ERROR_NOT_INITIALIZED): file /builds/worker/checkouts/gecko/layout/forms/nsTextControlFrame.cpp, line 313
[task 2020-08-24T17:15:37.465Z] 17:15:37 INFO - GECKO(5100) | Waiting for 2 events, got 2
[task 2020-08-24T17:15:37.492Z] 17:15:37 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 075B4000 == 4 [pid = 5896] [id = {d117c680-16df-42ae-a0d8-331a227ebce0}]
[task 2020-08-24T17:15:37.492Z] 17:15:37 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0180C010) [pid = 5896] [serial = 43] [outer = 00000000]
[task 2020-08-24T17:15:37.492Z] 17:15:37 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0A8AE800) [pid = 5896] [serial = 44] [outer = 0180C010]
[task 2020-08-24T17:15:37.567Z] 17:15:37 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (0A8BC000) [pid = 5896] [serial = 45] [outer = 0180C010]
[task 2020-08-24T17:15:37.642Z] 17:15:37 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001 (NS_ERROR_NOT_INITIALIZED): file /builds/worker/checkouts/gecko/layout/forms/nsTextControlFrame.cpp, line 313
[task 2020-08-24T17:15:37.842Z] 17:15:37 INFO - GECKO(5100) | [Child 3160, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:37.902Z] 17:15:37 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:38.002Z] 17:15:38 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (0B8BF800) [pid = 5896] [serial = 46] [outer = 0180C010]
[task 2020-08-24T17:15:38.082Z] 17:15:38 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001 (NS_ERROR_NOT_INITIALIZED): file /builds/worker/checkouts/gecko/layout/forms/nsTextControlFrame.cpp, line 313
[task 2020-08-24T17:15:38.443Z] 17:15:38 INFO - GECKO(5100) | Waiting for 1 events, got 1
[task 2020-08-24T17:15:38.466Z] 17:15:38 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0A8B6C00 == 5 [pid = 5896] [id = {82f4b107-98d8-4e3e-8784-eb3e661764da}]
[task 2020-08-24T17:15:38.466Z] 17:15:38 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (0180C120) [pid = 5896] [serial = 47] [outer = 00000000]
[task 2020-08-24T17:15:38.466Z] 17:15:38 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (0A8BC800) [pid = 5896] [serial = 48] [outer = 0180C120]
[task 2020-08-24T17:15:38.546Z] 17:15:38 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (0B8C3800) [pid = 5896] [serial = 49] [outer = 0180C120]
[task 2020-08-24T17:15:38.601Z] 17:15:38 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001 (NS_ERROR_NOT_INITIALIZED): file /builds/worker/checkouts/gecko/layout/forms/nsTextControlFrame.cpp, line 313
[task 2020-08-24T17:15:38.816Z] 17:15:38 INFO - GECKO(5100) | [Child 3160, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:38.875Z] 17:15:38 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:38.970Z] 17:15:38 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (0B8C7800) [pid = 5896] [serial = 50] [outer = 0180C120]
[task 2020-08-24T17:15:39.049Z] 17:15:39 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001 (NS_ERROR_NOT_INITIALIZED): file /builds/worker/checkouts/gecko/layout/forms/nsTextControlFrame.cpp, line 313
[task 2020-08-24T17:15:39.424Z] 17:15:39 INFO - GECKO(5100) | Waiting for 1 events, got 1
[task 2020-08-24T17:15:39.444Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 075B9400 == 6 [pid = 5896] [id = {6cb679a0-8b00-4a1a-98a7-7be2c6c8530e}]
[task 2020-08-24T17:15:39.444Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (0180C230) [pid = 5896] [serial = 51] [outer = 00000000]
[task 2020-08-24T17:15:39.444Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (0A8B9400) [pid = 5896] [serial = 52] [outer = 0180C230]
[task 2020-08-24T17:15:39.479Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 075B4000 == 5 [pid = 5896] [id = {d117c680-16df-42ae-a0d8-331a227ebce0}] [url = https://example.com/browser/toolkit/components/passwordmgr/test/browser/form_basic.html?username=savedUn&password=pagePw]
[task 2020-08-24T17:15:39.479Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 018B6C00 == 4 [pid = 5896] [id = {35ff362b-7da7-4bce-a563-fd1ea932f4f6}] [url = about:blank]
[task 2020-08-24T17:15:39.479Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 018B7000 == 3 [pid = 5896] [id = {df115b96-7801-4a3f-ac94-98263e2089ae}] [url = https://example.com/browser/toolkit/components/passwordmgr/test/browser/form_basic.html?username=pageUn&password=pagePw]
[task 2020-08-24T17:15:39.479Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 075B3000 == 2 [pid = 5896] [id = {748b43f3-0ff9-4caa-a900-19e7cbd930d5}] [url = https://example.com/browser/toolkit/components/passwordmgr/test/browser/form_basic.html?username=&password=pagePw]
[task 2020-08-24T17:15:39.541Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (075BFC00) [pid = 5896] [serial = 53] [outer = 0180C230]
[task 2020-08-24T17:15:39.601Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (0180BF00) [pid = 5896] [serial = 39] [outer = 00000000] [url = https://example.com/browser/toolkit/components/passwordmgr/test/browser/form_basic.html?username=&password=pagePw]
[task 2020-08-24T17:15:39.601Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (0180BDF0) [pid = 5896] [serial = 35] [outer = 00000000] [url = https://example.com/browser/toolkit/components/passwordmgr/test/browser/form_basic.html?username=pageUn&password=pagePw]
[task 2020-08-24T17:15:39.601Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (0180B8A0) [pid = 5896] [serial = 33] [outer = 00000000] [url = about:blank]
[task 2020-08-24T17:15:39.616Z] 17:15:39 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001 (NS_ERROR_NOT_INITIALIZED): file /builds/worker/checkouts/gecko/layout/forms/nsTextControlFrame.cpp, line 313
[task 2020-08-24T17:15:39.791Z] 17:15:39 INFO - GECKO(5100) | [Child 3160, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:39.866Z] 17:15:39 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-08-24T17:15:39.969Z] 17:15:39 INFO - GECKO(5100) | [Child 5896: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (0A8BC400) [pid = 5896] [serial = 54] [outer = 0180C230]
[task 2020-08-24T17:15:40.049Z] 17:15:40 INFO - GECKO(5100) | [Child 5896, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001 (NS_ERROR_NOT_INITIALIZED): file /builds/worker/checkouts/gecko/layout/forms/nsTextControlFrame.cpp, line 313
[task 2020-08-24T17:15:40.204Z] 17:15:40 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-24T17:15:40.204Z] 17:15:40 INFO - <snipped 81 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2020-08-24T17:15:40.205Z] 17:15:40 INFO - Buffered messages logged at 17:15:37
Description
•