1602188 - Intermittent TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/interfaces/TextTrack/addCue.html | Executor hit external timeout (this may indicate a hang)

Status: RESOLVED INCOMPLETE

(Core :: DOM: Core & HTML, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2019-12-06T17:11:17.808Z] 17:11:17 INFO - TEST-START | /html/semantics/embedded-content/media-elements/interfaces/TextTrack/addCue.html
[task 2019-12-06T17:11:17.812Z] 17:11:17 INFO - Closing window 27
[task 2019-12-06T17:11:17.840Z] 17:11:17 INFO - PID 582 | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 16: TypeError: browser is null
[task 2019-12-06T17:11:17.840Z] 17:11:17 INFO - PID 582 | JavaScript error: , line 0: NS_ERROR_UNEXPECTED:
[task 2019-12-06T17:11:37.812Z] 17:11:37 INFO - TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/interfaces/TextTrack/addCue.html | Executor hit external timeout (this may indicate a hang)
[task 2019-12-06T17:11:37.812Z] 17:11:37 INFO - File "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
[task 2019-12-06T17:11:37.812Z] 17:11:37 INFO - self.__bootstrap_inner()
[task 2019-12-06T17:11:37.812Z] 17:11:37 INFO - File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
[task 2019-12-06T17:11:37.812Z] 17:11:37 INFO - self.run()
[task 2019-12-06T17:11:37.812Z] 17:11:37 INFO - File "/usr/lib/python2.7/threading.py", line 1071, in run
[task 2019-12-06T17:11:37.812Z] 17:11:37 INFO - self.finished.wait(self.interval)
[task 2019-12-06T17:11:37.812Z] 17:11:37 INFO - File "/usr/lib/python2.7/threading.py", line 614, in wait
[task 2019-12-06T17:11:37.813Z] 17:11:37 INFO - self.__cond.wait(timeout)
[task 2019-12-06T17:11:37.813Z] 17:11:37 INFO - File "/usr/lib/python2.7/threading.py", line 359, in wait
[task 2019-12-06T17:11:37.813Z] 17:11:37 INFO - _sleep(delay)
[task 2019-12-06T17:11:37.813Z] 17:11:37 INFO - TEST-INFO took 20004ms
[task 2019-12-06T17:12:48.857Z] 17:12:48 INFO - Browser exited with return code -15
[task 2019-12-06T17:12:48.857Z] 17:12:48 INFO - PROCESS LEAKS None
[task 2019-12-06T17:12:48.857Z] 17:12:48 INFO - PROCESS LEAKS None
[task 2019-12-06T17:12:48.858Z] 17:12:48 INFO - Closing logging queue
[task 2019-12-06T17:12:48.859Z] 17:12:48 WARNING - Traceback (most recent call last):
[task 2019-12-06T17:12:48.859Z] 17:12:48 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 594, in run_func
[task 2019-12-06T17:12:48.859Z] 17:12:48 WARNING - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2019-12-06T17:12:48.859Z] 17:12:48 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 699, in do_testharness
[task 2019-12-06T17:12:48.859Z] 17:12:48 WARNING - timeout=10*self.timeout_multiplier)
[task 2019-12-06T17:12:48.860Z] 17:12:48 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 213, in get_test_window
[task 2019-12-06T17:12:48.860Z] 17:12:48 WARNING - handles = self.marionette.window_handles
[task 2019-12-06T17:12:48.860Z] 17:12:48 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1246, in window_handles
[task 2019-12-06T17:12:48.860Z] 17:12:48 WARNING - return self._send_message("WebDriver:GetWindowHandles")
[task 2019-12-06T17:12:48.860Z] 17:12:48 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING - return func(*args, *kwargs)
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 593, in _send_message
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING - self.delete_session(send_request=False)
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1158, in delete_session
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING - self.client.close()
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 293, in close
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING - self._sock.close()
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING - AttributeError: 'NoneType' object has no attribute 'close'
[task 2019-12-06T17:12:48.861Z] 17:12:48 WARNING -
[task 2019-12-06T17:12:48.861Z] 17:12:48 INFO - queue closed
[task 2019-12-06T17:12:48.884Z] 17:12:48 INFO - Setting up ssl
[task 2019-12-06T17:12:48.919Z] 17:12:48 INFO - certutil |
[task 2019-12-06T17:12:48.943Z] 17:12:48 INFO - certutil |
[task 2019-12-06T17:12:48.964Z] 17:12:48 INFO - certutil |
[task 2019-12-06T17:12:48.964Z] 17:12:48 INFO - Certificate Nickname Trust Attributes
[task 2019-12-06T17:12:48.964Z] 17:12:48 INFO - SSL,S/MIME,JAR/XPI
[task 2019-12-06T17:12:48.964Z] 17:12:48 INFO -
[task 2019-12-06T17:12:48.964Z] 17:12:48 INFO - web-platform-tests CT,,
[task 2019-12-06T17:12:48.964Z] 17:12:48 INFO -
[task 2019-12-06T17:12:48.980Z] 17:12:48 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpXiOMp8.mozrunner
[task 2019-12-06T17:12:48.996Z] 17:12:48 INFO - Starting runner
[task 2019-12-06T17:12:49.439Z] 17:12:49 INFO - PID 976 | 1575652369432 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: networkStatus
[task 2019-12-06T17:12:50.023Z] 17:12:50 INFO - PID 976 | 1575652370014 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-12-06T17:12:50.023Z] 17:12:50 INFO - PID 976 | 1575652370015 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
[task 2019-12-06T17:12:50.024Z] 17:12:50 INFO - PID 976 | 1575652370015 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-12-06T17:12:50.024Z] 17:12:50 INFO - PID 976 | 1575652370015 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader
[task 2019-12-06T17:12:56.717Z] 17:12:56 INFO - PID 976 | 1575652376714 Marionette INFO Listening on port 40965

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1602188&startday=2019-12-02&endday=2019-12-08&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1602188&startday=2019-12-16&endday=2019-12-22&tree=all

Comment 3

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

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

Comment 4

[Bogdan Tara[:bogdan_tara | bogdant]]

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=288397210&repo=mozilla-central&lineNumber=4552

[task 2020-02-11T18:34:55.719Z] 18:34:55 INFO - TEST-START | /html/semantics/embedded-content/media-elements/interfaces/TextTrack/addCue.html
[task 2020-02-11T18:34:55.728Z] 18:34:55 INFO - Closing window 29
[task 2020-02-11T18:34:55.778Z] 18:34:55 INFO - PID 1208 | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 19: TypeError: can't access property "audioPlaybackStopped", browser is null
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/interfaces/TextTrack/addCue.html | Executor hit external timeout (this may indicate a hang)
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 774, in __bootstrap
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - self.__bootstrap_inner()
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 801, in __bootstrap_inner
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - self.run()
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 754, in run
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - self.__target(self.__args, **self.__kwargs)
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - File "Z:\task_1581444882\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 642, in run_func
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - File "Z:\task_1581444882\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 747, in do_testharness
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - timeout=10self.timeout_multiplier)
[task 2020-02-11T18:35:15.726Z] 18:35:15 INFO - File "Z:\task_1581444882\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 220, in get_test_window
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - handles = self.marionette.window_handles
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - File "Z:\task_1581444882\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1246, in window_handles
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - return self._send_message("WebDriver:GetWindowHandles")
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - File "Z:\task_1581444882\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - return func(*args, **kwargs)
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - File "Z:\task_1581444882\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - msg = self.client.request(name, params)
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - File "Z:\task_1581444882\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - return self.receive()
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - File "Z:\task_1581444882\build\venv\lib\site-packages\marionette_driver\transport.py", line 153, in receive
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - chunk = self._sock.recv(bytes_to_recv)
[task 2020-02-11T18:35:15.727Z] 18:35:15 INFO - TEST-INFO took 20007ms

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1602188&startday=2020-02-10&endday=2020-02-16&tree=all

Comment 6

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

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• linux1804-64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1602188&startday=2020-03-09&endday=2020-03-15&tree=all