Closed Bug 1610432 Opened 5 years ago Closed 5 years ago

Perma [TCw] TEST-UNEXPECTED-TIMEOUT | /2dcontext/drawing-text-to-the-canvas/2d.text.measure.actualBoundingBox.html | Executor hit external timeout (this may indicate a hang)

Categories

(Core :: Graphics: Canvas2D, defect, P5)

Product:
Core
Component:
Graphics: Canvas2D
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=285722620&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WszveWXkSFm-qE4LY-af2A/runs/0/artifacts/public/logs/live_backing.log

[task 2020-01-21T05:54:52.609Z] 05:54:52 INFO - TEST-START | /2dcontext/drawing-text-to-the-canvas/2d.text.measure.actualBoundingBox.html
[task 2020-01-21T05:54:52.649Z] 05:54:52 INFO - PID 3400 | [CodeCoverage] Requested flush for 4624.
[task 2020-01-21T05:54:54.112Z] 05:54:54 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:54:54.373Z] 05:54:54 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:54:54.373Z] 05:54:54 INFO - PID 3400 | [CodeCoverage] Requested flush for 7876.
[task 2020-01-21T05:54:54.373Z] 05:54:54 INFO - PID 3400 | [CodeCoverage] Requested flush for 7008.
[task 2020-01-21T05:54:54.373Z] 05:54:54 INFO - PID 3400 | [CodeCoverage] Requested flush for 456.
[task 2020-01-21T05:54:57.995Z] 05:54:57 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:54:58.014Z] 05:54:58 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:55:01.283Z] 05:55:01 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:55:01.313Z] 05:55:01 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:55:04.528Z] 05:55:04 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:55:04.578Z] 05:55:04 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:55:06.036Z] 05:55:06 INFO - PID 3400 | [CodeCoverage] Requested flush for 4624.
[task 2020-01-21T05:55:07.495Z] 05:55:07 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:55:07.697Z] 05:55:07 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:55:07.697Z] 05:55:07 INFO - PID 3400 | [CodeCoverage] Requested flush for 7876.
[task 2020-01-21T05:55:07.697Z] 05:55:07 INFO - PID 3400 | [CodeCoverage] Requested flush for 7008.
[task 2020-01-21T05:55:07.697Z] 05:55:07 INFO - PID 3400 | [CodeCoverage] Requested flush for 456.
[task 2020-01-21T05:55:07.697Z] 05:55:07 INFO - PID 3400 | [CodeCoverage] Requested flush for 1332.
[task 2020-01-21T05:55:11.603Z] 05:55:11 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:55:11.633Z] 05:55:11 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:55:12.628Z] 05:55:12 INFO - TEST-UNEXPECTED-TIMEOUT | /2dcontext/drawing-text-to-the-canvas/2d.text.measure.actualBoundingBox.html | Executor hit external timeout (this may indicate a hang)
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 774, in __bootstrap
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - self.__bootstrap_inner()
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 801, in __bootstrap_inner
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - self.run()
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 754, in run
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - self.__target(*self.__args, **self.__kwargs)
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "Z:\task_1579583045\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 642, in run_func
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "Z:\task_1579583045\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 762, in do_testharness
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - self.protocol.coverage.dump()
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "Z:\task_1579583045\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 484, in dump
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - error = self.marionette.execute_async_script(script)
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1656, in execute_async_script
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - rv = self._send_message("WebDriver:ExecuteAsyncScript", body, key="value")
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - return func(*args, **kwargs)
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - msg = self.client.request(name, params)
[task 2020-01-21T05:55:12.629Z] 05:55:12 INFO - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
[task 2020-01-21T05:55:12.630Z] 05:55:12 INFO - return self.receive()
[task 2020-01-21T05:55:12.630Z] 05:55:12 INFO - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\transport.py", line 153, in receive
[task 2020-01-21T05:55:12.630Z] 05:55:12 INFO - chunk = self._sock.recv(bytes_to_recv)
[task 2020-01-21T05:55:12.630Z] 05:55:12 INFO - TEST-INFO took 20018ms
[task 2020-01-21T05:55:12.630Z] 05:55:12 INFO - No more tests
[task 2020-01-21T05:55:14.866Z] 05:55:14 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:55:14.884Z] 05:55:14 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:55:18.053Z] 05:55:18 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:55:18.092Z] 05:55:18 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:55:21.378Z] 05:55:21 INFO - PID 3400 | [CodeCoverage] flush completed.
[task 2020-01-21T05:55:21.396Z] 05:55:21 INFO - PID 3400 | [CodeCoverage] JS flush completed.
[task 2020-01-21T05:56:23.705Z] 05:56:23 INFO - Browser exited with return code 572
[task 2020-01-21T05:56:23.705Z] 05:56:23 INFO - PROCESS LEAKS None
[task 2020-01-21T05:56:23.705Z] 05:56:23 INFO - PROCESS LEAKS None
[task 2020-01-21T05:56:23.705Z] 05:56:23 INFO - Closing logging queue
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - Traceback (most recent call last):
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 642, in run_func
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 762, in do_testharness
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - self.protocol.coverage.dump()
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 489, in dump
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - pass
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "c:\mozilla-build\python\Lib\contextlib.py", line 35, in exit
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - self.gen.throw(type, value, traceback)
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1332, in using_context
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - self.set_context(scope)
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1309, in set_context
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - {"value": context})
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - return func(*args, **kwargs)
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - msg = self.client.request(name, params)
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - return self.receive()
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - File "Z:\task_1579583045\build\venv\lib\site-packages\marionette_driver\transport.py", line 166, in receive
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - if len(remaining) == int(length):
[task 2020-01-21T05:56:23.706Z] 05:56:23 WARNING - ValueError: invalid literal for int() with base 10: 'script timeout","message"'

Regressed by: 1102584
Has Regression Range: --- → yes
Keywords: regression

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.