1502839 - Intermittent TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/mochitest/browser_webconsole_object_inspector.js | application timed out after 370 seconds with no output

Status: RESOLVED INCOMPLETE

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nbeleuzu [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=208316453&repo=autoland

https://queue.taskcluster.net/v1/task/Q9CmKDKNSiSgrMIPGHGX1g/runs/3/artifacts/public/logs/live_backing.log

[task 2018-10-29T10:55:33.940Z] 10:55:33     INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_object_inspector.js | There is the expected number of nodes in the tree - 
[task 2018-10-29T10:55:33.940Z] 10:55:33     INFO - messages : [{"id":"1","type":"log","parameters":["oi-test",{"type":"object","actor":"server1.conn266.child1/obj28","class":"Array","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":4,"preview":{"kind":"ArrayLike","length":3,"items":[1,2,{"type":"object","actor":"server1.conn266.child1/obj29","class":"Object","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":2}]}},{"type":"object","actor":"server1.conn266.child1/obj30","class":"Object","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":3,"preview":{"kind":"Object","ownProperties":{"c":{"configurable":true,"enumerable":true,"writable":true,"value":"c"},"d":{"configurable":true,"enumerable":true,"writable":true,"value":{"type":"object","actor":"server1.conn266.child1/obj31","class":"Array","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":3,"preview":{"kind":"ArrayLike","length":2}}},"length":{"configurable":true,"enumerable":true,"writable":true,"value":987}},"ownSymbols":[],"ownPropertiesLength":3,"ownSymbolsLength":0,"safeGetterValues":{}}}],"messageText":null}]
[task 2018-10-29T10:55:33.940Z] 10:55:33     INFO - Buffered messages finished
[task 2018-10-29T10:55:33.940Z] 10:55:33    ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/mochitest/browser_webconsole_object_inspector.js | application timed out after 370 seconds with no output
[task 2018-10-29T10:55:33.940Z] 10:55:33    ERROR - Force-terminating active process(es).
[task 2018-10-29T10:55:33.940Z] 10:55:33     INFO - Determining child pids from psutil...
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - [3630, 3649, 3708, 3737, 3781]
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - ==> process 3524 launched child process 3547
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - ==> process 3524 launched child process 3630
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - ==> process 3524 launched child process 3649
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - ==> process 3524 launched child process 3708
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - ==> process 3524 launched child process 3737
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - ==> process 3524 launched child process 3781
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - Found child pids: set([3649, 3781, 3630, 3737, 3547, 3708])
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - Failed to get child procs
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - Killing process: 3649
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - TEST-INFO | started process screentopng
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - Failed to start /builds/worker/workspace/build/tests/bin/screentopng for screenshot: Cannot allocate memory
[task 2018-10-29T10:55:33.941Z] 10:55:33     INFO - Can't trigger Breakpad, just killing process
[task 2018-10-29T10:55:44.207Z] 10:55:44     INFO - psutil found pid 3649 dead
[task 2018-10-29T10:55:44.207Z] 10:55:44     INFO - Killing process: 3781
[task 2018-10-29T10:55:44.209Z] 10:55:44     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-10-29T10:55:44.210Z] 10:55:44     INFO - Can't trigger Breakpad, just killing process
[task 2018-10-29T10:55:44.630Z] 10:55:44     INFO - psutil found pid 3781 dead
[task 2018-10-29T10:55:44.630Z] 10:55:44     INFO - Killing process: 3630
[task 2018-10-29T10:55:44.631Z] 10:55:44     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-10-29T10:55:44.631Z] 10:55:44     INFO - Can't trigger Breakpad, just killing process
[task 2018-10-29T10:55:45.253Z] 10:55:45     INFO - psutil found pid 3630 dead
[task 2018-10-29T10:55:45.253Z] 10:55:45     INFO - Killing process: 3737
[task 2018-10-29T10:55:45.253Z] 10:55:45     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-10-29T10:55:45.253Z] 10:55:45     INFO - Can't trigger Breakpad, just killing process
[task 2018-10-29T10:55:45.987Z] 10:55:45     INFO - psutil found pid 3737 dead
[task 2018-10-29T10:55:45.989Z] 10:55:45     INFO - Killing process: 3547
[task 2018-10-29T10:55:45.991Z] 10:55:45     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-10-29T10:55:45.992Z] 10:55:45     INFO - Can't trigger Breakpad, just killing process
[task 2018-10-29T10:55:45.994Z] 10:55:45     INFO - Error: Failed to kill process 3547: psutil.NoSuchProcess no process found with pid 3547
[task 2018-10-29T10:55:45.994Z] 10:55:45     INFO - Killing process: 3708
[task 2018-10-29T10:55:45.995Z] 10:55:45     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-10-29T10:55:45.996Z] 10:55:45     INFO - Can't trigger Breakpad, just killing process
[task 2018-10-29T10:55:46.138Z] 10:55:46     INFO - psutil found pid 3708 dead
[task 2018-10-29T10:55:46.139Z] 10:55:46     INFO - Killing process: 3524
[task 2018-10-29T10:55:46.139Z] 10:55:46     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-10-29T10:55:46.140Z] 10:55:46     INFO - Can't trigger Breakpad, just killing process
[task 2018-10-29T10:56:16.151Z] 10:56:16     INFO - psutil found pid 3524 dead
[task 2018-10-29T10:56:16.151Z] 10:56:16     INFO - failed to kill pid 4838 after 30s
[task 2018-10-29T10:56:16.151Z] 10:56:16     INFO - failed to kill pid 4836 after 30s
[task 2018-10-29T10:56:16.152Z] 10:56:16     INFO - psutil found pid 3524 dead
[task 2018-10-29T10:56:16.180Z] 10:56:16  WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2018-10-29T10:56:16.182Z] 10:56:16     INFO - TEST-INFO | Main app process: exit 0

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1502839&startday=2018-10-29&endday=2018-11-04&tree=all

Comment 2

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup