1618317 - Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/xpinstall/browser_cookies3.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: aciure [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=290612488&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/b-9aBf1sRhOfKCLE07XqBg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-02-26T18:12:51.467Z] 18:12:51 INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_cookies3.js | Should have seen the expected number of installs started -
[task 2020-02-26T18:12:51.467Z] 18:12:51 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%22Cookie%20check%22%3A%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2FcookieRedirect.sjs%3Fhttp%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%7D" line: 0}]
[task 2020-02-26T18:12:51.468Z] 18:12:51 INFO - Console message: 1582740401357 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file C:\Users\task_1582738532\AppData\Local\Temp\tmp-6tw.xpi
[task 2020-02-26T18:12:51.468Z] 18:12:51 INFO - Console message: 1582740401359 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
[task 2020-02-26T18:12:51.469Z] 18:12:51 INFO - Console message: 1582740401415 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-26T18:12:51.469Z] 18:12:51 INFO - Buffered messages finished
[task 2020-02-26T18:12:51.469Z] 18:12:51 ERROR - TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/xpinstall/browser_cookies3.js | application timed out after 370 seconds with no output
[task 2020-02-26T18:12:51.469Z] 18:12:51 ERROR - Force-terminating active process(es).
[task 2020-02-26T18:12:51.469Z] 18:12:51 INFO - Determining child pids from psutil...
[task 2020-02-26T18:12:51.474Z] 18:12:51 INFO - [7096]
[task 2020-02-26T18:12:51.474Z] 18:12:51 INFO - ==> process 7096 launched child process 9264 ("Z:\task_1582738532\build\application\firefox\firefox.exe" -contentproc --channel="7096.0.840457340\621349301" -parentBuildID 20200226162551 -prefsHandle 2276 -prefMapHandle 2264 -prefsLen 1 -prefMapSize 228720 -appdir "Z:\task_1582738532\build\application\firefox\browser" - 7096 gpu)
[task 2020-02-26T18:12:51.474Z] 18:12:51 INFO - ==> process 7096 launched child process 5936 ("Z:\task_1582738532\build\application\firefox\firefox.exe" -contentproc --channel="7096.6.643236590\680544817" -childID 1 -isForBrowser -prefsHandle 2920 -prefMapHandle 2916 -prefsLen 1391 -prefMapSize 228720 -parentBuildID 20200226162551 -appdir "Z:\task_1582738532\build\application\firefox\browser" - 7096 tab)
[task 2020-02-26T18:12:51.474Z] 18:12:51 INFO - ==> process 7096 launched child process 9648 ("Z:\task_1582738532\build\application\firefox\firefox.exe" -contentproc --channel="7096.13.1005762466\2013337340" -childID 2 -isForBrowser -prefsHandle 3096 -prefMapHandle 3092 -prefsLen 1857 -prefMapSize 228720 -parentBuildID 20200226162551 -appdir "Z:\task_1582738532\build\application\firefox\browser" - 7096 tab)
[task 2020-02-26T18:12:51.474Z] 18:12:51 INFO - ==> process 7096 launched child process 3460 ("Z:\task_1582738532\build\application\firefox\firefox.exe" -contentproc --channel="7096.20.1643798852\413316282" -childID 3 -isForBrowser -prefsHandle 4900 -prefMapHandle 4896 -prefsLen 1918 -prefMapSize 228720 -parentBuildID 20200226162551 -appdir "Z:\task_1582738532\build\application\firefox\browser" - 7096 tab)
[task 2020-02-26T18:12:51.474Z] 18:12:51 INFO - ==> process 7096 launched child process 6152 ("Z:\task_1582738532\build\application\firefox\firefox.exe" -contentproc --channel="7096.27.305152010\1509199448" -childID 4 -isForBrowser -prefsHandle 4596 -prefMapHandle 4592 -prefsLen 11819 -prefMapSize 228720 -parentBuildID 20200226162551 -appdir "Z:\task_1582738532\build\application\firefox\browser" - 7096 tab)
[task 2020-02-26T18:12:51.475Z] 18:12:51 INFO - ==> process 7096 launched child process 7288 ("Z:\task_1582738532\build\application\firefox\firefox.exe" -contentproc --channel="7096.34.1272933382\1420446999" -childID 5 -isForBrowser -prefsHandle 3812 -prefMapHandle 3428 -prefsLen 11842 -prefMapSize 228720 -parentBuildID 20200226162551 -appdir "Z:\task_1582738532\build\application\firefox\browser" - 7096 tab)
[task 2020-02-26T18:12:51.475Z] 18:12:51 INFO - Found child pids: set([9648, 3460, 6152, 9264, 7288, 7096, 5936])
[task 2020-02-26T18:12:51.475Z] 18:12:51 INFO - Killing process: 9648
[task 2020-02-26T18:12:51.475Z] 18:12:51 INFO - TEST-INFO | started process screenshot
[task 2020-02-26T18:12:51.697Z] 18:12:51 INFO - TEST-INFO | screenshot: exit 0
[task 2020-02-26T18:12:51.697Z] 18:12:51 INFO - Can't trigger Breakpad, just killing process
[task 2020-02-26T18:12:51.743Z] 18:12:51 INFO - psutil found pid 9648 dead
[task 2020-02-26T18:12:51.743Z] 18:12:51 INFO - Killing process: 3460
[task 2020-02-26T18:12:51.743Z] 18:12:51 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-26T18:12:51.744Z] 18:12:51 INFO - Can't trigger Breakpad, just killing process
[task 2020-02-26T18:12:51.802Z] 18:12:51 INFO - psutil found pid 3460 dead
[task 2020-02-26T18:12:51.802Z] 18:12:51 INFO - Killing process: 6152
[task 2020-02-26T18:12:51.802Z] 18:12:51 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-26T18:12:51.803Z] 18:12:51 INFO - Can't trigger Breakpad, just killing process
[task 2020-02-26T18:12:51.848Z] 18:12:51 INFO - psutil found pid 6152 dead
[task 2020-02-26T18:12:51.848Z] 18:12:51 INFO - Killing process: 9264
[task 2020-02-26T18:12:51.848Z] 18:12:51 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-26T18:12:51.848Z] 18:12:51 INFO - Can't trigger Breakpad, just killing process
[task 2020-02-26T18:12:51.927Z] 18:12:51 INFO - psutil found pid 9264 dead
[task 2020-02-26T18:12:51.927Z] 18:12:51 INFO - Killing process: 7288
[task 2020-02-26T18:12:51.927Z] 18:12:51 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-26T18:12:51.927Z] 18:12:51 INFO - Can't trigger Breakpad, just killing process
[task 2020-02-26T18:12:51.987Z] 18:12:51 INFO - psutil found pid 7288 dead
[task 2020-02-26T18:12:51.987Z] 18:12:51 INFO - Killing process: 7096
[task 2020-02-26T18:12:51.987Z] 18:12:51 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-26T18:12:51.987Z] 18:12:51 INFO - Can't trigger Breakpad, just killing process
[task 2020-02-26T18:12:52.271Z] 18:12:52 INFO - psutil found pid 7096 dead
[task 2020-02-26T18:12:52.271Z] 18:12:52 INFO - psutil found pid 5936 dead
[task 2020-02-26T18:12:52.271Z] 18:12:52 INFO - Killing process: 5936
[task 2020-02-26T18:12:52.271Z] 18:12:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-26T18:12:52.271Z] 18:12:52 INFO - Can't trigger Breakpad, just killing process
[task 2020-02-26T18:12:52.272Z] 18:12:52 INFO - Error: Failed to kill process 5936: psutil.NoSuchProcess no process found with pid 5936

Comment 1

[Intermittent Failures Robot]

1 failures in 5233 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=1618317&startday=2020-02-24&endday=2020-03-01&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 3

[Natalia Csoregi [:nataliaCs]]

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=297345153&repo=autoland&lineNumber=7000

Comment 4

[Intermittent Failures Robot]

1 failures in 4528 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=1618317&startday=2020-04-13&endday=2020-04-19&tree=all

Comment 5

[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=1618317&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 5276 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=1618317&startday=2020-05-04&endday=2020-05-10&tree=all

Comment 7

[Intermittent Failures Robot]

1 failures in 5488 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=1618317&startday=2020-05-11&endday=2020-05-17&tree=all

Comment 8

[Intermittent Failures Robot]

1 failures in 4727 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=1618317&startday=2020-05-18&endday=2020-05-24&tree=all

Comment 9

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 10

[Cristian Brindusan [:cbrindusan]]

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• windows10-64: 3

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

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows10-64: 1

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

Comment 13

[Intermittent Failures Robot]

1 failures in 4310 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=1618317&startday=2020-07-20&endday=2020-07-26&tree=all

Comment 14

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 15

[Atila Butkovits]

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=313773799&repo=mozilla-central&lineNumber=5187

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64-ccov: 1

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

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64-ccov: 1

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

Comment 18

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 19

[Razvan Maries]

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

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• windows10-64: 3

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

Comment 21

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform breakdown:

• windows10-64: 2

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

Comment 22

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows10-64: 2

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

Comment 23

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1
• autoland: 1

Platform breakdown:

• windows10-64: 2

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

Comment 24

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows10-64: 2

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

Comment 25

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 26

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

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

Comment 27

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows10-64: 1
  • asan: 1

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

Comment 28

[Intermittent Failures Robot]

1 failures in 3891 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=1618317&startday=2021-01-25&endday=2021-01-31&tree=all

Comment 29

[Intermittent Failures Robot]

2 failures in 3966 pushes (0.001 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=1618317&startday=2021-02-01&endday=2021-02-07&tree=all

Comment 30

[Intermittent Failures Robot]

1 failures in 3737 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=1618317&startday=2021-02-15&endday=2021-02-21&tree=all

Comment 31

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform and build breakdown:

• windows10-64: 3
  • asan: 3

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

Comment 32

[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=1618317&startday=2021-03-01&endday=2021-03-07&tree=all

Comment 33

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows10-64: 1
  • asan: 1

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

Comment 34

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• try: 1

Platform and build breakdown:

• windows10-64: 6
  • asan: 6

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

Comment 35

[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: 2

Platform and build breakdown:

• windows10-64: 2
  • asan: 2

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

Comment 36

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows10-64: 1
  • asan: 1

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

Comment 37

[BugBot [:suhaib / :marco/ :calixte]]

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