1731173 - Intermittent [Tier 2] TEST-UNEXPECTED-TIMEOUT | /WebCryptoAPI/digest/digest.https.any.html | TestRunner hit external timeout (this may indicate a hang) | Test harness output was not a valid structured log message

Status: RESOLVED INCOMPLETE

(Testing :: web-platform-tests, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-09-16T22:50:39.469Z] 22:50:39     INFO - TEST-START | /WebCryptoAPI/digest/digest.https.any.html
[task 2021-09-16T22:50:40.895Z] 22:50:40     INFO - PID 1269 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-09-16T22:50:40.896Z] 22:50:40     INFO - PID 1269 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-09-16T22:52:54.472Z] 22:52:54     INFO - Got timeout in harness
[task 2021-09-16T22:52:54.474Z] 22:52:54     INFO - TEST-UNEXPECTED-TIMEOUT | /WebCryptoAPI/digest/digest.https.any.html | TestRunner hit external timeout (this may indicate a hang)
[task 2021-09-16T22:52:54.474Z] 22:52:54     INFO - TEST-INFO took 135007ms
[task 2021-09-16T22:54:05.522Z] 22:54:05     INFO - Browser exited with return code -15
[task 2021-09-16T22:54:15.528Z] 22:54:15  WARNING - Forcibly terminating runner process
[task 2021-09-16T22:54:15.532Z] 22:54:15 CRITICAL - Test harness output was not a valid structured log message: 
[task 2021-09-16T22:54:15.532Z] 22:54:15 CRITICAL - /usr/lib/python3.6/multiprocessing/semaphore_tracker.py:143: UserWarning: semaphore_tracker: There appear to be 3 leaked semaphores to clean up at shutdown
[task 2021-09-16T22:54:15.532Z] 22:54:15 CRITICAL -   len(cache))
[task 2021-09-16T22:54:15.548Z] 22:54:15     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpg16482cy
[task 2021-09-16T22:54:15.554Z] 22:54:15     INFO - PID 1273 | [CodeCoverage] Setting handlers for process 1273.
[task 2021-09-16T22:54:15.555Z] 22:54:15     INFO - PID 1273 | 1631832632390	Marionette	INFO	Marionette enabled
[task 2021-09-16T22:54:15.556Z] 22:54:15     INFO - PID 1273 | [CodeCoverage] Setting handlers for process 1458.
[task 2021-09-16T22:54:15.556Z] 22:54:15     INFO - PID 1273 | [CodeCoverage] Setting handlers for process 1462.
[task 2021-09-16T22:54:15.556Z] 22:54:15     INFO - PID 1273 | [CodeCoverage] Setting handlers for process 1554.
[task 2021-09-16T22:54:15.556Z] 22:54:15     INFO - PID 1273 | [CodeCoverage] Setting handlers for process 1561.
[task 2021-09-16T22:54:15.557Z] 22:54:15     INFO - PID 1273 | [CodeCoverage] Setting handlers for process 1578.
[task 2021-09-16T22:54:15.557Z] 22:54:15     INFO - PID 1273 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp_t3qkqgm/search.json.mozlz4", (void 0)))
[task 2021-09-16T22:54:15.558Z] 22:54:15     INFO - PID 1273 | 1631832639261	Marionette	INFO	Listening on port 39371
[task 2021-09-16T22:54:15.558Z] 22:54:15     INFO - Starting runner
[task 2021-09-16T22:54:16.421Z] 22:54:16     INFO - TEST-START | /WebCryptoAPI/digest/digest.https.any.worker.html

Comment 1

[Intermittent Failures Robot]

1 failures in 3375 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-ccov-qr: 1
  • opt: 1

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

Comment 2

[Intermittent Failures Robot]

1 failures in 2873 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-ccov-qr: 1
  • opt: 1

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

Comment 3

[Intermittent Failures Robot]

1 failures in 3376 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-ccov-qr: 1
  • opt: 1

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

Comment 4

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

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