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

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-03-11T17:53:44.115Z] 17:53:44 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-11T17:53:44.115Z] 17:53:44 INFO - Toolbox opened and focused
[task 2020-03-11T17:53:44.115Z] 17:53:44 INFO - wait for completion suggestions for "xx"
[task 2020-03-11T17:53:44.116Z] 17:53:44 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-11T17:53:44.117Z] 17:53:44 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_width.js | popup is open -
[task 2020-03-11T17:53:44.117Z] 17:53:44 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_width.js | popup has expected items -
[task 2020-03-11T17:53:44.117Z] 17:53:44 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_width.js | popup (38px) is at least wider than the width of the longest list item (18px) -
[task 2020-03-11T17:53:44.117Z] 17:53:44 INFO - wait for completion suggestions for "xx."
[task 2020-03-11T17:53:44.118Z] 17:53:44 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_width.js | popup has expected items -
[task 2020-03-11T17:53:44.118Z] 17:53:44 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_width.js | The popup width was updated (38px -> 140px) -
[task 2020-03-11T17:53:44.118Z] 17:53:44 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_width.js | popup (140px) is at least wider than the width of the longest list item (120px) -
[task 2020-03-11T17:53:44.119Z] 17:53:44 INFO - wait for completion suggestions for "xx"
[task 2020-03-11T17:53:44.119Z] 17:53:44 INFO - Buffered messages finished
[task 2020-03-11T17:53:44.119Z] 17:53:44 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/browser/browser_jsterm_autocomplete_width.js | application timed out after 370 seconds with no output
[task 2020-03-11T17:53:44.120Z] 17:53:44 ERROR - Force-terminating active process(es).
[task 2020-03-11T17:53:44.120Z] 17:53:44 INFO - Determining child pids from psutil...
[task 2020-03-11T17:53:44.120Z] 17:53:44 INFO - [2936]
[task 2020-03-11T17:53:44.121Z] 17:53:44 INFO - ==> process 2936 launched child process 4612 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.0.951644446\1729704859" -childID 1 -isForBrowser -prefsHandle 2208 -prefMapHandle 2200 -prefsLen 1 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 2252 tab)
[task 2020-03-11T17:53:44.121Z] 17:53:44 INFO - ==> process 2936 launched child process 3144 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.6.1038045774\1794663903" -childID 2 -isForBrowser -prefsHandle 2468 -prefMapHandle 2464 -prefsLen 463 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 2480 tab)
[task 2020-03-11T17:53:44.121Z] 17:53:44 INFO - ==> process 2936 launched child process 1684 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.12.53908844\382157968" -childID 3 -isForBrowser -prefsHandle 4792 -prefMapHandle 4804 -prefsLen 524 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 4816 tab)
[task 2020-03-11T17:53:44.121Z] 17:53:44 INFO - ==> process 2936 launched child process 2296 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.18.1969270599\1420576505" -childID 4 -isForBrowser -prefsHandle 3084 -prefMapHandle 3744 -prefsLen 10487 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 3756 tab)
[task 2020-03-11T17:53:44.122Z] 17:53:44 INFO - ==> process 2936 launched child process 548 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.24.639059971\1523624968" -childID 5 -isForBrowser -prefsHandle 4432 -prefMapHandle 2200 -prefsLen 10749 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 4464 tab)
[task 2020-03-11T17:53:44.122Z] 17:53:44 INFO - ==> process 2936 launched child process 3856 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.30.125925019\417733128" -childID 6 -isForBrowser -prefsHandle 4592 -prefMapHandle 4548 -prefsLen 10849 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 4372 tab)
[task 2020-03-11T17:53:44.122Z] 17:53:44 INFO - ==> process 2936 launched child process 4324 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.36.1130025167\299118335" -childID 7 -isForBrowser -prefsHandle 4452 -prefMapHandle 4776 -prefsLen 10938 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 3792 tab)
[task 2020-03-11T17:53:44.122Z] 17:53:44 INFO - ==> process 2936 launched child process 2052 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.42.1035418299\1940233424" -childID 8 -isForBrowser -prefsHandle 3492 -prefMapHandle 3876 -prefsLen 10938 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 3528 tab)
[task 2020-03-11T17:53:44.122Z] 17:53:44 INFO - ==> process 2936 launched child process 3156 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.48.869254693\1400383029" -childID 9 -isForBrowser -prefsHandle 4452 -prefMapHandle 3332 -prefsLen 11018 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 4652 tab)
[task 2020-03-11T17:53:44.122Z] 17:53:44 INFO - ==> process 2936 launched child process 4232 ("Z:\task_1583943603\build\application\firefox\firefox.exe" -contentproc --channel="2936.54.284631090\1287673323" -childID 10 -isForBrowser -prefsHandle 3396 -prefMapHandle 3400 -prefsLen 11018 -prefMapSize 231138 -parentBuildID 20200311164204 -appdir "Z:\task_1583943603\build\application\firefox\browser" - 2936 "\.\pipe\gecko-crash-server-pipe.2936" 4016 tab)
[task 2020-03-11T17:53:44.123Z] 17:53:44 INFO - Found child pids: set([3156, 4612, 4324, 3144, 3856, 2296, 1684, 2936, 548, 4232, 2052])
[task 2020-03-11T17:53:44.123Z] 17:53:44 INFO - Killing process: 3156
[task 2020-03-11T17:53:44.123Z] 17:53:44 INFO - TEST-INFO | started process screenshot
[task 2020-03-11T17:53:44.218Z] 17:53:44 INFO - TEST-INFO | screenshot: exit 0

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6
• mozilla-central: 1
• try: 1

Platform breakdown:

• windows7-32: 8

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

Comment 3

[Intermittent Failures Robot]

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

This is the #21 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: 37
• try: 3
• mozilla-central: 3
• mozilla-beta: 2

Platform breakdown:

• windows7-32: 45

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

Comment 4

[Intermittent Failures Robot]

16 failures in 1003 pushes (0.016 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 15
• mozilla-central: 1

Platform breakdown:

• windows7-32: 16

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

Comment 5

[Intermittent Failures Robot]

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

This is the #32 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: 33
• mozilla-central: 2

Platform breakdown:

• windows7-32: 35

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

Comment 6

[Intermittent Failures Robot]

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

This is the #40 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:

• mozilla-beta: 1
• mozilla-central: 3
• autoland: 27
• try: 1

Platform breakdown:

• windows7-32: 32

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

Comment 7

[Cosmin Sabou [:CosminS]]

This has:

• 148 total failures in the last 30 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-03-18&endday=2020-04-17&tree=trunk&bug=1621766
• 43 total failures in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-04-10&endday=2020-04-17&tree=trunk&bug=1621766

All failures are on windows7-32 debug. Nicolas, can we go ahead and disable this test on the failing platform or do you have a fix for it? Thanks.

Comment 8

[Intermittent Failures Robot]

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

This is the #32 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
• try: 1
• mozilla-beta: 1
• mozilla-central: 3

Platform breakdown:

• windows7-32: 45

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

Comment 9

[Nicolas Chevobbe [:nchevobbe]]

Yes, let's disable this.
This is part of a more global failures in the console autocomplete, only on windows 7 debug builds that I still didn't figure out as it's outside of my knowledge, and the people I asked for help so far were clueless as well :/

Comment 10

[Cosmin Sabou [:CosminS]]

Attachment: Bug 1621766 - Disable browser_jsterm_autocomplete_width.js on win 7 debug. r=nchevobbe

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 20
• try: 1
• mozilla-beta: 2

Platform breakdown:

• windows7-32: 23

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

Comment 12

[Nicolas Chevobbe [:nchevobbe]]

There wasn't any failures since Friday, this was probably fixed by Bug 1620622. Can we close this bug?

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• windows7-32: 1

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