Closed Bug 1622581 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | browser/components/extensions/test/browser/browser_ext_browserAction_popup.js | application timed out after 370 seconds with no output

Categories

(WebExtensions :: Untriaged, defect, P5)

Product:
WebExtensions
Component:
Untriaged
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2020-03-14T23:19:11.747Z] 23:19:11 INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_browserAction_popup.js | Expect widget not to be overflowed -
[task 2020-03-14T23:19:11.748Z] 23:19:11 INFO - Buffered messages finished
[task 2020-03-14T23:19:11.748Z] 23:19:11 ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/extensions/test/browser/browser_ext_browserAction_popup.js | application timed out after 370 seconds with no output
[task 2020-03-14T23:19:11.748Z] 23:19:11 ERROR - Force-terminating active process(es).
[task 2020-03-14T23:19:11.748Z] 23:19:11 INFO - Determining child pids from psutil...
[task 2020-03-14T23:19:11.750Z] 23:19:11 INFO - [10080]
[task 2020-03-14T23:19:11.750Z] 23:19:11 INFO - ==> process 10080 launched child process 5856 ("Z:\task_1584226951\build\application\firefox\firefox.exe" -contentproc --channel="10080.0.489448416\1407418925" -parentBuildID 20200314214233 -prefsHandle 2280 -prefMapHandle 2272 -prefsLen 1 -prefMapSize 230144 -appdir "Z:\task_1584226951\build\application\firefox\browser" - 10080 gpu)
[task 2020-03-14T23:19:11.751Z] 23:19:11 INFO - ==> process 10080 launched child process 7644 ("Z:\task_1584226951\build\application\firefox\firefox.exe" -contentproc --channel="10080.6.2130876513\1578814604" -childID 1 -isForBrowser -prefsHandle 2904 -prefMapHandle 2896 -prefsLen 1391 -prefMapSize 230144 -parentBuildID 20200314214233 -appdir "Z:\task_1584226951\build\application\firefox\browser" - 10080 tab)
[task 2020-03-14T23:19:11.751Z] 23:19:11 INFO - ==> process 10080 launched child process 5488 ("Z:\task_1584226951\build\application\firefox\firefox.exe" -contentproc --channel="10080.13.1440016185\411195521" -childID 2 -isForBrowser -prefsHandle 3116 -prefMapHandle 3112 -prefsLen 1488 -prefMapSize 230144 -parentBuildID 20200314214233 -appdir "Z:\task_1584226951\build\application\firefox\browser" - 10080 tab)
[task 2020-03-14T23:19:11.752Z] 23:19:11 INFO - ==> process 10080 launched child process 9920 ("Z:\task_1584226951\build\application\firefox\firefox.exe" -contentproc --channel="10080.20.1191589913\1887310578" -childID 3 -isForBrowser -prefsHandle 4912 -prefMapHandle 4908 -prefsLen 1549 -prefMapSize 230144 -parentBuildID 20200314214233 -appdir "Z:\task_1584226951\build\application\firefox\browser" - 10080 tab)
[task 2020-03-14T23:19:11.752Z] 23:19:11 INFO - ==> process 10080 launched child process 10940 ("Z:\task_1584226951\build\application\firefox\firefox.exe" -contentproc --channel="10080.27.1895659638\756591624" -childID 4 -isForBrowser -prefsHandle 3772 -prefMapHandle 3912 -prefsLen 11378 -prefMapSize 230144 -parentBuildID 20200314214233 -appdir "Z:\task_1584226951\build\application\firefox\browser" - 10080 tab)
[task 2020-03-14T23:19:11.752Z] 23:19:11 INFO - ==> process 10080 launched child process 2384 ("Z:\task_1584226951\build\application\firefox\firefox.exe" -contentproc --channel="10080.34.52209610\1541155239" -childID 5 -isForBrowser -prefsHandle 4756 -prefMapHandle 3984 -prefsLen 11379 -prefMapSize 230144 -parentBuildID 20200314214233 -appdir "Z:\task_1584226951\build\application\firefox\browser" - 10080 tab)
[task 2020-03-14T23:19:11.752Z] 23:19:11 INFO - Found child pids: set([10080, 5856, 7644, 5488, 2384, 9920, 10940])
[task 2020-03-14T23:19:11.753Z] 23:19:11 INFO - Killing process: 10080
[task 2020-03-14T23:19:11.753Z] 23:19:11 INFO - TEST-INFO | started process screenshot
[task 2020-03-14T23:19:12.000Z] 23:19:12 INFO - TEST-INFO | screenshot: exit 0
[task 2020-03-14T23:19:12.000Z] 23:19:12 INFO - Can't trigger Breakpad, just killing process
[task 2020-03-14T23:19:12.474Z] 23:19:12 INFO - psutil found pid 5488 dead
[task 2020-03-14T23:19:12.475Z] 23:19:12 INFO - psutil found pid 7644 dead
[task 2020-03-14T23:19:12.475Z] 23:19:12 INFO - psutil found pid 5856 dead
[task 2020-03-14T23:19:12.476Z] 23:19:12 INFO - psutil found pid 9920 dead
[task 2020-03-14T23:19:12.476Z] 23:19:12 INFO - psutil found pid 2384 dead
[task 2020-03-14T23:19:12.477Z] 23:19:12 INFO - psutil found pid 10080 dead
[task 2020-03-14T23:19:12.477Z] 23:19:12 INFO - psutil found pid 10940 dead
[task 2020-03-14T23:19:12.477Z] 23:19:12 INFO - Killing process: 5856
[task 2020-03-14T23:19:12.477Z] 23:19:12 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-14T23:19:12.478Z] 23:19:12 INFO - Can't trigger Breakpad, just killing process
[task 2020-03-14T23:19:12.478Z] 23:19:12 INFO - Error: Failed to kill process 5856: psutil.NoSuchProcess no process found with pid 5856
[task 2020-03-14T23:19:12.479Z] 23:19:12 INFO - Killing process: 7644

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

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.