1620622 - Intermittent TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_helpers.js | application timed out after 370 seconds with no output

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-03-06T15:48:03.917Z] 15:48:03 INFO - GECKO(4372) | [Child 5272: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (067D8800) [pid = 5272] [serial = 33] [outer = 00B0CCD0]
[task 2020-03-06T15:48:03.944Z] 15:48:03 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_helpers.js
[task 2020-03-06T15:48:03.991Z] 15:48:03 INFO - GECKO(4372) | [Child 3800: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 011AEC00 == 2 [pid = 3800] [id = {76d345f7-7df6-4bec-9752-5961c9cdbeba}]
[task 2020-03-06T15:48:03.991Z] 15:48:03 INFO - GECKO(4372) | [Child 3800: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0110CCD0) [pid = 3800] [serial = 36] [outer = 00000000]
[task 2020-03-06T15:48:03.991Z] 15:48:03 INFO - GECKO(4372) | [Child 3800: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (074D1C00) [pid = 3800] [serial = 37] [outer = 0110CCD0]
[task 2020-03-06T15:48:04.066Z] 15:48:04 INFO - GECKO(4372) | [Child 3800: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0D159C00) [pid = 3800] [serial = 38] [outer = 0110CCD0]
[task 2020-03-06T15:48:04.181Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 10397400 == 8 [pid = 2808] [id = {1899ce2d-c7d7-4449-9c1c-527f865a3163}]
[task 2020-03-06T15:48:04.181Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (10146BD0) [pid = 2808] [serial = 109] [outer = 00000000]
[task 2020-03-06T15:48:04.184Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (1039C400) [pid = 2808] [serial = 110] [outer = 10146BD0]
[task 2020-03-06T15:48:04.207Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (113F5400) [pid = 2808] [serial = 111] [outer = 10146BD0]
[task 2020-03-06T15:48:04.282Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/dom/base/nsContentUtils.cpp, line 3786
[task 2020-03-06T15:48:04.437Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 103A2800 == 9 [pid = 2808] [id = {919aeaba-bdbb-439b-9dd3-da903cb67391}]
[task 2020-03-06T15:48:04.437Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (10146DF0) [pid = 2808] [serial = 112] [outer = 00000000]
[task 2020-03-06T15:48:04.437Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 22 (103A5C00) [pid = 2808] [serial = 113] [outer = 10146DF0]
[task 2020-03-06T15:48:04.812Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file z:/build/build/src/extensions/permissions/nsPermission.cpp, line 45
[task 2020-03-06T15:48:04.812Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808, Main Thread] WARNING: '!principal', file z:/build/build/src/netwerk/cookie/CookieJarSettings.cpp, line 182
[task 2020-03-06T15:48:04.812Z] 15:48:04 INFO - GECKO(4372) | [Parent 2808, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/toolkit/components/antitracking/StorageAccess.cpp, line 33
[task 2020-03-06T15:48:05.211Z] 15:48:05 INFO - GECKO(4372) | [Parent 2808, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file z:/build/build/src/extensions/permissions/nsPermission.cpp, line 45
[task 2020-03-06T15:48:05.211Z] 15:48:05 INFO - GECKO(4372) | [Parent 2808, Main Thread] WARNING: '!principal', file z:/build/build/src/netwerk/cookie/CookieJarSettings.cpp, line 182

[task 2020-03-06T15:48:15.257Z] 15:48:15 INFO - GECKO(4372) | [Child 5648: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0C6C4C00) [pid = 5648] [serial = 41] [outer = 00000000] [url = data:text/html;charset=utf-8,<head> <script> /* Create a prototype-less object so popup does not contain native * Object prototype properties. */ let sideEffect; window.foo = Object.create(null, Object.getOwnPropertyDescriptors({ get bar() { sideEffect = "bar"; return "hello"; } })); </script></head><body>Autocomplete popup - invoke getter usage test</body>]
[task 2020-03-06T15:52:38.105Z] 15:52:38 INFO - [5008, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/toolkit/components/places/Database.cpp, line 544
[task 2020-03-06T15:52:38.105Z] 15:52:38 INFO - [5008, Main Thread] WARNING: Unable to get a connection to vacuum database: file z:/build/build/src/storage/VacuumManager.cpp, line 128
[task 2020-03-06T15:52:38.160Z] 15:52:38 INFO - [5008, IPDL Background] WARNING: '!gBaseDirPath', file z:/build/build/src/dom/quota/ActorsParent.cpp, line 3582
[task 2020-03-06T15:52:38.160Z] 15:52:38 INFO - [5008, IPDL Background] WARNING: profile-do-change must precede QuotaManager::GetOrCreate(): file z:/build/build/src/dom/quota/ActorsParent.cpp, line 3583
[task 2020-03-06T15:52:38.160Z] 15:52:38 INFO - [5008, IPDL Background] WARNING: '!quotaManager', file z:/build/build/src/dom/quota/ActorsParent.cpp, line 8523
[task 2020-03-06T15:54:25.275Z] 15:54:25 INFO - Buffered messages logged at 15:48:03
[task 2020-03-06T15:54:25.275Z] 15:54:25 INFO - Entering test bound
[task 2020-03-06T15:54:25.275Z] 15:54:25 INFO - Adding a new tab with URL: data:text/html;charset=utf8,<p>test JSTerm Helpers autocomplete
[task 2020-03-06T15:54:25.275Z] 15:54:25 INFO - Buffered messages logged at 15:48:04
[task 2020-03-06T15:54:25.275Z] 15:54:25 INFO - Tab added and finished loading
[task 2020-03-06T15:54:25.276Z] 15:54:25 INFO - Opening the toolbox
[task 2020-03-06T15:54:25.282Z] 15:54:25 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-03-06T15:54:25.283Z] 15:54:25 INFO - Buffered messages logged at 15:48:05
[task 2020-03-06T15:54:25.283Z] 15:54:25 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-03-06T15:54:25.283Z] 15:54:25 INFO - Toolbox opened and focused
[task 2020-03-06T15:54:25.284Z] 15:54:25 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-03-06T15:54:25.287Z] 15:54:25 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_helpers.js | autocomplete results contain helper 'inspect' -
[task 2020-03-06T15:54:25.287Z] 15:54:25 INFO - Buffered messages finished
[task 2020-03-06T15:54:25.287Z] 15:54:25 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_helpers.js | application timed out after 370 seconds with no output
[task 2020-03-06T15:54:25.287Z] 15:54:25 ERROR - Force-terminating active process(es).
[task 2020-03-06T15:54:25.288Z] 15:54:25 INFO - Determining child pids from psutil...
[task 2020-03-06T15:54:25.288Z] 15:54:25 INFO - [2808]
[task 2020-03-06T15:54:25.288Z] 15:54:25 INFO - ==> process 2808 launched child process 3800 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.0.1437383604\629905965" -childID 1 -isForBrowser -prefsHandle 2196 -prefMapHandle 2188 -prefsLen 1 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 2240 tab)
[task 2020-03-06T15:54:25.289Z] 15:54:25 INFO - ==> process 2808 launched child process 4844 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.6.905419228\1942910048" -childID 2 -isForBrowser -prefsHandle 2452 -prefMapHandle 2448 -prefsLen 463 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 2464 tab)
[task 2020-03-06T15:54:25.289Z] 15:54:25 INFO - ==> process 2808 launched child process 4680 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.12.1826844145\1151153410" -childID 3 -isForBrowser -prefsHandle 4552 -prefMapHandle 4544 -prefsLen 524 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 4564 tab)
[task 2020-03-06T15:54:25.289Z] 15:54:25 INFO - ==> process 2808 launched child process 5272 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.18.454586737\1594200268" -childID 4 -isForBrowser -prefsHandle 3252 -prefMapHandle 3892 -prefsLen 10840 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 3696 tab)
[task 2020-03-06T15:54:25.290Z] 15:54:25 INFO - ==> process 2808 launched child process 5648 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.24.1727023492\1888494304" -childID 5 -isForBrowser -prefsHandle 4244 -prefMapHandle 4192 -prefsLen 10940 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 4256 tab)
[task 2020-03-06T15:54:25.290Z] 15:54:25 INFO - ==> process 2808 launched child process 3808 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.30.2107673493\1930528409" -childID 6 -isForBrowser -prefsHandle 3696 -prefMapHandle 4248 -prefsLen 11029 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 3804 tab)
[task 2020-03-06T15:54:25.290Z] 15:54:25 INFO - ==> process 2808 launched child process 4764 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.36.361922501\1033810422" -childID 7 -isForBrowser -prefsHandle 3704 -prefMapHandle 2900 -prefsLen 11029 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 3224 tab)
[task 2020-03-06T15:54:25.290Z] 15:54:25 INFO - ==> process 2808 launched child process 4552 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.42.1383216519\1301696856" -childID 8 -isForBrowser -prefsHandle 3080 -prefMapHandle 2908 -prefsLen 11109 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 3772 tab)
[task 2020-03-06T15:54:25.291Z] 15:54:25 INFO - ==> process 2808 launched child process 4416 ("Z:\task_1583504252\build\application\firefox\firefox.exe" -contentproc --channel="2808.48.11869301\81903566" -childID 9 -isForBrowser -prefsHandle 4184 -prefMapHandle 3640 -prefsLen 11109 -prefMapSize 231022 -parentBuildID 20200306140443 -appdir "Z:\task_1583504252\build\application\firefox\browser" - 2808 "\.\pipe\gecko-crash-server-pipe.2808" 3944 tab)
[task 2020-03-06T15:54:25.291Z] 15:54:25 INFO - Found child pids: set([3808, 4416, 4552, 4680, 4844, 5648, 3800, 2808, 5272, 4764])
[task 2020-03-06T15:54:25.291Z] 15:54:25 INFO - Killing process: 3808
[task 2020-03-06T15:54:25.291Z] 15:54:25 INFO - TEST-INFO | started process screenshot
[task 2020-03-06T15:54:25.391Z] 15:54:25 INFO - TEST-INFO | screenshot: exit 0
[task 2020-03-06T15:54:25.526Z] 15:54:25 INFO - Killing process: 4416

Comment 1

[Cosmin Sabou [:CosminS]]

This is a regression from https://hg.mozilla.org/integration/autoland/rev/a2ebc0c68234d99bb4e598b9f1e3f4902df9ad54
Retriggers: https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=Windows%2C7%2Cdebug%2CMochitests%2Ctest-windows7-32%2Fdebug-mochitest-devtools-chrome-e10s-3%2CM%28dt3%29&tochange=afbf73fb407354fdd4750dfda7d09e92810ed7ce&group_state=expanded&fromchange=4c839456c3fff6c961a29e589590a12768a44867&selectedJob=292143492
Nicholas, could you take a look over it? It fails only on windows7-32 debug: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-03-01&endday=2020-03-08&tree=trunk&bug=1620622 Thank you.

Comment 2

[Intermittent Failures Robot]

16 failures in 5483 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 13
• mozilla-central: 3

Platform breakdown:

• windows7-32: 16

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1620622&startday=2020-03-02&endday=2020-03-08&tree=all

Comment 3

[Arthur Iakab [arthur_iakab]]

Nicolas did you got the chance to look in to this issue? Since it is affecting several tests, like Bug1609313, Bug1620724, Bug1480802

Comment 4

[Intermittent Failures Robot]

15 failures in 981 pushes (0.015 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 13
• mozilla-central: 2

Platform breakdown:

• windows7-32: 15

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1620622&startday=2020-03-09&endday=2020-03-09&tree=all

Comment 5

[Nicolas Chevobbe [:nchevobbe]]

I'm going to have a look

(Bug 1480802 seems unlikely to be caused by this IMO)

Comment 6

[Daniel Varga [:dvarga]]

Nicholas, this seems to be almost perma https://treeherder.mozilla.org/#/jobs?repo=autoland&collapsedPushes=659858&fromchange=3f4214b66b8e5992817bb96a54bde225179ed0a8&searchStr=windows%2C7%2Cdebug%2Cmochitests%2Ctest-windows7-32%2Fdebug-mochitest-devtools-chrome-e10s-3%2Cm%28dt3%29

Comment 7

[Intermittent Failures Robot]

46 failures in 1083 pushes (0.042 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 42
• mozilla-central: 3
• try: 1

Platform breakdown:

• windows7-32: 45
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1620622&startday=2020-03-10&endday=2020-03-10&tree=all

Comment 8

[Intermittent Failures Robot]

85 failures in 5336 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

This is the #16 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 74
• mozilla-central: 9
• try: 2

Platform breakdown:

• windows7-32: 84
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1620622&startday=2020-03-09&endday=2020-03-15&tree=all

Comment 9

[Cosmin Sabou [:CosminS]]

Nicholas, any updates for this bug? It has 29 total failures on windows7-32 debug in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-03-15&endday=2020-03-22&tree=trunk&bug=1620622
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=294250464&repo=autoland

[task 2020-03-22T13:03:34.808Z] 13:03:34     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_helpers.js | autocomplete results contain helper 'inspect' - 
[task 2020-03-22T13:03:34.808Z] 13:03:34     INFO - Buffered messages finished
[task 2020-03-22T13:03:34.809Z] 13:03:34    ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_helpers.js | application timed out after 370 seconds with no output
[task 2020-03-22T13:03:34.809Z] 13:03:34    ERROR - Force-terminating active process(es).
[task 2020-03-22T13:03:34.809Z] 13:03:34     INFO - Determining child pids from psutil...
[task 2020-03-22T13:03:34.809Z] 13:03:34     INFO - [5700]
[task 2020-03-22T13:03:34.810Z] 13:03:34     INFO - ==> process 5700 launched child process 6128 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.0.220286408\1421733169" -childID 1 -isForBrowser -prefsHandle 2140 -prefMapHandle 2132 -prefsLen 1 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 2184 tab)
[task 2020-03-22T13:03:34.810Z] 13:03:34     INFO - ==> process 5700 launched child process 5508 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.6.1605540323\1852862604" -childID 2 -isForBrowser -prefsHandle 2440 -prefMapHandle 2436 -prefsLen 507 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 2452 tab)
[task 2020-03-22T13:03:34.810Z] 13:03:34     INFO - ==> process 5700 launched child process 5432 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.12.1447056458\2035461920" -childID 3 -isForBrowser -prefsHandle 4364 -prefMapHandle 4368 -prefsLen 568 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 4384 tab)
[task 2020-03-22T13:03:34.811Z] 13:03:34     INFO - ==> process 5700 launched child process 1344 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.18.113396242\158411953" -childID 4 -isForBrowser -prefsHandle 3508 -prefMapHandle 3076 -prefsLen 10531 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 3524 tab)
[task 2020-03-22T13:03:34.812Z] 13:03:34     INFO - ==> process 5700 launched child process 1304 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.24.1741840261\2058404472" -childID 5 -isForBrowser -prefsHandle 3884 -prefMapHandle 3560 -prefsLen 10793 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 3900 tab)
[task 2020-03-22T13:03:34.812Z] 13:03:34     INFO - ==> process 5700 launched child process 3284 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.30.193096460\1643644429" -childID 6 -isForBrowser -prefsHandle 4180 -prefMapHandle 2772 -prefsLen 10893 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 3840 tab)
[task 2020-03-22T13:03:34.812Z] 13:03:34     INFO - ==> process 5700 launched child process 5712 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.36.971276582\1712821226" -childID 7 -isForBrowser -prefsHandle 4320 -prefMapHandle 3624 -prefsLen 10982 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 2780 tab)
[task 2020-03-22T13:03:34.813Z] 13:03:34     INFO - ==> process 5700 launched child process 5188 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.42.1315319368\72901762" -childID 8 -isForBrowser -prefsHandle 3104 -prefMapHandle 4340 -prefsLen 10982 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 4048 tab)
[task 2020-03-22T13:03:34.813Z] 13:03:34     INFO - ==> process 5700 launched child process 5824 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.48.1574393280\911267947" -childID 9 -isForBrowser -prefsHandle 3624 -prefMapHandle 4044 -prefsLen 11062 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 2976 tab)
[task 2020-03-22T13:03:34.814Z] 13:03:34     INFO - ==> process 5700 launched child process 4688 ("Z:\task_1584879068\build\application\firefox\firefox.exe" -contentproc --channel="5700.54.800412483\1818974552" -childID 10 -isForBrowser -prefsHandle 4620 -prefMapHandle 4596 -prefsLen 11062 -prefMapSize 231689 -parentBuildID 20200322113828 -appdir "Z:\task_1584879068\build\application\firefox\browser" - 5700 "\\.\pipe\gecko-crash-server-pipe.5700" 4624 tab)
[task 2020-03-22T13:03:34.814Z] 13:03:34     INFO - Found child pids: set([1344, 5712, 5508, 5188, 6128, 5432, 3284, 1304, 5700, 5824, 4688])
[task 2020-03-22T13:03:34.814Z] 13:03:34     INFO - Killing process: 1344
[task 2020-03-22T13:03:34.814Z] 13:03:34     INFO - TEST-INFO | started process screenshot
[task 2020-03-22T13:03:34.889Z] 13:03:34     INFO - TEST-INFO | screenshot: exit 0
[task 2020-03-22T13:03:35.004Z] 13:03:35     INFO - Killing process: 5712
[task 2020-03-22T13:03:35.004Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.111Z] 13:03:35     INFO - Killing process: 5508
[task 2020-03-22T13:03:35.111Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.218Z] 13:03:35     INFO - Killing process: 5188
[task 2020-03-22T13:03:35.218Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.313Z] 13:03:35     INFO - Killing process: 6128
[task 2020-03-22T13:03:35.313Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.433Z] 13:03:35     INFO - Killing process: 5432
[task 2020-03-22T13:03:35.433Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.536Z] 13:03:35     INFO - Killing process: 3284
[task 2020-03-22T13:03:35.536Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.635Z] 13:03:35     INFO - Killing process: 1304
[task 2020-03-22T13:03:35.635Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.738Z] 13:03:35     INFO - Killing process: 5700
[task 2020-03-22T13:03:35.738Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.933Z] 13:03:35     INFO - Killing process: 5824
[task 2020-03-22T13:03:35.934Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.934Z] 13:03:35  WARNING - mozcrash kill_pid(): unable to terminate pid 5824: 5
[task 2020-03-22T13:03:35.934Z] 13:03:35     INFO - Killing process: 4688
[task 2020-03-22T13:03:35.934Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.941Z] 13:03:35  WARNING - mozcrash kill_pid(): unable to get handle for pid 4688: 87
[task 2020-03-22T13:03:35.941Z] 13:03:35     INFO - psutil found pid 3284 dead
[task 2020-03-22T13:03:35.941Z] 13:03:35     INFO - psutil found pid 5432 dead
[task 2020-03-22T13:03:35.941Z] 13:03:35     INFO - psutil found pid 5508 dead
[task 2020-03-22T13:03:35.941Z] 13:03:35     INFO - psutil found pid 4688 dead
[task 2020-03-22T13:03:35.942Z] 13:03:35     INFO - psutil found pid 5700 dead
[task 2020-03-22T13:03:35.942Z] 13:03:35     INFO - psutil found pid 5712 dead
[task 2020-03-22T13:03:35.942Z] 13:03:35     INFO - psutil found pid 6128 dead
[task 2020-03-22T13:03:35.942Z] 13:03:35     INFO - psutil found pid 1304 dead
[task 2020-03-22T13:03:35.942Z] 13:03:35     INFO - psutil found pid 5824 dead
[task 2020-03-22T13:03:35.942Z] 13:03:35     INFO - psutil found pid 5188 dead
[task 2020-03-22T13:03:35.943Z] 13:03:35     INFO - psutil found pid 1344 dead
[task 2020-03-22T13:03:35.943Z] 13:03:35     INFO - Killing process: 4768
[task 2020-03-22T13:03:35.943Z] 13:03:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T13:03:35.943Z] 13:03:35  WARNING - mozcrash kill_pid(): unable to terminate pid 4768: 5
[task 2020-03-22T13:03:35.943Z] 13:03:35     INFO - psutil found pid 4768 dead
[task 2020-03-22T13:03:35.958Z] 13:03:35     INFO - TEST-INFO | Main app process: exit 1
[task 2020-03-22T13:03:35.958Z] 13:03:35    ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
[task 2020-03-22T13:03:35.959Z] 13:03:35     INFO - TEST-INFO | Confirming we saw 95 DOCSHELL created and 79 destroyed log strings.
[task 2020-03-22T13:03:35.959Z] 13:03:35     INFO - TEST-INFO | Confirming we saw 257 DOMWINDOW created and 221 destroyed log strings.
[task 2020-03-22T13:03:35.959Z] 13:03:35     INFO - Buffered messages finished
[task 2020-03-22T13:03:35.960Z] 13:03:35    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_helpers.js | application terminated with exit code 1 

Thank you.

Comment 10

[Intermittent Failures Robot]

30 failures in 4955 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 28
• try: 1
• mozilla-beta: 1

Platform breakdown:

• windows7-32: 30

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1620622&startday=2020-03-16&endday=2020-03-22&tree=all

Comment 11

[Narcis Beleuzu [:NarcisB]]

There are 155 total failures for this bug.
All of them are on windows7-32 / debug: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-02-29&endday=2020-03-30&tree=trunk&bug=1620622

Comment 12

[Intermittent Failures Robot]

33 failures in 4773 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #41 most frequent failure this week.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 26
• mozilla-beta: 4
• mozilla-central: 2
• try: 1

Platform breakdown:

• windows7-32: 33

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1620622&startday=2020-03-23&endday=2020-03-29&tree=all

Comment 13

[Dorel Luca [:dluca]]

Attachment: Bug 1620622 - Disable browser_jsterm_autocomplete_helpers.js on Win 7-32 debug r=#intermittent-reviewers

Comment 14

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/07b5def477f7
Disable browser_jsterm_autocomplete_helpers.js on Win 7-32 debug r=jmaher

Comment 15

[Arthur Iakab [arthur_iakab]]

https://hg.mozilla.org/mozilla-central/rev/07b5def477f7

Comment 16

[Intermittent Failures Robot]

14 failures in 5015 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 10
• mozilla-release: 2
• mozilla-beta: 1

Platform breakdown:

• windows7-32: 14

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1620622&startday=2020-03-30&endday=2020-04-05&tree=all

Comment 17

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1620622 - Fix intermittent failure on browser_jsterm_autocomplete_helpers.

Comment 18

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/6ac369df0341
Fix intermittent failure on browser_jsterm_autocomplete_helpers. r=jdescottes

Comment 19

[Cristian Brindusan [:cbrindusan]]

https://hg.mozilla.org/mozilla-central/rev/6ac369df0341