1735453 - Intermittent TEST-UNEXPECTED-TIMEOUT | devtools/client/debugger/test/mochitest/browser_dbg-search-project.js | application timed out after 370 seconds with no output

Status: RESOLVED INCOMPLETE

(DevTools :: Debugger, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-10-13T03:42:58.722Z] 03:42:58     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-search-project.js | undefined assertion name - 
[task 2021-10-13T03:42:58.722Z] 03:42:58     INFO - Leaving test bound 
[task 2021-10-13T03:42:58.722Z] 03:42:58     INFO - Entering test bound 
[task 2021-10-13T03:42:58.723Z] 03:42:58     INFO - Adding a new tab with URL: https://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-react.html
[task 2021-10-13T03:42:58.723Z] 03:42:58     INFO - Buffered messages logged at 03:31:17
[task 2021-10-13T03:42:58.723Z] 03:42:58     INFO - Console message: [JavaScript Warning: "Empty string passed to getElementById()." {file: "chrome://global/content/elements/browser-custom-element.js" line: 391}]
[task 2021-10-13T03:42:58.724Z] 03:42:58     INFO - Buffered messages logged at 03:31:24
[task 2021-10-13T03:42:58.724Z] 03:42:58     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: "https://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-react.html" line: 0}]
[task 2021-10-13T03:42:58.725Z] 03:42:58     INFO - Buffered messages logged at 03:31:25
[task 2021-10-13T03:42:58.725Z] 03:42:58     INFO - Tab added and finished loading
[task 2021-10-13T03:42:58.725Z] 03:42:58     INFO - Opening the toolbox
[task 2021-10-13T03:42:58.726Z] 03:42:58     INFO - Buffered messages logged at 03:31:30
[task 2021-10-13T03:42:58.726Z] 03:42:58     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-10-13T03:42:58.726Z] 03:42:58     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-10-13T03:42:58.727Z] 03:42:58     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 75}]
[task 2021-10-13T03:42:58.727Z] 03:42:58     INFO - Buffered messages finished
[task 2021-10-13T03:42:58.728Z] 03:42:58    ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/debugger/test/mochitest/browser_dbg-search-project.js | application timed out after 370 seconds with no output
[task 2021-10-13T03:42:58.728Z] 03:42:58    ERROR - Force-terminating active process(es).
[task 2021-10-13T03:42:58.728Z] 03:42:58     INFO - Determining child pids from psutil...
[task 2021-10-13T03:43:05.372Z] 03:43:03     INFO - [1666, 1683, 1764, 1803, 1818, 2025]
[task 2021-10-13T03:46:46.603Z] 03:46:45     INFO - ==> process 1578 launched child process 1598
[task 2021-10-13T03:46:57.647Z] 03:46:56     INFO - ==> process 1578 launched child process 1666
[task 2021-10-13T03:47:22.547Z] 03:47:20     INFO - ==> process 1578 launched child process 1683
[task 2021-10-13T03:48:50.762Z] 03:48:50     INFO - ==> process 1578 launched child process 1764
[task 2021-10-13T03:49:06.142Z] 03:49:04     INFO - ==> process 1578 launched child process 1803
[task 2021-10-13T03:49:07.149Z] 03:49:07     INFO - ==> process 1578 launched child process 1818
[task 2021-10-13T03:49:07.158Z] 03:49:07     INFO - ==> process 1578 launched child process 2025
[task 2021-10-13T03:49:07.158Z] 03:49:07     INFO - Found child pids: {1666, 1764, 2025, 1803, 1683, 1818, 1598}
[task 2021-10-13T03:49:07.158Z] 03:49:07     INFO - Failed to get child procs
<...>
[task 2021-10-13T03:52:41.517Z] 03:52:41     INFO -  TEST-INFO | checking window state
[task 2021-10-13T03:52:41.517Z] 03:52:41     INFO -  Browser Chrome Test Summary
[task 2021-10-13T03:52:41.518Z] 03:52:41     INFO -  	Passed: 0
[task 2021-10-13T03:52:41.518Z] 03:52:41     INFO -  	Failed: 0
[task 2021-10-13T03:52:41.518Z] 03:52:41     INFO -  	Todo: 0
[task 2021-10-13T03:52:41.518Z] 03:52:41     INFO -  	Mode: e10s
[task 2021-10-13T03:52:41.519Z] 03:52:41     INFO -  *** End BrowserChrome Test Results ***
[task 2021-10-13T03:52:41.519Z] 03:52:41     INFO - Buffered messages finished
[task 2021-10-13T03:52:41.519Z] 03:52:41     INFO - SUITE-END | took 2006s
[task 2021-10-13T03:52:41.600Z] 03:52:41    ERROR - Return code: 1
[task 2021-10-13T03:52:41.604Z] 03:52:41     INFO - TinderboxPrint: mochitest-mochitest-devtools-chrome<br/>136/0/0
[task 2021-10-13T03:52:41.604Z] 03:52:41    ERROR - # TBPL FAILURE #
[task 2021-10-13T03:52:41.604Z] 03:52:41  WARNING - setting return code to 2
[task 2021-10-13T03:52:41.604Z] 03:52:41    ERROR - The mochitest suite: mochitest-devtools-chrome ran with return status: FAILURE
[task 2021-10-13T03:52:41.605Z] 03:52:41     INFO - Running post-action listener: _package_coverage_data
[task 2021-10-13T03:52:41.605Z] 03:52:41     INFO - Running post-action listener: _resource_record_post_action
[task 2021-10-13T03:52:41.605Z] 03:52:41     INFO - Running post-action listener: process_java_coverage_data
[task 2021-10-13T03:52:41.605Z] 03:52:41     INFO - [mozharness: 2021-10-13 03:52:41.605159Z] Finished run-tests step (success)
[task 2021-10-13T03:52:41.605Z] 03:52:41     INFO - Running post-run listener: _resource_record_post_run
[task 2021-10-13T03:52:41.865Z] 03:52:41     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2021-10-13T03:52:41.868Z] 03:52:41     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-devtools-chrome.overall", "extraOptions": ["e10s", "taskcluster-c5.xlarge"], "subtests": [{"name": "cpu_percent", "value": 43.628425309229314}, {"name": "io_write_bytes", "value": 3631005696}, {"name": "io.read_bytes", "value": 406965837824}, {"name": "io_write_time", "value": 433156}, {"name": "io_read_time", "value": 58011128}]}, {"name": "mochitest.mochitest-devtools-chrome.start-pulseaudio", "subtests": [{"name": "time", "value": 0.018229961395263672}]}, {"name": "mochitest.mochitest-devtools-chrome.install", "subtests": [{"name": "time", "value": 48.73030757904053}, {"name": "cpu_percent", "value": 25.219680851063835}]}, {"name": "mochitest.mochitest-devtools-chrome.stage-files", "subtests": [{"name": "time", "value": 0.00036644935607910156}]}, {"name": "mochitest.mochitest-devtools-chrome.run-tests", "subtests": [{"name": "time", "value": 2007.1024131774902}, {"name": "cpu_percent", "value": 44.52672155688624}]}]}
[task 2021-10-13T03:52:41.868Z] 03:52:41     INFO - Total resource usage - Wall time: 2054s; CPU: 45%; Read bytes: 406965837824; Write bytes: 3631005696; Read time: 58011128; Write time: 433156

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1735453&startday=2021-10-11&endday=2021-10-17&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1735453&startday=2021-10-25&endday=2021-10-31&tree=all

Comment 4

[BugBot [:suhaib / :marco]]

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