1589483 - Intermittent devtools/client/debugger/test/mochitest/browser_dbg-returnvalues.js | Test timed out -

Status: RESOLVED WORKSFORME

(DevTools :: Debugger, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: dvarga [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=271760378&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/P3AfCz-nTjicYtG_wYc8Gg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-10-17T18:46:11.938Z] 18:46:11     INFO - GECKO(3128) | [ACTION] CONNECT  - {"type":"CONNECT","mainThread":{"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-return-values.html","actor":"server0.conn81.child1/thread20","type":"mainThread","name":"Main Thread"},"traits":{"reconfigure":false,"frames":true,"logInPage":true,"canRewind":false,"watchpoints":true},"isWebExtension":false}
[task 2019-10-17T18:46:11.981Z] 18:46:11     INFO - GECKO(3128) | JavaScript error: , line 0: uncaught exception: out of memory
[task 2019-10-17T18:46:11.981Z] 18:46:11     INFO - GECKO(3128) | JavaScript error: , line 0: uncaught exception: out of memory
[task 2019-10-17T18:46:12.000Z] 18:46:12     INFO - GECKO(3128) | JavaScript error: , line 0: uncaught exception: out of memory
[task 2019-10-17T18:46:12.007Z] 18:46:12     INFO - GECKO(3128) | JavaScript error: , line 0: uncaught exception: out of memory
[task 2019-10-17T18:46:12.007Z] 18:46:12     INFO - GECKO(3128) | JavaScript error: , line 0: uncaught exception: out of memory
[task 2019-10-17T18:46:12.022Z] 18:46:12     INFO - GECKO(3128) | JavaScript error: , line 0: uncaught exception: out of memory
[task 2019-10-17T18:46:12.022Z] 18:46:12     INFO - GECKO(3128) | JavaScript error: , line 0: uncaught exception: out of memory
[task 2019-10-17T18:46:12.029Z] 18:46:12     INFO - GECKO(3128) | JavaScript error: , line 0: uncaught exception: out of memory
[task 2019-10-17T18:46:56.446Z] 18:46:56     INFO - TEST-INFO | started process screenshot
[task 2019-10-17T18:46:56.509Z] 18:46:56     INFO - TEST-INFO | screenshot: exit 0
[task 2019-10-17T18:46:56.510Z] 18:46:56     INFO - Buffered messages logged at 18:46:11
[task 2019-10-17T18:46:56.510Z] 18:46:56     INFO - Entering test bound 
[task 2019-10-17T18:46:56.510Z] 18:46:56     INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-return-values.html
[task 2019-10-17T18:46:56.511Z] 18:46:56     INFO - Tab added and finished loading
[task 2019-10-17T18:46:56.511Z] 18:46:56     INFO - Opening the toolbox
[task 2019-10-17T18:46:56.512Z] 18:46:56     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/devtools/client/debugger/test/mochitest/examples/doc-return-values.html" line: 0}]
[task 2019-10-17T18:46:56.512Z] 18:46:56     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 2019-10-17T18:46:56.512Z] 18:46:56     INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
[task 2019-10-17T18:46:56.512Z] 18:46:56     INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
[task 2019-10-17T18:46:56.513Z] 18:46:56     INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
[task 2019-10-17T18:46:56.513Z] 18:46:56     INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
[task 2019-10-17T18:46:56.513Z] 18:46:56     INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
[task 2019-10-17T18:46:56.513Z] 18:46:56     INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
[task 2019-10-17T18:46:56.514Z] 18:46:56     INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
[task 2019-10-17T18:46:56.514Z] 18:46:56     INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
[task 2019-10-17T18:46:56.514Z] 18:46:56     INFO - Buffered messages logged at 18:46:20
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - Console message: [JavaScript Error: "getScreenshot(http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-scripts.html) failed: TypeError: NetworkError when attempting to fetch resource." {file: "resource://activity-stream/lib/Screenshots.jsm" line: 59}]
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:59:10
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:112:37
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - _fetchScreenshot@resource://activity-stream/lib/TopSitesFeed.jsm:527:23
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - _fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:515:16
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:408:16
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:431:30
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - onAction@resource://activity-stream/lib/TopSitesFeed.jsm:771:14
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:63:17
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:39:54
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:27:24
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - notify@resource://gre/modules/Timer.jsm:62:17
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - 
[task 2019-10-17T18:46:56.515Z] 18:46:56     INFO - Buffered messages finished
[task 2019-10-17T18:46:56.516Z] 18:46:56     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-returnvalues.js | Test timed out - 
[task 2019-10-17T18:53:06.450Z] 18:53:06     INFO - Buffered messages finished
[task 2019-10-17T18:53:06.450Z] 18:53:06    ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/debugger/test/mochitest/browser_dbg-returnvalues.js | application timed out after 370 seconds with no output
[task 2019-10-17T18:53:06.450Z] 18:53:06    ERROR - Force-terminating active process(es).
[task 2019-10-17T18:53:06.450Z] 18:53:06     INFO - Determining child pids from psutil...
[task 2019-10-17T18:53:06.452Z] 18:53:06     INFO - [3784]
[task 2019-10-17T18:53:06.455Z] 18:53:06     INFO - ==> process 3784 launched child process 2464 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.0.120405355\1167466132" -childID 1 -isForBrowser -prefsHandle 1936 -prefMapHandle 2544 -prefsLen 1 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 2576 tab)
[task 2019-10-17T18:53:06.455Z] 18:53:06     INFO - ==> process 3784 launched child process 1032 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.6.1981378627\1357212753" -childID 2 -isForBrowser -prefsHandle 2776 -prefMapHandle 2796 -prefsLen 98 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 2788 tab)
[task 2019-10-17T18:53:06.455Z] 18:53:06     INFO - ==> process 3784 launched child process 2168 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.12.804906960\1876493521" -childID 3 -isForBrowser -prefsHandle 2984 -prefMapHandle 2992 -prefsLen 159 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 2988 tab)
[task 2019-10-17T18:53:06.456Z] 18:53:06     INFO - ==> process 3784 launched child process 1528 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.18.984629475\1954252546" -childID 4 -isForBrowser -prefsHandle 3840 -prefMapHandle 3832 -prefsLen 9641 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 3860 tab)
[task 2019-10-17T18:53:06.456Z] 18:53:06     INFO - ==> process 3784 launched child process 4292 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.24.1794209466\1381286930" -childID 5 -isForBrowser -prefsHandle 4320 -prefMapHandle 4312 -prefsLen 9741 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 4332 tab)
[task 2019-10-17T18:53:06.457Z] 18:53:06     INFO - ==> process 3784 launched child process 4428 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.30.981479046\1636242205" -childID 6 -isForBrowser -prefsHandle 2984 -prefMapHandle 4076 -prefsLen 9938 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 4088 tab)
[task 2019-10-17T18:53:06.457Z] 18:53:06     INFO - ==> process 3784 launched child process 4600 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.36.1541073757\449742690" -childID 7 -isForBrowser -prefsHandle 4668 -prefMapHandle 4696 -prefsLen 10144 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 4660 tab)
[task 2019-10-17T18:53:06.458Z] 18:53:06     INFO - ==> process 3784 launched child process 4736 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.42.590038473\1788850120" -childID 8 -isForBrowser -prefsHandle 4560 -prefMapHandle 4208 -prefsLen 9937 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 4688 tab)
[task 2019-10-17T18:53:06.458Z] 18:53:06     INFO - ==> process 3784 launched child process 5036 ("Z:\task_1571333469\build\application\firefox\firefox.exe" -contentproc --channel="3784.48.1087171005\70775179" -childID 9 -isForBrowser -prefsHandle 5036 -prefMapHandle 5032 -prefsLen 9937 -prefMapSize 221875 -parentBuildID 20191017155610 -greomni "Z:\task_1571333469\build\application\firefox\omni.ja" -appomni "Z:\task_1571333469\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1571333469\build\application\firefox\browser" - 3784 "\\.\pipe\gecko-crash-server-pipe.3784" 5048 tab)
[task 2019-10-17T18:53:06.458Z] 18:53:06     INFO - Found child pids: set([2464, 4736, 4292, 4600, 3784, 5036, 4428, 1032, 1528, 2168])
[task 2019-10-17T18:53:06.458Z] 18:53:06     INFO - Killing process: 2464
[task 2019-10-17T18:53:06.458Z] 18:53:06     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:06.618Z] 18:53:06     INFO - Killing process: 4736
[task 2019-10-17T18:53:06.618Z] 18:53:06     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:06.740Z] 18:53:06     INFO - Killing process: 4292
[task 2019-10-17T18:53:06.740Z] 18:53:06     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:06.907Z] 18:53:06     INFO - Killing process: 4600
[task 2019-10-17T18:53:06.907Z] 18:53:06     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:07.034Z] 18:53:07     INFO - Killing process: 3784
[task 2019-10-17T18:53:07.034Z] 18:53:07     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:07.343Z] 18:53:07     INFO - Killing process: 5036
[task 2019-10-17T18:53:07.343Z] 18:53:07     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:07.343Z] 18:53:07  WARNING - mozcrash kill_pid(): unable to get handle for pid 5036: 87
[task 2019-10-17T18:53:07.344Z] 18:53:07     INFO - Killing process: 4428
[task 2019-10-17T18:53:07.344Z] 18:53:07     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:07.344Z] 18:53:07  WARNING - mozcrash kill_pid(): unable to get handle for pid 4428: 87
[task 2019-10-17T18:53:07.344Z] 18:53:07     INFO - Killing process: 1032
[task 2019-10-17T18:53:07.344Z] 18:53:07     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:07.344Z] 18:53:07  WARNING - mozcrash kill_pid(): unable to get handle for pid 1032: 87
[task 2019-10-17T18:53:07.345Z] 18:53:07     INFO - Killing process: 1528
[task 2019-10-17T18:53:07.345Z] 18:53:07     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:07.348Z] 18:53:07  WARNING - mozcrash kill_pid(): unable to terminate pid 1528: 5
[task 2019-10-17T18:53:07.348Z] 18:53:07     INFO - Killing process: 2168
[task 2019-10-17T18:53:07.349Z] 18:53:07     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:07.349Z] 18:53:07  WARNING - mozcrash kill_pid(): unable to get handle for pid 2168: 87
[task 2019-10-17T18:53:07.350Z] 18:53:07     INFO - psutil found pid 4736 dead
[task 2019-10-17T18:53:07.350Z] 18:53:07     INFO - psutil found pid 3784 dead
[task 2019-10-17T18:53:07.351Z] 18:53:07     INFO - psutil found pid 2464 dead
[task 2019-10-17T18:53:07.352Z] 18:53:07     INFO - psutil found pid 1528 dead
[task 2019-10-17T18:53:07.352Z] 18:53:07     INFO - psutil found pid 4292 dead
[task 2019-10-17T18:53:07.352Z] 18:53:07     INFO - psutil found pid 4428 dead
[task 2019-10-17T18:53:07.352Z] 18:53:07     INFO - psutil found pid 2168 dead
[task 2019-10-17T18:53:07.353Z] 18:53:07     INFO - psutil found pid 5036 dead
[task 2019-10-17T18:53:07.353Z] 18:53:07     INFO - psutil found pid 4600 dead
[task 2019-10-17T18:53:07.353Z] 18:53:07     INFO - psutil found pid 1032 dead
[task 2019-10-17T18:53:07.353Z] 18:53:07     INFO - Killing process: 3128
[task 2019-10-17T18:53:07.354Z] 18:53:07     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-17T18:53:07.354Z] 18:53:07  WARNING - mozcrash kill_pid(): unable to terminate pid 3128: 5
[task 2019-10-17T18:53:07.354Z] 18:53:07     INFO - psutil found pid 3128 dead
[task 2019-10-17T18:53:07.369Z] 18:53:07     INFO - TEST-INFO | Main app process: exit 1
[task 2019-10-17T18:53:07.369Z] 18:53:07     INFO - Buffered messages finished
[task 2019-10-17T18:53:07.369Z] 18:53:07    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-returnvalues.js | application terminated with exit code 1

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1589483&startday=2019-10-14&endday=2019-10-20&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1589483&startday=2019-10-21&endday=2019-10-27&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1589483&startday=2019-10-28&endday=2019-11-03&tree=all