1623509 - Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/xpinstall/browser_httphash3.js | application timed out after 370 seconds with no output

Status: RESOLVED INCOMPLETE

(Toolkit :: Add-ons Manager, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-03-18T22:48:06.474Z] 22:48:06 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_httphash3.js
[task 2020-03-18T22:48:06.740Z] 22:48:06 INFO - GECKO(11064) | Sending InstallTriggered
[task 2020-03-18T22:48:06.881Z] 22:48:06 INFO - GECKO(11064) | 1584571686868 addons.xpi DEBUG Download started for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file C:\Users\task_1584569431\AppData\Local\Temp\tmp-s18.xpi
[task 2020-03-18T22:48:06.881Z] 22:48:06 INFO - GECKO(11064) | 1584571686870 addons.xpi DEBUG Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
[task 2020-03-18T22:48:06.922Z] 22:48:06 INFO - GECKO(11064) | 1584571686917 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-03-18T22:54:16.977Z] 22:54:16 INFO - Buffered messages logged at 22:48:06
[task 2020-03-18T22:54:16.977Z] 22:54:16 INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_httphash3.js | Should have seen the expected number of installs started -
[task 2020-03-18T22:54:16.977Z] 22:54:16 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: "http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%7B%22URL%22%3A%22https%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2FhashRedirect.sjs%3Fsha1%3Afoobar%7Chttp%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%2C%22Hash%22%3A%22sha1%3Aee95834ad862245a9ef99ccecc2a857cadc16404%22%7D%7D" line: 0}]
[task 2020-03-18T22:54:16.977Z] 22:54:16 INFO - Console message: 1584571686868 addons.xpi DEBUG Download started for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file C:\Users\task_1584569431\AppData\Local\Temp\tmp-s18.xpi
[task 2020-03-18T22:54:16.978Z] 22:54:16 INFO - Console message: 1584571686870 addons.xpi DEBUG Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
[task 2020-03-18T22:54:16.978Z] 22:54:16 INFO - Console message: 1584571686917 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-03-18T22:54:16.978Z] 22:54:16 INFO - Buffered messages finished
[task 2020-03-18T22:54:16.978Z] 22:54:16 ERROR - TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/xpinstall/browser_httphash3.js | application timed out after 370 seconds with no output
[task 2020-03-18T22:54:16.978Z] 22:54:16 ERROR - Force-terminating active process(es).
[task 2020-03-18T22:54:16.978Z] 22:54:16 INFO - Determining child pids from psutil...
[task 2020-03-18T22:54:16.983Z] 22:54:16 INFO - [10696]
[task 2020-03-18T22:54:16.983Z] 22:54:16 INFO - ==> process 10696 launched child process 6532 ("Z:\task_1584569431\build\application\firefox\firefox.exe" -contentproc --channel="10696.0.1571245402\1953888218" -parentBuildID 20200318212030 -prefsHandle 2268 -prefMapHandle 2256 -prefsLen 1 -prefMapSize 230352 -appdir "Z:\task_1584569431\build\application\firefox\browser" - 10696 gpu)
[task 2020-03-18T22:54:16.983Z] 22:54:16 INFO - ==> process 10696 launched child process 6940 ("Z:\task_1584569431\build\application\firefox\firefox.exe" -contentproc --channel="10696.6.155844798\2147077648" -childID 1 -isForBrowser -prefsHandle 2168 -prefMapHandle 2004 -prefsLen 1362 -prefMapSize 230352 -parentBuildID 20200318212030 -appdir "Z:\task_1584569431\build\application\firefox\browser" - 10696 tab)
[task 2020-03-18T22:54:16.983Z] 22:54:16 INFO - ==> process 10696 launched child process 1296 ("Z:\task_1584569431\build\application\firefox\firefox.exe" -contentproc --channel="10696.13.742968424\939355963" -childID 2 -isForBrowser -prefsHandle 3088 -prefMapHandle 3080 -prefsLen 1828 -prefMapSize 230352 -parentBuildID 20200318212030 -appdir "Z:\task_1584569431\build\application\firefox\browser" - 10696 tab)
[task 2020-03-18T22:54:16.983Z] 22:54:16 INFO - ==> process 10696 launched child process 4700 ("Z:\task_1584569431\build\application\firefox\firefox.exe" -contentproc --channel="10696.20.621549626\735812784" -childID 3 -isForBrowser -prefsHandle 5620 -prefMapHandle 5616 -prefsLen 1889 -prefMapSize 230352 -parentBuildID 20200318212030 -appdir "Z:\task_1584569431\build\application\firefox\browser" - 10696 tab)
[task 2020-03-18T22:54:16.984Z] 22:54:16 INFO - ==> process 10696 launched child process 10704 ("Z:\task_1584569431\build\application\firefox\firefox.exe" -contentproc --channel="10696.27.1265725320\335901525" -childID 4 -isForBrowser -prefsHandle 3768 -prefMapHandle 1276 -prefsLen 11794 -prefMapSize 230352 -parentBuildID 20200318212030 -appdir "Z:\task_1584569431\build\application\firefox\browser" - 10696 tab)
[task 2020-03-18T22:54:16.984Z] 22:54:16 INFO - ==> process 10696 launched child process 776 ("Z:\task_1584569431\build\application\firefox\firefox.exe" -contentproc --channel="10696.34.75751327\387424082" -childID 5 -isForBrowser -prefsHandle 4060 -prefMapHandle 5428 -prefsLen 11817 -prefMapSize 230352 -parentBuildID 20200318212030 -appdir "Z:\task_1584569431\build\application\firefox\browser" - 10696 tab)
[task 2020-03-18T22:54:16.984Z] 22:54:16 INFO - Found child pids: set([10704, 6532, 10696, 4700, 1296, 776, 6940])
[task 2020-03-18T22:54:16.984Z] 22:54:16 INFO - Killing process: 10704
[task 2020-03-18T22:54:16.984Z] 22:54:16 INFO - TEST-INFO | started process screenshot
[task 2020-03-18T22:54:17.204Z] 22:54:17 INFO - TEST-INFO | screenshot: exit 0
[task 2020-03-18T22:54:17.204Z] 22:54:17 INFO - Can't trigger Breakpad, just killing process
[task 2020-03-18T22:54:17.253Z] 22:54:17 INFO - psutil found pid 10704 dead
[task 2020-03-18T22:54:17.253Z] 22:54:17 INFO - Killing process: 6532
[task 2020-03-18T22:54:17.253Z] 22:54:17 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-18T22:54:17.253Z] 22:54:17 INFO - Can't trigger Breakpad, just killing process
[task 2020-03-18T22:54:17.324Z] 22:54:17 INFO - psutil found pid 6532 dead
[task 2020-03-18T22:54:17.324Z] 22:54:17 INFO - Killing process: 10696

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64: 1

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

Comment 2

[BugBot [:suhaib / :marco]]

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64: 1

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

Comment 4

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

New occurrence: https://treeherder.mozilla.org/logviewer.html#?job_id=311279697&repo=try

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64: 1

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

Comment 6

[BugBot [:suhaib / :marco]]

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

Comment 7

[Bogdan Tara[:bogdan_tara | bogdant]]

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=317502600&repo=mozilla-central&lineNumber=6743

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64: 1

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

Comment 9

[BugBot [:suhaib / :marco]]

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

Comment 10

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

New occurrence: https://treeherder.mozilla.org/logviewer?job_id=322044903&repo=try

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64: 1

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

Comment 12

[BugBot [:suhaib / :marco]]

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

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1623509&startday=2020-12-14&endday=2020-12-20&tree=all

Comment 14

[Atila Butkovits]

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=331942338&repo=autoland&lineNumber=4395

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64: 1
  • asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1623509&startday=2021-03-01&endday=2021-03-07&tree=all

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows10-64: 2
  • asan: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1623509&startday=2021-03-08&endday=2021-03-14&tree=all

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1
• autoland: 1

Platform and build breakdown:

• windows10-64: 2
  • asan: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1623509&startday=2021-03-15&endday=2021-03-21&tree=all

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• try: 1

Platform and build breakdown:

• windows10-64: 2
  • asan: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1623509&startday=2021-03-22&endday=2021-03-28&tree=all

Comment 19

[BugBot [:suhaib / :marco]]

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

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform and build breakdown:

• windows10-64: 1
  • asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1623509&startday=2021-05-03&endday=2021-05-09&tree=all