1730605 - Intermittent damp | styleeditor/simple.js: TypeError: target.client is null

Status: RESOLVED INCOMPLETE

(Testing :: Talos, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-09-13T20:59:02.055Z] 20:59:02     INFO -  PID 5532 | Executing test 'styleeditor/simple.js'
[task 2021-09-13T20:59:02.062Z] 20:59:02     INFO -  PID 5532 | Wait for a pageshow event for browsing context 239
[task 2021-09-13T20:59:02.080Z] 20:59:02     INFO -  PID 5532 | Received pageshow event for 239
[task 2021-09-13T20:59:02.180Z] 20:59:02     INFO -  PID 5532 | Open toolbox on 'styleeditor'
[task 2021-09-13T20:59:02.181Z] 20:59:02     INFO -  PID 5532 | Open toolbox - Call showToolboxForTab
[task 2021-09-13T20:59:02.181Z] 20:59:02     INFO -  PID 5532 | Open toolbox - Wait for "toolbox-created"
[task 2021-09-13T20:59:02.186Z] 20:59:02     INFO -  PID 5532 | Open toolbox - Wait for showToolbox to resolve
[task 2021-09-13T20:59:02.298Z] 20:59:02     INFO -  PID 5532 | Wait for pending paints on 'simple.styleeditor.open'
[task 2021-09-13T20:59:02.298Z] 20:59:02     INFO -  PID 5532 | 'simple.styleeditor.open.settle.DAMP' took 0.03515729133505374ms.
[task 2021-09-13T20:59:02.298Z] 20:59:02     INFO -  PID 5532 | Garbage collect
[task 2021-09-13T20:59:02.671Z] 20:59:02     INFO -  PID 5532 | Reload page on 'simple.styleeditor'
[task 2021-09-13T20:59:02.671Z] 20:59:02     INFO -  PID 5532 | Wait for a pageshow event for browsing context 239
[task 2021-09-13T20:59:02.706Z] 20:59:02     INFO -  PID 5532 | Received pageshow event for 239
[task 2021-09-13T20:59:02.707Z] 20:59:02     INFO -  PID 5532 | Wait for pending paints on 'simple.styleeditor.reload'
[task 2021-09-13T20:59:02.707Z] 20:59:02     INFO -  PID 5532 | 'simple.styleeditor.reload.settle.DAMP' took 0.05700259856530465ms.
[task 2021-09-13T20:59:02.707Z] 20:59:02     INFO -  PID 5532 | Close toolbox on 'simple.styleeditor'
[task 2021-09-13T20:59:02.711Z] 20:59:02    ERROR -  PID 5532 | TEST-UNEXPECTED-FAIL | damp | styleeditor/simple.js: TypeError: target.client is null
[task 2021-09-13T20:59:02.712Z] 20:59:02     INFO -  Launcher process psutil.Process(pid=5532, name='firefox.exe', started='20:55:55') detected. Terminating parent process psutil.Process(pid=2276, name='firefox.exe', started='20:55:55') instead.
[task 2021-09-13T20:59:02.713Z] 20:59:02     INFO -  Terminating psutil.Process(pid=2276, name='firefox.exe', started='20:55:55')
[task 2021-09-13T20:59:02.713Z] 20:59:02     INFO -  PID 5532 | [DampLoad helper] Unregister DampLoad actors
[task 2021-09-13T20:59:02.713Z] 20:59:02     INFO -  PID 5532 | exports.closeToolboxAndLog@file:///C:/Users/task_1631556402/AppData/Local/Temp/tmpc4x418r1/profile/extensions/[e-mail]/content/tests/head.js:155:3
[task 2021-09-13T20:59:02.714Z] 20:59:02     INFO -  PID 5532 | module.exports@file:///C:/Users/task_1631556402/AppData/Local/Temp/tmpc4x418r1/profile/extensions/[e-mail]/content/tests/styleeditor/simple.js:20:9
[task 2021-09-13T20:59:02.714Z] 20:59:02     INFO -  PID 5532 |
[task 2021-09-13T20:59:02.719Z] 20:59:02     INFO -  PID 5532 | Exiting due to channel error.
[task 2021-09-13T20:59:02.719Z] 20:59:02     INFO -  PID 5532 | Exiting due to channel error.
[task 2021-09-13T20:59:02.991Z] 20:59:02     INFO -  mozcrash checking C:\Users\task_1631556402\AppData\Local\Temp\tmpc4x418r1\profile\minidumps for minidumps...
[task 2021-09-13T20:59:02.991Z] 20:59:02     INFO -  TEST-UNEXPECTED-ERROR | damp | unexpected error
[task 2021-09-13T20:59:02.991Z] 20:59:02    ERROR -  Traceback (most recent call last):
[task 2021-09-13T20:59:02.992Z] 20:59:02     INFO -    File "C:\Users\task_1631556402\build\tests\talos\talos\run_tests.py", line 336, in run_tests
[task 2021-09-13T20:59:02.992Z] 20:59:02     INFO -      talos_results.add(mytest.runTest(browser_config, test))
[task 2021-09-13T20:59:02.992Z] 20:59:02     INFO -    File "C:\Users\task_1631556402\build\tests\talos\talos\ttest.py", line 65, in runTest
[task 2021-09-13T20:59:02.992Z] 20:59:02     INFO -      return self._runTest(browser_config, test_config, setup)
[task 2021-09-13T20:59:02.993Z] 20:59:02     INFO -    File "C:\Users\task_1631556402\build\tests\talos\talos\ttest.py", line 215, in _runTest
[task 2021-09-13T20:59:02.993Z] 20:59:02     INFO -      debugger_args=browser_config["debugger_args"],
[task 2021-09-13T20:59:02.993Z] 20:59:02     INFO -    File "C:\Users\task_1631556402\build\tests\talos\talos\talos_process.py", line 191, in run_browser
[task 2021-09-13T20:59:02.994Z] 20:59:02     INFO -      raise TalosError("unexpected error")
[task 2021-09-13T20:59:02.994Z] 20:59:02     INFO -  talos.utils.TalosError: unexpected error
[task 2021-09-13T20:59:02.994Z] 20:59:02     INFO -  TEST-INFO took 1865813ms
[task 2021-09-13T20:59:02.994Z] 20:59:02     INFO -  SUITE-END | took 1865s
[task 2021-09-13T20:59:03.201Z] 20:59:03     INFO -  Exception ignored in: <bound method ProcessHandlerMixin.Process.__del__ of <mozprocess.processhandler.ProcessHandlerMixin.Process object at 0x0000024C725D22B0>>
[task 2021-09-13T20:59:03.201Z] 20:59:03    ERROR -  Traceback (most recent call last):
[task 2021-09-13T20:59:03.201Z] 20:59:03     INFO -    File "C:\Users\task_1631556402\build\venv\lib\site-packages\mozprocess\processhandler.py", line 192, in __del__
[task 2021-09-13T20:59:03.202Z] 20:59:03     INFO -      self._internal_poll(_deadstate=_maxint)
[task 2021-09-13T20:59:03.202Z] 20:59:03     INFO -    File "c:\mozilla-build\python3\lib\subprocess.py", line 1035, in _internal_poll
[task 2021-09-13T20:59:03.202Z] 20:59:03     INFO -      if _WaitForSingleObject(self._handle, 0) == _WAIT_OBJECT_0:
[task 2021-09-13T20:59:03.203Z] 20:59:03    ERROR -  TypeError: WaitForSingleObject() argument 1 must be int, not AutoHANDLE
[task 2021-09-13T20:59:03.230Z] 20:59:03    ERROR - Return code: 2
[task 2021-09-13T20:59:03.230Z] 20:59:03  WARNING - setting return code to 2
[task 2021-09-13T20:59:03.230Z] 20:59:03    ERROR - # TBPL FAILURE #
[task 2021-09-13T20:59:03.230Z] 20:59:03     INFO - Running post-action listener: _package_coverage_data
[task 2021-09-13T20:59:03.230Z] 20:59:03     INFO - Running post-action listener: _resource_record_post_action
[task 2021-09-13T20:59:03.230Z] 20:59:03     INFO - Running post-action listener: process_java_coverage_data
[task 2021-09-13T20:59:03.231Z] 20:59:03     INFO - [mozharness: 2021-09-13 20:59:03.231358Z] Finished run-tests step (success)
[task 2021-09-13T20:59:03.231Z] 20:59:03     INFO - Running post-run listener: _resource_record_post_run
[task 2021-09-13T20:59:03.378Z] 20:59:03     INFO - Total resource usage - Wall time: 1868s; CPU: 19%; Read bytes: 141404160; Write bytes: 1732051456; Read time: 1; Write time: 19
[task 2021-09-13T20:59:03.378Z] 20:59:03     INFO - TinderboxPrint: CPU usage<br/>18.7%
[task 2021-09-13T20:59:03.378Z] 20:59:03     INFO - TinderboxPrint: I/O read bytes / time<br/>141,404,160 / 1
[task 2021-09-13T20:59:03.378Z] 20:59:03     INFO - TinderboxPrint: I/O write bytes / time<br/>1,732,051,456 / 19
[task 2021-09-13T20:59:03.378Z] 20:59:03     INFO - TinderboxPrint: CPU idle<br/>12,141.9 (81.2%)
[task 2021-09-13T20:59:03.378Z] 20:59:03     INFO - TinderboxPrint: CPU system<br/>401.8 (2.7%)
[task 2021-09-13T20:59:03.378Z] 20:59:03     INFO - TinderboxPrint: CPU user<br/>2,396.5 (16.0%)
[task 2021-09-13T20:59:03.378Z] 20:59:03     INFO - install - Wall time: 2s; CPU: 13%; Read bytes: 98304; Write bytes: 1568768; Read time: 0; Write time: 0
[task 2021-09-13T20:59:03.393Z] 20:59:03     INFO - run-tests - Wall time: 1867s; CPU: 19%; Read bytes: 141244928; Write bytes: 1722094080; Read time: 1; Write time: 19
[task 2021-09-13T20:59:03.889Z] 20:59:03  WARNING - returning nonzero exit status 2
[taskcluster 2021-09-13T20:59:03.939Z]    Exit Code: 2
[taskcluster 2021-09-13T20:59:03.939Z]    User Time: 0s
[taskcluster 2021-09-13T20:59:03.939Z]  Kernel Time: 0s
[taskcluster 2021-09-13T20:59:03.939Z]    Wall Time: 32m29.1207992s
[taskcluster 2021-09-13T20:59:03.939Z]       Result: FAILED
[taskcluster 2021-09-13T20:59:03.939Z] === Task Finished ===
[taskcluster 2021-09-13T20:59:03.939Z] Task Duration: 32m29.1207992s
[taskcluster 2021-09-13T20:59:04.018Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2022-09-13T19:01:06.349Z
[taskcluster 2021-09-13T20:59:04.216Z] 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-13T19:01:06.349Z
[taskcluster 2021-09-13T20:59:04.323Z] 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-13T19:01:06.349Z
[taskcluster 2021-09-13T20:59:04.527Z] 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-13T19:01:06.349Z
[taskcluster 2021-09-13T20:59:04.756Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/G2_JDod7R6mqCgey7STeLQ/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2022-09-13T19:01:06.349Z
[taskcluster:error] exit status 2

Comment 1

[Intermittent Failures Robot]

3 failures in 3375 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 3

Platform and build breakdown:

• windows10-32-shippable-qr: 2
  • opt: 2
• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1730605&startday=2021-09-13&endday=2021-09-19&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1730605&startday=2021-09-27&endday=2021-10-03&tree=all

Comment 3

[Intermittent Failures Robot]

2 failures in 3626 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 2

Platform and build breakdown:

• windows10-64-shippable-qr: 2
  • opt: 2

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

Comment 4

[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-beta: 1

Platform and build breakdown:

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

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

Comment 5

[BugBot [:suhaib / :marco/ :calixte]]

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