1506288 - Intermittent browser/base/content/test/tabcrashed/browser_clearEmail.js | Test timed out -

Reporter

Description

•

6 years ago

treeherder

Filed by: aciure [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=210899138&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/ZhsTJdDxSludMSVG7zWGcw/runs/0/artifacts/public/logs/live_backing.log 20:33:35 INFO - TEST-INFO | screenshot: exit 0 20:33:35 INFO - Buffered messages logged at 20:32:05 20:33:35 INFO - Entering test bound setup 20:33:35 INFO - Leaving test bound setup 20:33:35 INFO - Entering test bound test_clear_email 20:33:35 INFO - Buffered messages logged at 20:32:06 20:33:35 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page." line: 0}] 20:33:35 INFO - Console message: [JavaScript Error: "remote browser crashed while on data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page. 20:33:35 INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 8}] 20:33:35 INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:8:5 20:33:35 INFO - EventListener.handleEvent*e10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:3 20:33:35 INFO - testInit@chrome://mochikit/content/browser-test.js:102:5 20:33:35 INFO - setTimeout handler*@chrome://mochikit/content/browser-test.js:22:1 20:33:35 INFO - loadChromeScripts@jar:file:///C:/Users/task_1541793104/AppData/Local/Temp/tmpn3ca9y.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:15:3 20:33:35 INFO - loadMochitest@jar:file:///C:/Users/task_1541793104/AppData/Local/Temp/tmpn3ca9y.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:107:3 20:33:35 INFO - @Z:\task_1541793104\build\tests\mochitest\runtests.py:1734:1 20:33:35 INFO - @Z:\task_1541793104\build\tests\mochitest\runtests.py:1716:2 20:33:35 INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:128:13 20:33:35 INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:97:17 20:33:35 INFO - GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:1018:19 20:33:35 INFO - async*GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:888:24 20:33:35 INFO - Async*despatch@chrome://marionette/content/server.js:291:20 20:33:35 INFO - async*execute@chrome://marionette/content/server.js:264:11 20:33:35 INFO - async*onPacket/<@chrome://marionette/content/server.js:237:15 20:33:35 INFO - async*onPacket@chrome://marionette/content/server.js:236:8 20:33:35 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:490:9 20:33:35 INFO - 20:33:35 INFO - Buffered messages finished 20:33:35 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabcrashed/browser_clearEmail.js | Test timed out - 20:33:35 INFO - this test left a pending crash report; could not delete Z:\task_1541793104\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\61f86d22-c433-435e-bb79-81deaece9e97.dmp 20:33:35 INFO - this test left a pending crash report; deleted Z:\task_1541793104\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\61f86d22-c433-435e-bb79-81deaece9e97.extra 20:33:35 INFO - GECKO(5308) | MEMORY STAT | vsize 762MB | vsizeMaxContiguous 496MB | residentFast 220MB | heapAllocated 72MB 20:33:35 INFO - TEST-OK | browser/base/content/test/tabcrashed/browser_clearEmail.js | took 90030ms 20:33:35 INFO - Not taking screenshot here: see the one that was previously logged 20:33:35 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabcrashed/browser_clearEmail.js | Found a tab after previous test timed out: data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page. - 20:33:35 INFO - GECKO(5308) | ++DOCSHELL 0106A000 == 1 [pid = 3552] [id = {0aa02397-1432-4a0b-8f2a-3fee265462c9}] 20:33:35 INFO - GECKO(5308) | [Child 3552, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/widget/windows/nsLookAndFeel.cpp, line 901 20:33:35 INFO - GECKO(5308) | ++DOMWINDOW == 1 (07E0D410) [pid = 3552] [serial = 1] [outer = 00000000]

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 5

•

6 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED

Closed: 6 years ago

Resolution: --- → INCOMPLETE

Dorel Luca [:dluca]

Comment 6

•

6 years ago

Recent log:

https://treeherder.mozilla.org/logviewer.html#?job_id=228471321&repo=autoland

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 10

•

6 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 6 years ago → 6 years ago

Resolution: --- → INCOMPLETE

Alexandru Michis [:malexandru]

Comment 11

•

6 years ago

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

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 14

•

5 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 6 years ago → 5 years ago

Resolution: --- → INCOMPLETE

Oana Pop-Rus

Comment 15

•

5 years ago

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

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 19

•

5 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 5 years ago → 5 years ago

Resolution: --- → INCOMPLETE

Cristian Brindusan [:cbrindusan]

Comment 20

•

5 years ago

New occurrence:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=266931244&repo=autoland&lineNumber=11521

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Andrei Ciure[:aciure]

Comment 38

•

5 years ago

•

Edited

There are 29 failures associated to this bug in the last 7 days. These are occurring on:

macosx1014-64-shippable, opt
windows10-64-qr, debug
windows7-32, opt and debug
windows7-32-shippable, opt

log: https://treeherder.mozilla.org/logviewer.html#?job_id=297992195&repo=autoland
[task 2020-04-16T21:27:50.731Z] 21:27:50 INFO - Console message: [JavaScript Error: "NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]" {file: "resource://testing-common/BrowserTestUtils.jsm" line: 1704}]
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - Buffered messages finished
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabcrashed/browser_clearEmail.js | Test timed out -
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - this test left a pending crash report; deleted Z:\task_1587070205\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\74fa6306-3071-471f-a8db-1dae1b0d6202.dmp
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - GECKO(4324) | MEMORY STAT | vsize 2104289MB | vsizeMaxContiguous 65917158MB | residentFast 315MB | heapAllocated 87MB
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - TEST-OK | browser/base/content/test/tabcrashed/browser_clearEmail.js | took 90076ms
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabcrashed/browser_clearEmail.js | Found a tab after previous test timed out: data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page. -
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - GECKO(4324) | [Parent 2080, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorService.cpp, line 188
[task 2020-04-16T21:27:50.732Z] 21:27:50 INFO - GECKO(4324) | [Parent 2080, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorService.cpp, line 188
[task 2020-04-16T21:27:50.742Z] 21:27:50 INFO - GECKO(4324) | [Child 5900: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000002460C699400 == 1 [pid = 5900] [id = {c742f398-321e-49b9-a12c-8d1e70016129}]
[task 2020-04-16T21:27:50.742Z] 21:27:50 INFO - GECKO(4324) | [Child 5900: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (000002460C6D3900) [pid = 5900] [serial = 1] [outer = 0000000000000000]
[task 2020-04-16T21:27:50.742Z] 21:27:50 INFO - GECKO(4324) | [Child 5900: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (000002460CB6D400) [pid = 5900] [serial = 2] [outer = 000002460C6D3900]
[task 2020-04-16T21:27:50.768Z] 21:27:50 INFO - checking window state

::dao can you take a look?

Flags: needinfo?(dao+bmo)

Dão Gottwald [:dao]

Updated

•

5 years ago

Flags: needinfo?(dao+bmo) → needinfo?(mconley)

Dão Gottwald [:dao]

Updated

•

5 years ago

Comment 40

•

5 years ago

There are 60 total failures in the last 7 days on windows10-64-qr and windows10-64 debug

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

[task 2020-04-23T00:53:03.294Z] 00:53:03 [task 2020-04-23T00:53:03.294Z] 00:53:03 [task 2020-04-23T00:53:03.294Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.295Z] 00:53:03 [task 2020-04-23T00:53:03.296Z] 00:53:03 [task 2020-04-23T00:53:03.296Z] 00:53:03 [task 2020-04-23T00:53:03.296Z] 00:53:03 [task 2020-04-23T00:53:03.296Z] 00:53:03 [task 2020-04-23T00:53:03.296Z] 00:53:03 [task 2020-04-23T00:53:03.296Z] 00:53:03 [task 2020-04-23T00:53:03.296Z] 00:53:03 [task 2020-04-23T00:53:03.296Z] 00:53:03 [task 2020-04-23T00:53:03.297Z] 00:53:03 [task 2020-04-23T00:53:03.297Z] 00:53:03 [task 2020-04-23T00:53:03.297Z] 00:53:03 [task 2020-04-23T00:53:03.297Z] 00:53:03 [task 2020-04-23T00:53:03.297Z] 00:53:03 [task 2020-04-23T00:53:03.297Z] 00:53:03 [task 2020-04-23T00:53:03.297Z] 00:53:03 INFO - TEST-START | browser/base/content/test/tabcrashed/browser_clearEmail.js
INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: NS_ENSURE_TRUE(GetWrapper()) failed: file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActor.cpp, line 90
INFO - GECKO(7436) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1587600771\appdata\local\temp\tmpmoxlxl.mozrunner\runtests_leaks_tab_pid2824.log
INFO - GECKO(7436) | [2824, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 221
INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 92
INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
INFO - GECKO(7436) | [Child 2824: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0000027716BAC000 == 1 [pid = 2824] [id = {7072a1e8-794a-41f3-a53a-782cbce75c1b}]
INFO - GECKO(7436) | [Child 2824: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0000027717062090) [pid = 2824] [serial = 1] [outer = 0000000000000000]
INFO - GECKO(7436) | [Child 2824: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (000002771706F800) [pid = 2824] [serial = 2] [outer = 0000027717062090]
INFO - GECKO(7436) | [Child 2824: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0000027716BAB800) [pid = 2824] [serial = 3] [outer = 0000027717062090]
INFO - GECKO(7436) | Et tu, Brute?
INFO - GECKO(7436) | XPCOM_MEM_BLOAT_LOG: c:\users\task_1587600771\appdata\local\temp\tmpmoxlxl.mozrunner\runtests_leaks.log
INFO - GECKO(7436) | Writing to log: c:\users\task_1587600771\appdata\local\temp\tmpmoxlxl.mozrunner\runtests_leaks_tab_pid2824.log
INFO - GECKO(7436) | [Child 6788: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (00000296F11D0000) [pid = 6788] [serial = 3] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html]
INFO - GECKO(7436) | [Child 6388: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0000019A1A99D800 == 0 [pid = 6388] [id = {20a4bfda-534d-4ca3-8a44-0b0e74a76894}] [url = data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page.]
INFO - GECKO(7436) | [Child 6788: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 00000296F11CD400 == 0 [pid = 6788] [id = {e044b9eb-6724-451e-b1ae-76a121cfad57}] [url = about:blank]
INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp, line 475
INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp, line 475
INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp, line 475
INFO - GECKO(7436) | JavaScript error: resource://testing-common/BrowserTestUtils.jsm, line 1702: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
INFO - GECKO(7436) | [Parent 11064: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000002268870EC00 == 9 [pid = 11064] [id = {e9e39cf6-04a5-44b8-817c-0f38a6b38bd0}]
INFO - GECKO(7436) | [Parent 11064: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (0000022689584B70) [pid = 11064] [serial = 23] [outer = 0000000000000000]
INFO - GECKO(7436) | [Parent 11064: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (0000022688711C00) [pid = 11064] [serial = 24] [outer = 0000022689584B70]
INFO - GECKO(7436) | [Parent 11064, Gecko_IOThread] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc, line 166
INFO - GECKO(7436) | [Parent 11064: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (000002268889AC00) [pid = 11064] [serial = 25] [outer = 0000022689584B70]
INFO - GECKO(7436) | about:tabcrashed loaded and ready

[task 2020-04-23T00:53:05.957Z] 00:53:05 INFO - GECKO(7436) | [Child 3840: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0000024D09467090) [pid = 3840] [serial = 1] [outer = 0000000000000000] [url = data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page.]
[task 2020-04-23T00:53:06.586Z] 00:53:06 INFO - GECKO(7436) | [Child 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (000001D7FB47E000) [pid = 4448] [serial = 3] [outer = 0000000000000000] [url = data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page.]
[task 2020-04-23T00:53:10.006Z] 00:53:10 INFO - GECKO(7436) | [Child 3840: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0000024D0947E000) [pid = 3840] [serial = 3] [outer = 0000000000000000] [url = data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page.]
[task 2020-04-23T00:54:24.169Z] 00:54:24 INFO - TEST-INFO | started process screenshot
[task 2020-04-23T00:54:24.240Z] 00:54:24 INFO - TEST-INFO | screenshot: exit 0
[task 2020-04-23T00:54:24.240Z] 00:54:24 INFO - Buffered messages logged at 00:52:54
[task 2020-04-23T00:54:24.240Z] 00:54:24 INFO - Entering test bound setup
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - Leaving test bound setup
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - Entering test bound test_clear_email
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page." line: 0}]
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - Console message: [JavaScript Error: "remote browser crashed while on data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page.
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 10}]
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:10:10
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - EventListener.handleEvente10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:10
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - testInit@chrome://mochikit/content/browser-test.js:125:5
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - setTimeout handler@chrome://mochikit/content/browser-test.js:35:11
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - loadChromeScripts@jar:file:///C:/Users/task_1587600771/AppData/Local/Temp/tmpmoxlxl.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:21:25
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - loadMochitest@jar:file:///C:/Users/task_1587600771/AppData/Local/Temp/tmpmoxlxl.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:143:20
[task 2020-04-23T00:54:24.241Z] 00:54:24 INFO - @Z:\task_1587600771\build\tests\mochitest\runtests.py:1812:5
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - @Z:\task_1587600771\build\tests\mochitest\runtests.py:1813:8
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:138:10
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:114:17
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:1099:28
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - async*GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:974:30
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO -
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - Console message: [JavaScript Error: "NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]" {file: "resource://testing-common/BrowserTestUtils.jsm" line: 1702}]
[task 2020-04-23T00:54:24.242Z] 00:54:24 INFO - Buffered messages finished
[task 2020-04-23T00:54:24.243Z] 00:54:24 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabcrashed/browser_clearEmail.js | Test timed out -
[task 2020-04-23T00:54:24.243Z] 00:54:24 INFO - this test left a pending crash report; deleted Z:\task_1587600771\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\eb8c794b-b433-4e93-9f41-91b41c030bbf.dmp
[task 2020-04-23T00:54:24.243Z] 00:54:24 INFO - GECKO(7436) | MEMORY STAT | vsize 2104289MB | vsizeMaxContiguous 71454032MB | residentFast 315MB | heapAllocated 86MB
[task 2020-04-23T00:54:24.243Z] 00:54:24 INFO - TEST-OK | browser/base/content/test/tabcrashed/browser_clearEmail.js | took 90100ms
[task 2020-04-23T00:54:24.243Z] 00:54:24 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-04-23T00:54:24.243Z] 00:54:24 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabcrashed/browser_clearEmail.js | Found a tab after previous test timed out: data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page. -
[task 2020-04-23T00:54:24.261Z] 00:54:24 INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorService.cpp, line 188
[task 2020-04-23T00:54:24.261Z] 00:54:24 INFO - GECKO(7436) | [Parent 11064, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorService.cpp, line 188
[task 2020-04-23T00:54:24.280Z] 00:54:24 INFO - GECKO(7436) | [Child 10416: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000001A91CE9A800 == 1 [pid = 10416] [id = {9b7e28b3-eb06-4a6e-a2b4-6132c8cccca5}]
[task 2020-04-23T00:54:24.280Z] 00:54:24 INFO - GECKO(7436) | [Child 10416: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (000001A91D365090) [pid = 10416] [serial = 1] [outer = 0000000000000000]
[task 2020-04-23T00:54:24.280Z] 00:54:24 INFO - GECKO(7436) | [Child 10416: Main Thread]:

Mike are you working on this?

Flags: needinfo?(mconley)

Andreea Pavel [:apavel]

Updated

•

5 years ago

Flags: needinfo?(mconley)

Andreea Pavel [:apavel]

Updated

•

5 years ago

Whiteboard: [stockwell needswork:owner]

Comment hidden (Intermittent Failures Robot)

Mike Conley (:mconley) (:⚙️)

Comment 42

•

5 years ago

[task 2020-04-16T21:27:50.731Z] 21:27:50 INFO - Console message: [JavaScript Error: "NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]" {file: "resource://testing-common/BrowserTestUtils.jsm" line: 1704}]

This seems to be the important part, and is probably referring to this line: https://searchfox.org/mozilla-central/rev/41c3ea3ee8eab9ce7b82932257cb80b703cbba67/testing/mochitest/BrowserTestUtils/BrowserTestUtils.jsm#1702

It looks like there's a case where the dumpID might not be set?: https://searchfox.org/mozilla-central/rev/41c3ea3ee8eab9ce7b82932257cb80b703cbba67/dom/ipc/ContentParent.cpp#1716-1718

Hey gsvelto, under what circumstances might the dumpID not be available, do you know?

Flags: needinfo?(mconley) → needinfo?(gsvelto)

Gabriele Svelto [:gsvelto]

Comment 43

•

5 years ago

That happens if we fail to write out a minidump but I've fixed pretty much all the scenarios where this used to happen so in theory it shouldn't... But the timing of this spike roughly corresponds with me fixing bug 1434933 which has caused other intermittents to spike... so my guess is that this was also my fault. I'm developing a fix tonight and will test it against this bug too. Leaving the NI? for now.

Comment hidden (Intermittent Failures Robot)

Andreea Pavel [:apavel]

Comment 46

•

5 years ago

There are 83 total failures in the last 7 days, with spike starting from the 19th of April: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-04-18&endday=2020-04-25&tree=trunk&bug=1506288

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

[task 2020-04-25T06:08:43.604Z] 06:08:43 INFO - Entering test bound test_clear_email
[task 2020-04-25T06:08:43.605Z] 06:08:43 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page." line: 0}]
[task 2020-04-25T06:08:43.605Z] 06:08:43 INFO - Buffered messages logged at 06:07:14
[task 2020-04-25T06:08:43.605Z] 06:08:43 INFO - Console message: [JavaScript Error: "remote browser crashed while on data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page.
[task 2020-04-25T06:08:43.605Z] 06:08:43 INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 10}]
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:10:10
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - EventListener.handleEvente10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:10
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - testInit@chrome://mochikit/content/browser-test.js:125:5
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - setTimeout handler@chrome://mochikit/content/browser-test.js:35:11
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - loadChromeScripts@jar:file:///C:/Users/task_1587791376/AppData/Local/Temp/tmpsuyyfo.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:21:25
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - loadMochitest@jar:file:///C:/Users/task_1587791376/AppData/Local/Temp/tmpsuyyfo.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:143:20
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - @Z:\task_1587791376\build\tests\mochitest\runtests.py:1812:5
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - @Z:\task_1587791376\build\tests\mochitest\runtests.py:1813:8
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:138:10
[task 2020-04-25T06:08:43.606Z] 06:08:43 INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:114:17
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:1099:28
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - async*GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:974:30
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO -
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - Console message: [JavaScript Error: "NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]" {file: "resource://testing-common/BrowserTestUtils.jsm" line: 1702}]
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - Buffered messages finished
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabcrashed/browser_clearEmail.js | Test timed out -
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - this test left a pending crash report; deleted Z:\task_1587791376\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\75b0f19d-1ba8-4bd8-93e4-ec8e4ab0bd49.dmp
[task 2020-04-25T06:08:43.607Z] 06:08:43 INFO - GECKO(4684) | MEMORY STAT | vsize 890MB | vsizeMaxContiguous 482MB | residentFast 289MB | heapAllocated 100MB
[task 2020-04-25T06:08:43.608Z] 06:08:43 INFO - TEST-OK | browser/base/content/test/tabcrashed/browser_clearEmail.js | took 90046ms
[task 2020-04-25T06:08:43.608Z] 06:08:43 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-04-25T06:08:43.608Z] 06:08:43 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/tabcrashed/browser_clearEmail.js | Found a tab after previous test timed out: data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page. -
[task 2020-04-25T06:08:43.608Z] 06:08:43 INFO - GECKO(4684) | [Parent 5544, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorService.cpp, line 188
[task 2020-04-25T06:08:43.608Z] 06:08:43 INFO - GECKO(4684) | [Parent 5544, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorService.cpp, line 188
[task 2020-04-25T06:08:43.623Z] 06:08:43 INFO - GECKO(4684) | [Child 3352: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 00DA9800 == 1 [pid = 3352] [id = {9a9aedfd-4c07-4f96-ba01-e329122748b3}]
[task 2020-04-25T06:08:43.623Z] 06:08:43 INFO - GECKO(4684) | [Child 3352, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp, line 853
[task 2020-04-25T06:08:43.623Z] 06:08:43 INFO - GECKO(4684) | [Child 3352: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (00D68EF0) [pid = 3352] [serial = 1] [outer = 00000000]
[task 2020-04-25T06:08:43.623Z] 06:08:43 INFO - GECKO(4684) | [Child 3352: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (00DB1800) [pid = 3352] [serial = 2] [outer = 00D68EF0]
[task 2020-04-25T06:08:43.650Z] 06:08:43 INFO - checking window state
[task 2020-04-25T06:08:43.657Z] 06:08:43 INFO - GECKO(4684) | [Child 3352, Main Thread] WARNING: 'NS_FAILED(GetAccentColor(unused))', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp, line 481

Andreea Pavel [:apavel]

Updated

•

5 years ago

Regressed by: 1434933

Comment hidden (Intermittent Failures Robot)

Gabriele Svelto [:gsvelto]

Comment 48

•

5 years ago

This should be fixed by the patch I'll land to fix bug 1498706.

Flags: needinfo?(gsvelto)

Comment hidden (Intermittent Failures Robot)

Gabriele Svelto [:gsvelto]

Comment 50

•

5 years ago

Waiting for this week's volume but it looks like it's been "mostly" fixed by bug 1498706. There are macOS crashes which are certainly unrelated to that bug and I'll investigate them separately.

Comment hidden (Intermittent Failures Robot)

Marco Castelluccio [:marco]

Updated

•

3 years ago

Keywords: regression

Comment hidden (Intermittent Failures Robot)

BugBot [:suhaib / :marco/ :calixte]

Updated

•

3 years ago

Has Regression Range: --- → yes

BMO Automation

Updated

•

2 years ago

Severity: normal → S3

BugBot [:suhaib / :marco/ :calixte]

Comment 60

•

2 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Status: REOPENED → RESOLVED

Closed: 5 years ago → 2 years ago

Resolution: --- → INCOMPLETE