test failure: dom/telephony/test/marionette/*.js | ScriptTimeoutException: timed out

RESOLVED FIXED in mozilla17

Status

()

Core
DOM: Device Interfaces
RESOLVED FIXED
5 years ago
5 years ago

People

(Reporter: mdas, Assigned: hsinyi)

Tracking

({intermittent-failure})

Trunk
mozilla17
ARM
Linux
intermittent-failure
Points:
---

Firefox Tracking Flags

(Not tracked)

Details

Attachments

(3 attachments, 2 obsolete attachments)

These are the webapi tests running on the ARM b2g emulator. They passed before June 14th, and started failing sometime between the 14th and the 24th. I appended the respective logs under each test

======================================================================
ERROR: /data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_incoming_answer_hangup.js, runTest (marionette_test.MarionetteJSTestCase)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette_test.py", line 176, in runTest
    results = self.marionette.execute_js_script(js, args)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 334, in execute_js_script
    newSandbox=new_sandbox)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 150, in _send_message
    self._handle_error(response)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 199, in _handle_error
    raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
ScriptTimeoutException: timed out

----------------------------------------------------------------------
Ran 1 test in 27.063s

FAILED (errors=1)

START LOG:
INFO Verifying initial state. Tue Jun 26 2012 18:23:14 GMT+0000 (GMT)
INFO Initial call list: OK Tue Jun 26 2012 18:23:14 GMT+0000 (GMT)
INFO Simulating an incoming call. Tue Jun 26 2012 18:23:14 GMT+0000 (GMT)
INFO Received 'incoming' call event. Tue Jun 26 2012 18:23:14 GMT+0000 (GMT)
INFO Call list is now: inbound from 5555552368 : incoming,OK Tue Jun 26 2012 18:23:14 GMT+0000 (GMT)
INFO Answering the incoming call. Tue Jun 26 2012 18:23:15 GMT+0000 (GMT)
INFO Received 'connecting' call event. Tue Jun 26 2012 18:23:15 GMT+0000 (GMT)
INFO Received 'connected' call event. Tue Jun 26 2012 18:23:15 GMT+0000 (GMT)
INFO Call list is now: inbound from 5555552368 : active,OK Tue Jun 26 2012 18:23:15 GMT+0000 (GMT)
INFO Hanging up the incoming call. Tue Jun 26 2012 18:23:15 GMT+0000 (GMT)
INFO Received 'disconnecting' call event. Tue Jun 26 2012 18:23:15 GMT+0000 (GMT)
END LOG:

TEST-START test_incoming_reject.js
/data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_incoming_reject.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR

======================================================================
ERROR: /data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_incoming_reject.js, runTest (marionette_test.MarionetteJSTestCase)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette_test.py", line 176, in runTest
    results = self.marionette.execute_js_script(js, args)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 334, in execute_js_script
    newSandbox=new_sandbox)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 150, in _send_message
    self._handle_error(response)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 199, in _handle_error
    raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
ScriptTimeoutException: timed out

----------------------------------------------------------------------
Ran 1 test in 10.701s

FAILED (errors=1)
START LOG:
INFO Verifying initial state. Tue Jun 26 2012 18:23:24 GMT+0000 (GMT)
INFO Initial call list: OK Tue Jun 26 2012 18:23:24 GMT+0000 (GMT)
INFO Simulating an incoming call. Tue Jun 26 2012 18:23:24 GMT+0000 (GMT)
INFO Received 'incoming' call event. Tue Jun 26 2012 18:23:24 GMT+0000 (GMT)
INFO Call list is now: inbound from 5555552368 : incoming,OK Tue Jun 26 2012 18:23:24 GMT+0000 (GMT)
INFO Reject the incoming call. Tue Jun 26 2012 18:23:24 GMT+0000 (GMT)
INFO Received 'disconnecting' call event. Tue Jun 26 2012 18:23:25 GMT+0000 (GMT)
END LOG:

TEST-START test_outgoing_answer_hangup.js
/data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_outgoing_answer_hangup.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR

======================================================================
ERROR: /data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_outgoing_answer_hangup.js, runTest (marionette_test.MarionetteJSTestCase)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette_test.py", line 176, in runTest
    results = self.marionette.execute_js_script(js, args)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 334, in execute_js_script
    newSandbox=new_sandbox)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 150, in _send_message
    self._handle_error(response)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 199, in _handle_error
    raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
ScriptTimeoutException: timed out

----------------------------------------------------------------------
Ran 1 test in 10.680s

FAILED (errors=1)

START LOG:
INFO Verifying initial state. Tue Jun 26 2012 18:23:35 GMT+0000 (GMT)
INFO Initial call list: OK Tue Jun 26 2012 18:23:35 GMT+0000 (GMT)
INFO Make an outgoing call. Tue Jun 26 2012 18:23:35 GMT+0000 (GMT)
INFO Call list is now: OK Tue Jun 26 2012 18:23:35 GMT+0000 (GMT)
INFO Answering the outgoing call. Tue Jun 26 2012 18:23:35 GMT+0000 (GMT)
INFO Received 'connected' call event. Tue Jun 26 2012 18:23:35 GMT+0000 (GMT)
INFO Call list is now: outbound to  5555552368 : active,OK Tue Jun 26 2012 18:23:35 GMT+0000 (GMT)
INFO Hanging up the outgoing call. Tue Jun 26 2012 18:23:35 GMT+0000 (GMT)
END LOG:

TEST-START test_incoming_answer_hangup_oncallschanged.js
/data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_incoming_answer_hangup_oncallschanged.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR

======================================================================
ERROR: /data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_incoming_answer_hangup_oncallschanged.js, runTest (marionette_test.MarionetteJSTestCase)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette_test.py", line 176, in runTest
    results = self.marionette.execute_js_script(js, args)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 334, in execute_js_script
    newSandbox=new_sandbox)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 150, in _send_message
    self._handle_error(response)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 199, in _handle_error
    raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
ScriptTimeoutException: timed out

----------------------------------------------------------------------
Ran 1 test in 10.705s

FAILED (errors=1)

START LOG:
INFO Verifying initial state. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Initial call list: OK Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Simulating an incoming call. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Received 'callschanged' event. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Received 'callschanged' event for an incoming call. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Call list is now: inbound from 5555552368 : incoming,OK Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Answering the incoming call. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Received 'connecting' call event. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Received 'connected' call event. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Call list is now: inbound from 5555552368 : active,OK Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Hanging up the incoming call. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
INFO Received 'disconnecting' call event. Tue Jun 26 2012 18:23:46 GMT+0000 (GMT)
END LOG:

TEST-START test_outgoing_answer_hangup_oncallschanged.js
/data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_outgoing_answer_hangup_oncallschanged.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR

======================================================================
ERROR: /data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_outgoing_answer_hangup_oncallschanged.js, runTest (marionette_test.MarionetteJSTestCase)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette_test.py", line 176, in runTest
    results = self.marionette.execute_js_script(js, args)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 334, in execute_js_script
    newSandbox=new_sandbox)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 150, in _send_message
    self._handle_error(response)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 199, in _handle_error
    raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
ScriptTimeoutException: timed out

----------------------------------------------------------------------
Ran 1 test in 10.677s

FAILED (errors=1)


START LOG:
INFO Verifying initial state. Tue Jun 26 2012 18:23:56 GMT+0000 (GMT)
INFO Initial call list: OK Tue Jun 26 2012 18:23:56 GMT+0000 (GMT)
INFO Make an outgoing call. Tue Jun 26 2012 18:23:56 GMT+0000 (GMT)
INFO Received 'callschanged' call event. Tue Jun 26 2012 18:23:56 GMT+0000 (GMT)
INFO Call list is now: outbound to  5555552368 : unknown,OK Tue Jun 26 2012 18:23:56 GMT+0000 (GMT)
INFO Answering the outgoing call. Tue Jun 26 2012 18:23:56 GMT+0000 (GMT)
INFO Received 'connected' call event. Tue Jun 26 2012 18:23:57 GMT+0000 (GMT)
INFO Call list is now: outbound to  5555552368 : active,OK Tue Jun 26 2012 18:23:57 GMT+0000 (GMT)
INFO Hanging up the outgoing call. Tue Jun 26 2012 18:23:57 GMT+0000 (GMT)
END LOG:

TEST-START test_outgoing_reject.js
/data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_outgoing_reject.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR

======================================================================
ERROR: /data/jenkins/jobs/webapi-marionette-test/workspace/dom/telephony/test/marionette/test_outgoing_reject.js, runTest (marionette_test.MarionetteJSTestCase)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette_test.py", line 176, in runTest
    results = self.marionette.execute_js_script(js, args)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 334, in execute_js_script
    newSandbox=new_sandbox)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 150, in _send_message
    self._handle_error(response)
  File "/data/jenkins/jobs/webapi-marionette-test/workspace/testing/marionette/client/marionette/marionette.py", line 199, in _handle_error
    raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
ScriptTimeoutException: timed out
18:24----------------------------------------------------------------------
Ran 1 test in 10.703s 

START LOG:
INFO Verifying initial state. Tue Jun 26 2012 18:24:07 GMT+0000 (GMT)
INFO Initial call list: OK Tue Jun 26 2012 18:24:07 GMT+0000 (GMT)
INFO Make an outgoing call. Tue Jun 26 2012 18:24:07 GMT+0000 (GMT)
INFO Call list is now: outbound to  5555552368 : unknown,OK Tue Jun 26 2012
(Assignee)

Comment 1

5 years ago
I am thinking these failures result from 'REQUEST_LAST_CALL_FAIL_CAUSE' in 'ril_worker.js' which we use to change call state into 'disconnected'

Please see my previous explanation here:
https://bugzilla.mozilla.org/show_bug.cgi?id=761533#c5
Regression from bug 761533. Hsinyi knows what to do, I'm sure. :)
Assignee: nobody → htsai
Whiteboard: [orange]
Blocks: 438871
(Assignee)

Comment 3

5 years ago
Created attachment 641405 [details] [diff] [review]
Add AT+CEER in android_modem
Attachment #641405 - Flags: review?(mwu)
(Assignee)

Comment 4

5 years ago
Created attachment 641407 [details] [diff] [review]
Support RIL_REQUEST_LAST_CALL_FAIL_CAUSE in reference-ril.c
Attachment #641407 - Flags: review?(mwu)
(Assignee)

Comment 5

5 years ago
Created attachment 641418 [details] [diff] [review]
Part 1: Add AT+CEER in android_modem (v2)

Addressed coding-style nit.
Attachment #641405 - Attachment is obsolete: true
Attachment #641407 - Attachment is obsolete: true
Attachment #641405 - Flags: review?(mwu)
Attachment #641407 - Flags: review?(mwu)
Attachment #641418 - Flags: review?(mwu)
(Assignee)

Comment 6

5 years ago
Created attachment 641419 [details] [diff] [review]
Part 2: Support RIL_REQUEST_LAST_CALL_FAIL_CAUSE in reference-ril.c (v2)

Addressed coding-style nit.
Attachment #641419 - Flags: review?(mwu)

Comment 7

5 years ago
Comment on attachment 641419 [details] [diff] [review]
Part 2: Support RIL_REQUEST_LAST_CALL_FAIL_CAUSE in reference-ril.c (v2)

Please make this a pull request against https://github.com/mozilla-b2g/platform_hardware_ril
Attachment #641419 - Flags: review?(mwu)

Comment 8

5 years ago
(In reply to Hsin-Yi Tsai [:hsinyi] from comment #5)
> Created attachment 641418 [details] [diff] [review]
> Part 1: Add AT+CEER in android_modem (v2)
> 
> Addressed coding-style nit.

Where is this file? I can't find android_modem.c
(Assignee)

Comment 9

5 years ago
(In reply to Michael Wu [:mwu] from comment #8)
> (In reply to Hsin-Yi Tsai [:hsinyi] from comment #5)
> > Created attachment 641418 [details] [diff] [review]
> > Part 1: Add AT+CEER in android_modem (v2)
> > 
> > Addressed coding-style nit.
> 
> Where is this file? I can't find android_modem.c

it's under B2G/external/qemu/telephony

Comment 10

5 years ago
Comment on attachment 641418 [details] [diff] [review]
Part 1: Add AT+CEER in android_modem (v2)

Please submit a pull request to https://github.com/mozilla-b2g/platform_external_qemu
Attachment #641418 - Flags: review?(mwu)
(Assignee)

Comment 11

5 years ago
(In reply to Michael Wu [:mwu] from comment #10)
> Comment on attachment 641418 [details] [diff] [review]
> Part 1: Add AT+CEER in android_modem (v2)
> 
> Please submit a pull request to
> https://github.com/mozilla-b2g/platform_external_qemu
Got it. Thanks.
FWIW, fixing the emulated modem to support the necessary requests is agreat. Thank you, Hsinyi! However, I think we should still make Gecko resilient against these failures. If for whatever reason RIL_REQUEST_LAST_CALL_FAIL_CAUSE fails when a phone call ends, we should still make sure we dispatch the "disconnected" event always.
(Assignee)

Comment 13

5 years ago
Created attachment 642179 [details] [diff] [review]
Change call state into disconnected even no response of call failure cause
Attachment #642179 - Flags: review?(philipp)
(Assignee)

Comment 14

5 years ago
Hi Philip, thanks for the comment and I addressed that in attachment 642179 [details] [diff] [review] in addition to the improvement I did in qemu.
Attachment #642179 - Flags: review?(philipp) → review+
(Assignee)

Comment 15

5 years ago
http://hg.mozilla.org/integration/mozilla-inbound/rev/df99b539896a
https://hg.mozilla.org/mozilla-central/rev/df99b539896a
Status: NEW → RESOLVED
Last Resolved: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla17
Keywords: intermittent-failure
Whiteboard: [orange]
You need to log in before you can comment on or make changes to this bug.