Closed Bug 1757155 Opened 4 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /gamepad/gamepad-default-feature-policy.https.sub.html | TestRunner hit external timeout (this may indicate a hang)

Tracking

()

Status:

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Treeherder Bug Filer

Reporter

Description

•

4 years ago

treeherder

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

[task 2022-02-25T11:51:15.915Z] 11:51:15     INFO - TEST-START | /gamepad/gamepad-default-feature-policy.https.sub.html
[task 2022-02-25T11:51:50.916Z] 11:51:50     INFO - Got timeout in harness
[task 2022-02-25T11:51:50.919Z] 11:51:50     INFO - TEST-UNEXPECTED-TIMEOUT | /gamepad/gamepad-default-feature-policy.https.sub.html | TestRunner hit external timeout (this may indicate a hang)
[task 2022-02-25T11:51:50.919Z] 11:51:50     INFO - TEST-INFO took 35005ms
[task 2022-02-25T11:53:01.978Z] 11:53:01     INFO - Browser exited with return code -15
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL - Loading initial page https://web-platform.test:8443/testharness_runner.html failed. Ensure that the there are no other programs bound to this port and that your firewall rules or network setup does not prevent access.
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL - Traceback (most recent call last):
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in load_runner
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 212, in dismiss_alert
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -     f()
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in <lambda>
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1551, in navigate
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2022-02-25T11:53:02.047Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -     m._handle_socket_failure()
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 712, in _handle_socket_failure
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -     reraise(exc_cls, exc, tb)
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -     raise value
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -     return func(*args, **kwargs)
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 661, in _send_message
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -     msg = self.client.request(name, params)
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -     return self.receive()
[task 2022-02-25T11:53:02.048Z] 11:53:02 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2022-02-25T11:53:02.049Z] 11:53:02 CRITICAL -     raise socket.error("No data received over socket")
[task 2022-02-25T11:53:02.049Z] 11:53:02 CRITICAL - OSError: No data received over socket
[task 2022-02-25T11:53:02.049Z] 11:53:02 CRITICAL - 
[task 2022-02-25T11:53:02.051Z] 11:53:02  WARNING - Traceback (most recent call last):
[task 2022-02-25T11:53:02.051Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 297, in run_test
[task 2022-02-25T11:53:02.051Z] 11:53:02  WARNING -     self.on_environment_change(test.environment)
[task 2022-02-25T11:53:02.051Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 877, in on_environment_change
[task 2022-02-25T11:53:02.052Z] 11:53:02  WARNING -     self.protocol.testharness.load_runner(new_environment["protocol"])
[task 2022-02-25T11:53:02.052Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in load_runner
[task 2022-02-25T11:53:02.053Z] 11:53:02  WARNING -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-02-25T11:53:02.053Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 212, in dismiss_alert
[task 2022-02-25T11:53:02.053Z] 11:53:02  WARNING -     f()
[task 2022-02-25T11:53:02.053Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in <lambda>
[task 2022-02-25T11:53:02.053Z] 11:53:02  WARNING -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-02-25T11:53:02.054Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1551, in navigate
[task 2022-02-25T11:53:02.054Z] 11:53:02  WARNING -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2022-02-25T11:53:02.054Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2022-02-25T11:53:02.054Z] 11:53:02  WARNING -     m._handle_socket_failure()
[task 2022-02-25T11:53:02.055Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 712, in _handle_socket_failure
[task 2022-02-25T11:53:02.055Z] 11:53:02  WARNING -     reraise(exc_cls, exc, tb)
[task 2022-02-25T11:53:02.055Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2022-02-25T11:53:02.056Z] 11:53:02  WARNING -     raise value
[task 2022-02-25T11:53:02.056Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-02-25T11:53:02.056Z] 11:53:02  WARNING -     return func(*args, **kwargs)
[task 2022-02-25T11:53:02.057Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 661, in _send_message
[task 2022-02-25T11:53:02.057Z] 11:53:02  WARNING -     msg = self.client.request(name, params)
[task 2022-02-25T11:53:02.057Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2022-02-25T11:53:02.057Z] 11:53:02  WARNING -     return self.receive()
[task 2022-02-25T11:53:02.058Z] 11:53:02  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2022-02-25T11:53:02.058Z] 11:53:02  WARNING -     raise socket.error("No data received over socket")
[task 2022-02-25T11:53:02.058Z] 11:53:02  WARNING - OSError: No data received over socket
[task 2022-02-25T11:53:02.058Z] 11:53:02  WARNING - 
[task 2022-02-25T11:53:02.073Z] 11:53:02  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /gamepad/gamepad-default-feature-policy.https.sub.html>, (<wptrunner.wpttest.TestharnessResult INTERNAL-ERROR>, []))
[task 2022-02-25T11:53:02.074Z] 11:53:02     INFO - Closing logging queue
[task 2022-02-25T11:53:02.074Z] 11:53:02     INFO - queue closed
[task 2022-02-25T11:53:02.077Z] 11:53:02     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpjag1becl
[task 2022-02-25T11:53:02.103Z] 11:53:02     INFO - PID 9499 | [CodeCoverage] Setting handlers for process 9499.
[task 2022-02-25T11:53:02.103Z] 11:53:02     INFO - PID 9499 | 1645789876108	Marionette	INFO	Marionette enabled
[task 2022-02-25T11:53:02.103Z] 11:53:02     INFO - PID 9499 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpgb1dlrm5/search.json.mozlz4", (void 0)))
[task 2022-02-25T11:53:02.103Z] 11:53:02     INFO - PID 9499 | 1645789880882	Marionette	INFO	Listening on port 33737
[task 2022-02-25T11:53:02.103Z] 11:53:02     INFO - Starting runner
[task 2022-02-25T11:53:03.312Z] 11:53:03     INFO - PID 9499 | [CodeCoverage] Setting handlers for process 9606.
[task 2022-02-25T11:53:03.336Z] 11:53:03     INFO - PID 9499 | [CodeCoverage] Setting handlers for process 9603.
[task 2022-02-25T11:53:03.768Z] 11:53:03     INFO - PID 9499 | JavaScript error: resource://gre/modules/URLQueryStrippingListService.jsm, line 90: TypeError: can't access property "removeEventListener", Services.cpmm.sharedData is null
[task 2022-02-25T11:53:03.772Z] 11:53:03     INFO - PID 9499 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-02-25T11:53:04.666Z] 11:53:04     INFO - PID 9499 | [CodeCoverage] Setting handlers for process 9729.
[task 2022-02-25T11:53:04.982Z] 11:53:04     INFO - TEST-START | /gamepad/gamepad-secure-context.html

Comment hidden (Intermittent Failures Robot)

BugBot [:suhaib / :marco/ :calixte]

Comment 2

•

3 years ago

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

Status: NEW → RESOLVED

Closed: 3 years ago

Resolution: --- → INCOMPLETE

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Intermittent TEST-UNEXPECTED-TIMEOUT | /gamepad/gamepad-default-feature-policy.https.sub.html | TestRunner hit external timeout (this may indicate a hang)

Categories

(Core :: DOM: Device Interfaces, defect, P5)

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Comment 1

Comment 2