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

Status: RESOLVED DUPLICATE of bug 1620622

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-03-13T05:52:07.501Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | output is correct for $x() -
[task 2020-03-13T05:52:07.502Z] 05:52:07 INFO - Buffered messages logged at 05:45:51
[task 2020-03-13T05:52:07.502Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | There's no duplicated "cd" item in the autocomplete popup -
[task 2020-03-13T05:52:07.502Z] 05:52:07 INFO - Matched a message with text: ""content-cd"", all messages received.
[task 2020-03-13T05:52:07.502Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | output is correct for cd() -
[task 2020-03-13T05:52:07.503Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | There's no duplicated "clear" item in the autocomplete popup -
[task 2020-03-13T05:52:07.503Z] 05:52:07 INFO - Matched a message with text: ""content-clear"", all messages received.
[task 2020-03-13T05:52:07.503Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | output is correct for clear() -
[task 2020-03-13T05:52:07.503Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | There's no duplicated "clearHistory" item in the autocomplete popup -
[task 2020-03-13T05:52:07.504Z] 05:52:07 INFO - Matched a message with text: ""content-clearHistory"", all messages received.
[task 2020-03-13T05:52:07.504Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | output is correct for clearHistory() -
[task 2020-03-13T05:52:07.504Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | There's no duplicated "copy" item in the autocomplete popup -
[task 2020-03-13T05:52:07.504Z] 05:52:07 INFO - Buffered messages logged at 05:45:52
[task 2020-03-13T05:52:07.505Z] 05:52:07 INFO - Matched a message with text: ""content-copy"", all messages received.
[task 2020-03-13T05:52:07.505Z] 05:52:07 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | output is correct for copy() -
[task 2020-03-13T05:52:07.505Z] 05:52:07 INFO - Buffered messages finished
[task 2020-03-13T05:52:07.505Z] 05:52:07 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/browser/browser_jsterm_content_defined_helpers.js | application timed out after 370 seconds with no output
[task 2020-03-13T05:52:07.505Z] 05:52:07 ERROR - Force-terminating active process(es).
[task 2020-03-13T05:52:07.506Z] 05:52:07 INFO - Determining child pids from psutil...
[task 2020-03-13T05:52:07.506Z] 05:52:07 INFO - [3776]
[task 2020-03-13T05:52:07.506Z] 05:52:07 INFO - ==> process 3776 launched child process 3676 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.0.1860324676\80572662" -childID 1 -isForBrowser -prefsHandle 2184 -prefMapHandle 2176 -prefsLen 1 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 2228 tab)
[task 2020-03-13T05:52:07.507Z] 05:52:07 INFO - ==> process 3776 launched child process 4940 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.6.1590105870\605584882" -childID 2 -isForBrowser -prefsHandle 2436 -prefMapHandle 2432 -prefsLen 463 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 2448 tab)
[task 2020-03-13T05:52:07.507Z] 05:52:07 INFO - ==> process 3776 launched child process 1076 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.12.186630512\1190852242" -childID 3 -isForBrowser -prefsHandle 4388 -prefMapHandle 4376 -prefsLen 524 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 4400 tab)
[task 2020-03-13T05:52:07.507Z] 05:52:07 INFO - ==> process 3776 launched child process 4128 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.18.712690770\1034751714" -childID 4 -isForBrowser -prefsHandle 3352 -prefMapHandle 3508 -prefsLen 10647 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 4336 tab)
[task 2020-03-13T05:52:07.507Z] 05:52:07 INFO - ==> process 3776 launched child process 5356 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.24.2146811146\994258823" -childID 5 -isForBrowser -prefsHandle 3356 -prefMapHandle 4016 -prefsLen 10840 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 3100 tab)
[task 2020-03-13T05:52:07.508Z] 05:52:07 INFO - ==> process 3776 launched child process 5072 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.30.1552857344\68276843" -childID 6 -isForBrowser -prefsHandle 2844 -prefMapHandle 3484 -prefsLen 10840 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 2768 tab)
[task 2020-03-13T05:52:07.508Z] 05:52:07 INFO - ==> process 3776 launched child process 156 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.36.512112163\4150146" -childID 7 -isForBrowser -prefsHandle 4132 -prefMapHandle 4044 -prefsLen 11029 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 2736 tab)
[task 2020-03-13T05:52:07.508Z] 05:52:07 INFO - ==> process 3776 launched child process 4852 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.42.1530392480\830654100" -childID 8 -isForBrowser -prefsHandle 2824 -prefMapHandle 2836 -prefsLen 11029 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 2696 tab)
[task 2020-03-13T05:52:07.508Z] 05:52:07 INFO - ==> process 3776 launched child process 5260 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.48.1542654042\1268271864" -childID 9 -isForBrowser -prefsHandle 3344 -prefMapHandle 2712 -prefsLen 11109 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 3784 tab)
[task 2020-03-13T05:52:07.508Z] 05:52:07 INFO - ==> process 3776 launched child process 1408 ("Z:\task_1584072623\build\application\firefox\firefox.exe" -contentproc --channel="3776.54.1959579220\91273911" -childID 10 -isForBrowser -prefsHandle 3848 -prefMapHandle 1672 -prefsLen 11109 -prefMapSize 231349 -parentBuildID 20200313042427 -appdir "Z:\task_1584072623\build\application\firefox\browser" - 3776 "\.\pipe\gecko-crash-server-pipe.3776" 3224 tab)
[task 2020-03-13T05:52:07.509Z] 05:52:07 INFO - Found child pids: set([3776, 4128, 5260, 5356, 4940, 1408, 5072, 156, 1076, 4852, 3676])
[task 2020-03-13T05:52:07.509Z] 05:52:07 INFO - Killing process: 3776
[task 2020-03-13T05:52:07.509Z] 05:52:07 INFO - TEST-INFO | started process screenshot
[task 2020-03-13T05:52:07.584Z] 05:52:07 INFO - TEST-INFO | screenshot: exit 0
[task 2020-03-13T05:52:07.811Z] 05:52:07 INFO - Killing process: 4128
[task 2020-03-13T05:52:07.811Z] 05:52:07 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-13T05:52:07.811Z] 05:52:07 WARNING - mozcrash kill_pid(): unable to get handle for pid 4128: 87
[task 2020-03-13T05:52:07.811Z] 05:52:07 INFO - Killing process: 5260
[task 2020-03-13T05:52:07.811Z] 05:52:07 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-13T05:52:07.818Z] 05:52:07 WARNING - mozcrash kill_pid(): unable to get handle for pid 5260: 87
[task 2020-03-13T05:52:07.818Z] 05:52:07 INFO - Killing process: 5356
[task 2020-03-13T05:52:07.819Z] 05:52:07 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-13T05:52:07.820Z] 05:52:07 WARNING - mozcrash kill_pid(): unable to get handle for pid 5356: 87

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows7-32: 2

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5

Platform breakdown:

• windows7-32: 5

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

Comment 3

[Intermittent Failures Robot]

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

This is the #33 most frequent failure this week.

** 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: 36
• mozilla-central: 1

Platform breakdown:

• windows7-32: 37

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

Comment 4

[Intermittent Failures Robot]

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

This is the #22 most frequent failure this week.

** 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: 40

Platform breakdown:

• windows7-32: 40

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

Comment 5

[Intermittent Failures Robot]

19 failures in 4823 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 18
• mozilla-central: 1

Platform breakdown:

• windows7-32: 19

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 11
• mozilla-beta: 1

Platform breakdown:

• windows7-32: 12

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

Comment 7

[Intermittent Failures Robot]

10 failures in 5582 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 1
• mozilla-beta: 2

Platform breakdown:

• windows7-32: 10

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

Comment 8

[Nicolas Chevobbe [:nchevobbe]]

No failures since Friday, I guess it was fixed by Bug 1620622.