1719006 - Intermittent TEST-UNEXPECTED-TIMEOUT | [tier 2] /WebCryptoAPI/derive_bits_keys/ecdh_keys.https.any.html | Executor hit external timeout (this may indicate a hang)

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

[task 2021-07-02T23:18:06.845Z] 23:18:06     INFO - TEST-START | /beacon/headers/header-referrer-unsafe-url.https.html
[task 2021-07-02T23:18:06.849Z] 23:18:06     INFO - Closing window 3cc7530e-5459-46fc-a831-a42f728c7234
[task 2021-07-02T23:18:07.208Z] 23:18:07     INFO - ..
[task 2021-07-02T23:18:07.208Z] 23:18:07     INFO - TEST-OK | /beacon/headers/header-referrer-unsafe-url.https.html | took 363ms
[task 2021-07-02T23:18:07.208Z] 23:18:07     INFO - Restarting browser for new test group
[task 2021-07-02T23:18:07.212Z] 23:18:07     INFO - PID 2019 | 1625267887210	Marionette	INFO	Stopped listening on port 57538
[task 2021-07-02T23:18:07.332Z] 23:18:07     INFO - PID 2019 | JavaScript error: resource:///modules/Interactions.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2021-07-02T23:18:07.815Z] 23:18:07     INFO - Browser exited with return code 0
[task 2021-07-02T23:18:07.818Z] 23:18:07     INFO - Closing logging queue
[task 2021-07-02T23:18:07.818Z] 23:18:07     INFO - queue closed
[task 2021-07-02T23:18:07.840Z] 23:18:07     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpyscu931l
[task 2021-07-02T23:18:07.847Z] 23:18:07     INFO - Starting runner
[task 2021-07-02T23:18:07.934Z] 23:18:07     INFO - PID 2321 | Gtk-Message: 23:18:07.932: Failed to load module "canberra-gtk-module"
[task 2021-07-02T23:18:07.936Z] 23:18:07     INFO - PID 2321 | Gtk-Message: 23:18:07.935: Failed to load module "canberra-gtk-module"
[task 2021-07-02T23:18:08.328Z] 23:18:08     INFO - PID 2321 | [GFX1-]: glxtest: libpci missing
[task 2021-07-02T23:18:08.328Z] 23:18:08     INFO - PID 2321 | [GFX1-]: glxtest: libEGL missing
[task 2021-07-02T23:18:08.329Z] 23:18:08     INFO - PID 2321 | [GFX1-]: glxtest: libGL.so.1 missing
[task 2021-07-02T23:18:08.330Z] 23:18:08     INFO - PID 2321 | [GFX1-]: glxtest: libEGL missing
[task 2021-07-02T23:18:08.330Z] 23:18:08     INFO - PID 2321 | [GFX1-]: No GPUs detected via PCI
[task 2021-07-02T23:18:08.385Z] 23:18:08     INFO - PID 2321 | 1625267888383	Marionette	INFO	Marionette enabled
[task 2021-07-02T23:18:09.114Z] 23:18:09     INFO - PID 2321 | Gtk-Message: 23:18:09.112: Failed to load module "canberra-gtk-module"
[task 2021-07-02T23:18:09.116Z] 23:18:09     INFO - PID 2321 | Gtk-Message: 23:18:09.115: Failed to load module "canberra-gtk-module"
[task 2021-07-02T23:18:09.220Z] 23:18:09     INFO - PID 2321 | Gtk-Message: 23:18:09.219: Failed to load module "canberra-gtk-module"
[task 2021-07-02T23:18:09.222Z] 23:18:09     INFO - PID 2321 | Gtk-Message: 23:18:09.221: Failed to load module "canberra-gtk-module"
[task 2021-07-02T23:18:10.221Z] 23:18:10     INFO - TEST-UNEXPECTED-TIMEOUT | /WebCryptoAPI/derive_bits_keys/ecdh_keys.https.any.html | Executor hit external timeout (this may indicate a hang)
[task 2021-07-02T23:18:10.221Z] 23:18:10     INFO -   File "/usr/lib/python3.6/threading.py", line 884, in _bootstrap
[task 2021-07-02T23:18:10.221Z] 23:18:10     INFO -     self._bootstrap_inner()
[task 2021-07-02T23:18:10.221Z] 23:18:10     INFO -   File "/usr/lib/python3.6/threading.py", line 916, in _bootstrap_inner
[task 2021-07-02T23:18:10.222Z] 23:18:10     INFO -     self.run()
[task 2021-07-02T23:18:10.222Z] 23:18:10     INFO -   File "/usr/lib/python3.6/threading.py", line 864, in run
[task 2021-07-02T23:18:10.222Z] 23:18:10     INFO -     self._target(*self._args, **self._kwargs)
[task 2021-07-02T23:18:10.222Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 790, in run_func
[task 2021-07-02T23:18:10.222Z] 23:18:10     INFO -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2021-07-02T23:18:10.222Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 905, in do_testharness
[task 2021-07-02T23:18:10.222Z] 23:18:10     INFO -     protocol.testharness.test_window_loaded()
[task 2021-07-02T23:18:10.223Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 265, in test_window_loaded
[task 2021-07-02T23:18:10.223Z] 23:18:10     INFO -     self.parent.base.execute_script(self.window_loaded_script, asynchronous=True)
[task 2021-07-02T23:18:10.223Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 83, in execute_script
[task 2021-07-02T23:18:10.223Z] 23:18:10     INFO -     return method(script, new_sandbox=False, sandbox=None)
[task 2021-07-02T23:18:10.224Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1729, in execute_async_script
[task 2021-07-02T23:18:10.224Z] 23:18:10     INFO -     rv = self._send_message("WebDriver:ExecuteAsyncScript", body, key="value")
[task 2021-07-02T23:18:10.224Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-07-02T23:18:10.224Z] 23:18:10     INFO -     return func(*args, **kwargs)
[task 2021-07-02T23:18:10.224Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 621, in _send_message
[task 2021-07-02T23:18:10.224Z] 23:18:10     INFO -     msg = self.client.request(name, params)
[task 2021-07-02T23:18:10.224Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 292, in request
[task 2021-07-02T23:18:10.224Z] 23:18:10     INFO -     return self.receive()
[task 2021-07-02T23:18:10.225Z] 23:18:10     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 165, in receive
[task 2021-07-02T23:18:10.225Z] 23:18:10     INFO -     chunk = self._sock.recv(bytes_to_recv)
[task 2021-07-02T23:18:10.225Z] 23:18:10     INFO - TEST-INFO took 20012ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-32-shippable: 1
  • opt: 1

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

Comment 2

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

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