Closed Bug 1728745 Opened 4 years ago Closed 1 year ago

Intermittent damp | inspector/layout.js: Test timed out

Categories

(DevTools :: Inspector: Layout, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2021-09-02T08:42:48.697Z] 08:42:48     INFO -  PID 2368 | Garbage collect
[task 2021-09-02T08:42:52.235Z] 08:42:52     INFO -  PID 2368 | webconsole/openwithcache.js took 7700ms.
[task 2021-09-02T08:42:52.236Z] 08:42:52     INFO -  PID 2368 | Loading test 'webconsole/typing.js'
[task 2021-09-02T08:42:52.236Z] 08:42:52     INFO -  PID 2368 | Executing test 'webconsole/typing.js'
[task 2021-09-02T08:42:52.254Z] 08:42:52     INFO -  PID 2368 | Wait for a pageshow event for browsing context 757
[task 2021-09-02T08:42:52.258Z] 08:42:52     INFO -  PID 2368 | [Parent 6652, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-09-02T08:42:52.348Z] 08:42:52     INFO -  PID 2368 | Received pageshow event for 757
[task 2021-09-02T08:42:52.448Z] 08:42:52     INFO -  PID 2368 | Open toolbox on 'webconsole'
[task 2021-09-02T08:42:52.449Z] 08:42:52     INFO -  PID 2368 | Open toolbox - Call showToolboxForTab
[task 2021-09-02T08:42:52.449Z] 08:42:52     INFO -  PID 2368 | Open toolbox - Wait for "toolbox-created"
[task 2021-09-02T08:42:52.453Z] 08:42:52     INFO -  PID 2368 | Open toolbox - Wait for showToolbox to resolve
[task 2021-09-02T08:42:54.516Z] 08:42:54     INFO -  PID 2368 | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2021-09-02T08:42:54.523Z] 08:42:54     INFO -  PID 2368 | Garbage collect
[task 2021-09-02T08:42:58.771Z] 08:42:58     INFO -  PID 2368 | webconsole/typing.js took 6535ms.
[task 2021-09-02T08:42:58.771Z] 08:42:58     INFO -  PID 2368 | Loading test 'inspector/mutations.js'
[task 2021-09-02T08:42:58.771Z] 08:42:58     INFO -  PID 2368 | Executing test 'inspector/mutations.js'
[task 2021-09-02T08:42:58.778Z] 08:42:58     INFO -  PID 2368 | Wait for a pageshow event for browsing context 762
[task 2021-09-02T08:42:58.807Z] 08:42:58     INFO -  PID 2368 | Received pageshow event for 762
[task 2021-09-02T08:42:58.924Z] 08:42:58     INFO -  PID 2368 | Open toolbox on 'inspector'
[task 2021-09-02T08:42:58.924Z] 08:42:58     INFO -  PID 2368 | Open toolbox - Call showToolboxForTab
[task 2021-09-02T08:42:58.925Z] 08:42:58     INFO -  PID 2368 | Open toolbox - Wait for "toolbox-created"
[task 2021-09-02T08:42:58.930Z] 08:42:58     INFO -  PID 2368 | Open toolbox - Wait for showToolbox to resolve
[task 2021-09-02T08:43:00.076Z] 08:43:00     INFO -  PID 2368 | Garbage collect
[task 2021-09-02T08:43:03.922Z] 08:43:03     INFO -  PID 2368 | inspector/mutations.js took 5151ms.
[task 2021-09-02T08:43:03.922Z] 08:43:03     INFO -  PID 2368 | Loading test 'inspector/layout.js'
[task 2021-09-02T08:43:03.922Z] 08:43:03     INFO -  PID 2368 | Executing test 'inspector/layout.js'
[task 2021-09-02T08:43:03.930Z] 08:43:03     INFO -  PID 2368 | Wait for a pageshow event for browsing context 768
[task 2021-09-02T08:43:03.935Z] 08:43:03     INFO -  PID 2368 | [Parent 6652, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-09-02T08:48:03.926Z] 08:48:03     INFO -  PID 2368 | TEST-UNEXPECTED-FAIL | damp | inspector/layout.js: Test timed out
[task 2021-09-02T08:48:03.931Z] 08:48:03     INFO -  Launcher process psutil.Process(pid=2368, name='firefox.exe', started='08:28:54') detected. Terminating parent process psutil.Process(pid=6652, name='firefox.exe', started='08:28:54') instead.
[task 2021-09-02T08:48:03.931Z] 08:48:03     INFO -  Terminating psutil.Process(pid=6652, name='firefox.exe', started='08:28:54')
[task 2021-09-02T08:48:03.932Z] 08:48:03     INFO -  PID 2368 | [DampLoad helper] Unregister DampLoad actors
[task 2021-09-02T08:48:03.942Z] 08:48:03     INFO -  PID 2368 | Exiting due to channel error.
[task 2021-09-02T08:48:04.268Z] 08:48:04     INFO -  mozcrash checking C:\Users\task_1630543791\AppData\Local\Temp\tmpaq_waeqv\profile\minidumps for minidumps...
[task 2021-09-02T08:48:04.268Z] 08:48:04     INFO -  TEST-UNEXPECTED-ERROR | damp | unexpected error
[task 2021-09-02T08:48:04.269Z] 08:48:04    ERROR -  Traceback (most recent call last):
[task 2021-09-02T08:48:04.269Z] 08:48:04     INFO -    File "C:\Users\task_1630543791\build\tests\talos\talos\run_tests.py", line 336, in run_tests
[task 2021-09-02T08:48:04.270Z] 08:48:04     INFO -      talos_results.add(mytest.runTest(browser_config, test))
[task 2021-09-02T08:48:04.270Z] 08:48:04     INFO -    File "C:\Users\task_1630543791\build\tests\talos\talos\ttest.py", line 65, in runTest
[task 2021-09-02T08:48:04.271Z] 08:48:04     INFO -      return self._runTest(browser_config, test_config, setup)
[task 2021-09-02T08:48:04.271Z] 08:48:04     INFO -    File "C:\Users\task_1630543791\build\tests\talos\talos\ttest.py", line 215, in _runTest
[task 2021-09-02T08:48:04.271Z] 08:48:04     INFO -      debugger_args=browser_config["debugger_args"],
[task 2021-09-02T08:48:04.272Z] 08:48:04     INFO -    File "C:\Users\task_1630543791\build\tests\talos\talos\talos_process.py", line 191, in run_browser
[task 2021-09-02T08:48:04.272Z] 08:48:04     INFO -      raise TalosError("unexpected error")
[task 2021-09-02T08:48:04.272Z] 08:48:04     INFO -  talos.utils.TalosError: unexpected error
[task 2021-09-02T08:48:04.272Z] 08:48:04     INFO -  TEST-INFO took 2870284ms
[task 2021-09-02T08:48:04.273Z] 08:48:04     INFO -  SUITE-END | took 2870s
[task 2021-09-02T08:48:04.609Z] 08:48:04     INFO -  Exception ignored in: <bound method ProcessHandlerMixin.Process.__del__ of <mozprocess.processhandler.ProcessHandlerMixin.Process object at 0x000001D837391E48>>
[task 2021-09-02T08:48:04.609Z] 08:48:04    ERROR -  Traceback (most recent call last):
[task 2021-09-02T08:48:04.609Z] 08:48:04     INFO -    File "C:\Users\task_1630543791\build\venv\lib\site-packages\mozprocess\processhandler.py", line 192, in __del__
[task 2021-09-02T08:48:04.610Z] 08:48:04     INFO -      self._internal_poll(_deadstate=_maxint)
[task 2021-09-02T08:48:04.610Z] 08:48:04     INFO -    File "c:\mozilla-build\python3\lib\subprocess.py", line 1035, in _internal_poll
[task 2021-09-02T08:48:04.610Z] 08:48:04     INFO -      if _WaitForSingleObject(self._handle, 0) == _WAIT_OBJECT_0:
[task 2021-09-02T08:48:04.611Z] 08:48:04    ERROR -  TypeError: WaitForSingleObject() argument 1 must be int, not AutoHANDLE
[task 2021-09-02T08:48:04.637Z] 08:48:04    ERROR - Return code: 2
[task 2021-09-02T08:48:04.637Z] 08:48:04  WARNING - setting return code to 2
[task 2021-09-02T08:48:04.637Z] 08:48:04    ERROR - # TBPL FAILURE #
[task 2021-09-02T08:48:04.637Z] 08:48:04     INFO - Running post-action listener: _package_coverage_data
[task 2021-09-02T08:48:04.637Z] 08:48:04     INFO - Running post-action listener: _resource_record_post_action
[task 2021-09-02T08:48:04.638Z] 08:48:04     INFO - Running post-action listener: process_java_coverage_data
[task 2021-09-02T08:48:04.638Z] 08:48:04     INFO - [mozharness: 2021-09-02 08:48:04.638880Z] Finished run-tests step (success)
[task 2021-09-02T08:48:04.638Z] 08:48:04     INFO - Running post-run listener: _resource_record_post_run
[task 2021-09-02T08:48:04.863Z] 08:48:04     INFO - Total resource usage - Wall time: 2873s; CPU: 18%; Read bytes: 528309248; Write bytes: 2449192448; Read time: 6; Write time: 34
[task 2021-09-02T08:48:04.863Z] 08:48:04     INFO - TinderboxPrint: CPU usage<br/>17.7%
[task 2021-09-02T08:48:04.863Z] 08:48:04     INFO - TinderboxPrint: I/O read bytes / time<br/>528,309,248 / 6
[task 2021-09-02T08:48:04.863Z] 08:48:04     INFO - TinderboxPrint: I/O write bytes / time<br/>2,449,192,448 / 34
[task 2021-09-02T08:48:04.863Z] 08:48:04     INFO - TinderboxPrint: CPU idle<br/>18,924.3 (82.3%)
[task 2021-09-02T08:48:04.864Z] 08:48:04     INFO - TinderboxPrint: CPU system<br/>696.8 (3.0%)
[task 2021-09-02T08:48:04.864Z] 08:48:04     INFO - TinderboxPrint: CPU user<br/>3,361.4 (14.6%)
[task 2021-09-02T08:48:04.864Z] 08:48:04     INFO - install - Wall time: 2s; CPU: 13%; Read bytes: 98304; Write bytes: 1236992; Read time: 0; Write time: 0
[task 2021-09-02T08:48:04.887Z] 08:48:04     INFO - run-tests - Wall time: 2871s; CPU: 18%; Read bytes: 528178176; Write bytes: 2433779200; Read time: 6; Write time: 34
[task 2021-09-02T08:48:05.643Z] 08:48:05  WARNING - returning nonzero exit status 2
[taskcluster 2021-09-02T08:48:05.702Z]    Exit Code: 2
[taskcluster 2021-09-02T08:48:05.702Z]    User Time: 0s
[taskcluster 2021-09-02T08:48:05.702Z]  Kernel Time: 15.625ms
[taskcluster 2021-09-02T08:48:05.702Z]    Wall Time: 49m27.8340492s
[taskcluster 2021-09-02T08:48:05.702Z]       Result: FAILED
[taskcluster 2021-09-02T08:48:05.702Z] === Task Finished ===
[taskcluster 2021-09-02T08:48:05.702Z] Task Duration: 49m27.8340492s
[taskcluster 2021-09-02T08:48:05.778Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2022-09-02T06:37:53.149Z
[taskcluster 2021-09-02T08:48:06.034Z] Uploading artifact public/test_info/damp_errorsummary.log from file build\blobber_upload_dir\damp_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2022-09-02T06:37:53.149Z
[taskcluster 2021-09-02T08:48:06.123Z] Uploading artifact public/test_info/damp_raw.log from file build\blobber_upload_dir\damp_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2022-09-02T06:37:53.149Z
[taskcluster 2021-09-02T08:48:06.352Z] Uploading artifact public/test_info/resource-usage.json from file build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2022-09-02T06:37:53.149Z
[taskcluster 2021-09-02T08:48:06.626Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PoNSTNGRS5-jQ3JTMCocQg/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2022-09-02T06:37:53.149Z
[taskcluster:error] exit status 2
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Component: Talos → Inspector: Layout
Product: Testing → DevTools
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.