Closed Bug 1601964 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCDTMFSender-ontonechange-long.https.html | TestRunner hit external timeout (this may indicate a hang)

Categories

(Core :: WebRTC, defect, P5)

Product:
Core
Component:
WebRTC
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2019-12-06T12:32:20.450Z] 12:32:20 INFO - PID 7660 | (ice/INFO) ICE-PEER(PC:1575635540187000 (id=17179869186 url=https://web-platform.test:8443/webrtc/RTCDTMFSender-insertDTMF.https.html):default)/CAND-PAIR(a+gi): setting pair to state SUCCEEDED: a+gi|IP4:10.145.47.248:57508/UDP|IP4:10.145.47.248:57505/UDP(host(IP4:10.145.47.248:57508/UDP)|candidate:0 1 UDP 2122252543 10.145.47.248 57505 typ host)
[task 2019-12-06T12:32:20.450Z] 12:32:20 INFO - PID 7660 | [Child 11156: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2019-12-06T12:32:20.450Z] 12:32:20 INFO - PID 7660 | [Child 11156: Socket Thread]: D/mtransport NrIceCtx(PC:1575635540169000 (id=17179869186 url=https://web-platform.test:8443/webrtc/RTCDTMFSender-insertDTMF.https.html)): state 1->2
[task 2019-12-06T12:35:35.365Z] 12:35:35 INFO - Got timeout in harness
[task 2019-12-06T12:35:35.365Z] 12:35:35 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCDTMFSender-ontonechange-long.https.html | TestRunner hit external timeout (this may indicate a hang)
[task 2019-12-06T12:35:35.365Z] 12:35:35 INFO - TEST-INFO took 195001ms
[task 2019-12-06T12:38:20.476Z] 12:38:20 INFO - IOError on command, setting status to CRASH
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - Traceback (most recent call last):
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 252, in run_test
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - result = self.do_test(test)
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 680, in do_test
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - assertion_count = self.protocol.asserts.get()
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 369, in get
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - with self.marionette.using_context(self.marionette.CONTEXT_CHROME):
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - File "c:\mozilla-build\python\Lib\contextlib.py", line 17, in enter
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - return self.gen.next()
[task 2019-12-06T12:40:20.515Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1327, in using_context
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - key="value")
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\decorators.py", line 36, in _
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - m._handle_socket_failure()
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\marionette.py", line 650, in _handle_socket_failure
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - reraise(exc, val, tb)
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - return func(*args, **kwargs)
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - msg = self.client.request(name, params)
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - return self.receive()
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\transport.py", line 153, in receive
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - chunk = self._sock.recv(bytes_to_recv)
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING - error: [Errno 10058] A request to send or receive data was disallowed because the socket had already been shut down in that direction with a previous shutdown call
[task 2019-12-06T12:40:20.516Z] 12:40:20 WARNING -
[task 2019-12-06T12:40:20.520Z] 12:40:20 ERROR - test_end for /webrtc/RTCDTMFSender-ontonechange-long.https.html logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"test_timeout": 180}, "known_intermittent": [], "test": "/webrtc/RTCDTMFSender-ontonechange-long.https.html", "message": "Traceback (most recent call last):\n File "Z:\task_1575632066\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 252, in run_test\n result = self.do_test(test)\n File "Z:\task_1575632066\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 680, in do_test\n assertion_count = self.protocol.asserts.get()\n File "Z:\task_1575632066\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 369, in get\n with self.marionette.using_context(self.marionette.CONTEXT_CHROME):\n File "c:\mozilla-build\python\Lib\contextlib.py", line 17, in enter\n return self.gen.next()\n File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1327, in using_context\n key="value")\n File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\decorators.py", line 36, in _\n m._handle_socket_failure()\n File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\marionette.py", line 650, in _handle_socket_failure\n reraise(exc, val, tb)\n File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _\n return func(*args, **kwargs)\n File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message\n msg = self.client.request(name, params)\n File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request\n return self.receive()\n File "Z:\task_1575632066\build\venv\lib\site-packages\marionette_driver\transport.py", line 153, in receive\n chunk = self._sock.recv(bytes_to_recv)\nerror: [Errno 10058] A request to send or receive data was disallowed because the socket had already been shut down in that direction with a previous shutdown call\n", "expected": "OK"}
[task 2019-12-06T12:41:31.598Z] 12:41:31 INFO - Browser exited with return code 572

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

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE

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

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 6 years ago → 6 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=298819555&repo=try&lineNumber=10974

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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