Closed Bug 1614032 Opened 6 years ago Closed 5 years ago

Intermittent [TCw] TEST-UNEXPECTED-TIMEOUT | /web-animations/timing-model/animations/playing-an-animation.html | Executor hit external timeout (this may indicate a hang)

Categories

(Core :: DOM: Animation, defect, P5)

Product:
Core
Component:
DOM: Animation
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

[task 2020-02-07T14:52:46.671Z] 14:52:46 INFO - TEST-START | /web-animations/timing-model/animations/playing-an-animation.html
[task 2020-02-07T14:52:46.691Z] 14:52:46 INFO - Setting pref layout.css.marker.restricted (false)
[task 2020-02-07T14:52:46.730Z] 14:52:46 INFO - Setting pref dom.animations-api.core.enabled (true)
[task 2020-02-07T14:52:46.748Z] 14:52:46 INFO - Setting pref layout.css.step-position-jump.enabled (true)
[task 2020-02-07T14:52:46.786Z] 14:52:46 INFO - Setting pref dom.animations-api.implicit-keyframes.enabled (true)
[task 2020-02-07T14:52:46.807Z] 14:52:46 INFO - Setting pref privacy.reduceTimerPrecision (false)
[task 2020-02-07T14:52:46.846Z] 14:52:46 INFO - Setting pref dom.animations-api.getAnimations.enabled (true)
[task 2020-02-07T14:52:46.864Z] 14:52:46 INFO - Setting pref dom.animations-api.compositing.enabled (true)
[task 2020-02-07T14:52:46.887Z] 14:52:46 INFO - Setting pref dom.animations-api.autoremove.enabled (true)
[task 2020-02-07T14:52:46.927Z] 14:52:46 INFO - Setting pref dom.animations-api.timelines.enabled (true)
[task 2020-02-07T14:52:46.948Z] 14:52:46 INFO - PID 4444 | [CodeCoverage] Requested flush for 9312.
[task 2020-02-07T14:52:48.737Z] 14:52:48 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:52:49.060Z] 14:52:49 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:52:49.060Z] 14:52:49 INFO - PID 4444 | [CodeCoverage] Requested flush for 5944.
[task 2020-02-07T14:52:49.060Z] 14:52:49 INFO - PID 4444 | [CodeCoverage] Requested flush for 660.
[task 2020-02-07T14:52:49.060Z] 14:52:49 INFO - PID 4444 | [CodeCoverage] Requested flush for 4292.
[task 2020-02-07T14:52:53.482Z] 14:52:53 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:52:53.501Z] 14:52:53 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:52:57.522Z] 14:52:57 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:52:57.540Z] 14:52:57 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:53:01.505Z] 14:53:01 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:53:01.557Z] 14:53:01 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:53:02.756Z] 14:53:02 INFO - PID 4444 | [CodeCoverage] Requested flush for 9312.
[task 2020-02-07T14:53:05.033Z] 14:53:05 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:53:05.276Z] 14:53:05 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:53:05.276Z] 14:53:05 INFO - PID 4444 | [CodeCoverage] Requested flush for 5944.
[task 2020-02-07T14:53:05.276Z] 14:53:05 INFO - PID 4444 | [CodeCoverage] Requested flush for 660.
[task 2020-02-07T14:53:05.276Z] 14:53:05 INFO - PID 4444 | [CodeCoverage] Requested flush for 4292.
[task 2020-02-07T14:53:05.277Z] 14:53:05 INFO - PID 4444 | [CodeCoverage] Requested flush for 7624.
[task 2020-02-07T14:53:10.212Z] 14:53:10 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:53:10.241Z] 14:53:10 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:53:14.105Z] 14:53:14 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:53:14.131Z] 14:53:14 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - TEST-UNEXPECTED-TIMEOUT | /web-animations/timing-model/animations/playing-an-animation.html | Executor hit external timeout (this may indicate a hang)
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 774, in __bootstrap
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - self.__bootstrap_inner()
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 801, in __bootstrap_inner
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - self.run()
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 754, in run
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - self.__target(*self.__args, **self.__kwargs)
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - File "Z:\task_1581085585\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 642, in run_func
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - File "Z:\task_1581085585\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 762, in do_testharness
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - self.protocol.coverage.dump()
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - File "Z:\task_1581085585\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 484, in dump
[task 2020-02-07T14:53:16.942Z] 14:53:16 INFO - error = self.marionette.execute_async_script(script)
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - File "Z:\task_1581085585\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1656, in execute_async_script
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - rv = self._send_message("WebDriver:ExecuteAsyncScript", body, key="value")
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - File "Z:\task_1581085585\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - return func(*args, **kwargs)
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - File "Z:\task_1581085585\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - msg = self.client.request(name, params)
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - File "Z:\task_1581085585\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - return self.receive()
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - File "Z:\task_1581085585\build\venv\lib\site-packages\marionette_driver\transport.py", line 153, in receive
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - chunk = self._sock.recv(bytes_to_recv)
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - TEST-INFO took 30270ms
[task 2020-02-07T14:53:16.943Z] 14:53:16 INFO - No more tests
[task 2020-02-07T14:53:18.203Z] 14:53:18 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:53:18.246Z] 14:53:18 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:53:22.398Z] 14:53:22 INFO - PID 4444 | [CodeCoverage] flush completed.
[task 2020-02-07T14:53:22.416Z] 14:53:22 INFO - PID 4444 | [CodeCoverage] JS flush completed.
[task 2020-02-07T14:54:28.074Z] 14:54:28 INFO - Browser exited with return code 572
[task 2020-02-07T14:54:28.074Z] 14:54:28 INFO - PROCESS LEAKS None
[task 2020-02-07T14:54:28.074Z] 14:54:28 INFO - PROCESS LEAKS None
[task 2020-02-07T14:54:28.075Z] 14:54:28 INFO - Closing logging queue
[task 2020-02-07T14:54:28.075Z] 14:54:28 INFO - queue closed
[task 2020-02-07T14:54:28.075Z] 14:54:28 INFO - PROCESS LEAKS None
[task 2020-02-07T14:54:28.076Z] 14:54:28 INFO - Got 1 unexpected results
[task 2020-02-07T14:54:28.076Z] 14:54:28 INFO - SUITE-END | took 111s
[task 2020-02-07T14:54:28.098Z] 14:54:28 INFO - Removed font: Ahem.ttf
[task 2020-02-07T14:54:28.117Z] 14:54:28 INFO - Closing logging queue
[task 2020-02-07T14:54:28.117Z] 14:54:28 INFO - queue closed
[task 2020-02-07T14:54:28.387Z] 14:54:28 ERROR - Return code: 1

See Also: → 1612639
Summary: Intermittent TEST-UNEXPECTED-TIMEOUT | /web-animations/timing-model/animations/playing-an-animation.html | Executor hit external timeout (this may indicate a hang) → Intermittent [TCw] TEST-UNEXPECTED-TIMEOUT | /web-animations/timing-model/animations/playing-an-animation.html | Executor hit external timeout (this may indicate a hang)

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.