Closed Bug 1008471 Opened 10 years ago Closed 9 years ago

Intermittent test_conference_remove_error.js | TimeoutException: TimeoutException: Connection timed out

Categories

(Firefox OS Graveyard :: RIL, defect)

Product:
Firefox OS Graveyard
Component:
RIL
Platform:
ARM
Gonk (Firefox OS)
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED WORKSFORME

People

(Reporter: KWierso, Unassigned)

References

Details

(Keywords: intermittent-failure) 

https://tbpl.mozilla.org/php/getParsedLog.php?id=39391063&tree=B2g-Inbound
b2g_emulator_vm b2g-inbound opt test marionette-webapi on 2014-05-09 14:15:12 PDT for push f22e38ce15cd

slave: tst-linux64-spot-648



14:45:40     INFO -  INFO Received 'callschanged' event for the conference Fri May 09 2014 17:44:42 GMT-0400 (EDT)
14:45:40     INFO -  INFO Received 'groupchange' event for the callToAdd (5555550201) Fri May 09 2014 17:44:42 GMT-0400 (EDT)
14:45:40     INFO -  INFO Received 'callschanged' event for the conference Fri May 09 2014 17:44:42 GMT-0400 (EDT)
14:45:40     INFO -  INFO Received 'connected' event for the conference Fri May 09 2014 17:44:42 GMT-0400 (EDT)
14:45:40     INFO -  INFO Received 'statechange' event for the callToAdd (5555550101) Fri May 09 2014 17:44:42 GMT-0400 (EDT)
14:45:40     INFO -  INFO Received 'statechange' event for the callToAdd (5555550201) Fri May 09 2014 17:44:42 GMT-0400 (EDT)
14:45:40     INFO -  END LOG:
14:45:40     INFO -  ======================================================================
14:45:40     INFO -  ERROR: None
14:45:40     INFO -  ----------------------------------------------------------------------
14:45:40     INFO -  Traceback (most recent call last):
14:45:40     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 170, in run
14:45:40     INFO -      testMethod()
14:45:40     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 497, in runTest
14:45:40     INFO -      filename=os.path.basename(self.jsFile))
14:45:40     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 1057, in execute_js_script
14:45:40     INFO -      line=None)
14:45:40     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/decorators.py", line 35, in _
14:45:40     INFO -      return func(*args, **kwargs)
14:45:40     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 631, in _send_message
14:45:40     INFO -      self._handle_error(response)
14:45:40     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 690, in _handle_error
14:45:40    ERROR -      raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
14:45:40    ERROR -  TEST-UNEXPECTED-FAIL | test_conference_remove_error.js | ScriptTimeoutException: ScriptTimeoutException: timed out
14:45:40     INFO -  ----------------------------------------------------------------------
14:45:40     INFO -  Ran 1 test in 62.091s
14:45:40  WARNING -  FAILED (errors=1)
14:45:41     INFO -  TEST-START test_conference_two_hangup_one.js
14:45:48     INFO -  /builds/slave/test/build/tests/marionette/tests/dom/telephony/test/marionette/test_conference_two_hangup_one.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR
14:45:49     INFO -  START LOG:
14:45:49     INFO -  INFO Call list is now: outbound to  5555550101 : active,inbound from 5555550201 : active,OK Fri May 09 2014 17:45:39 GMT-0400 (EDT)
14:45:49     INFO -  INFO Simulating an incoming call. Fri May 09 2014 17:45:39 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'incoming' call event. Fri May 09 2014 17:45:39 GMT-0400 (EDT)
14:45:49     INFO -  INFO TEST-START: /builds/slave/test/build/tests/marionette/tests/dom/telephony/test/marionette/test_conference_two_hangup_one.js Fri May 09 2014 17:45:42 GMT-0400 (EDT)
14:45:49     INFO -  INFO == Test SetUp == Fri May 09 2014 17:45:43 GMT-0400 (EDT)
14:45:49     INFO -  INFO Clear existing calls. Fri May 09 2014 17:45:43 GMT-0400 (EDT)
14:45:49     INFO -  INFO Verify initial state. Fri May 09 2014 17:45:43 GMT-0400 (EDT)
14:45:49     INFO -  INFO == Test Start == Fri May 09 2014 17:45:43 GMT-0400 (EDT)
14:45:49     INFO -  INFO = testConferenceTwoAndHangupOne = Fri May 09 2014 17:45:43 GMT-0400 (EDT)
14:45:49     INFO -  INFO Create a conference with 2 calls. Fri May 09 2014 17:45:43 GMT-0400 (EDT)
14:45:49     INFO -  INFO Verify initial state. Fri May 09 2014 17:45:44 GMT-0400 (EDT)
14:45:49     INFO -  INFO Make an outgoing call: 5555550101, serviceId: 0 Fri May 09 2014 17:45:44 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'onalerting' call event. Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Call list is now: outbound to  5555550101 : ringing,OK Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Remote answering the call. Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'connected' call event. Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Call list is now: outbound to  5555550101 : active,OK Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Simulating an incoming call. Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'incoming' call event. Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Call list is now: outbound to  5555550101 : active,inbound from 5555550201 : incoming,OK Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Answering the incoming call. Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'connecting' call event for incoming call. Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'connected' call event for incoming call. Fri May 09 2014 17:45:46 GMT-0400 (EDT)
14:45:49     INFO -  INFO Call list is now: outbound to  5555550101 : held,inbound from 5555550201 : active,OK Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Add 2 calls into conference. Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'groupchange' event for the callToAdd (5555550101) Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'callschanged' event for the conference Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'groupchange' event for the callToAdd (5555550201) Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'callschanged' event for the conference Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'connected' event for the conference Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'statechange' event for the callToAdd (5555550101) Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'statechange' event for the callToAdd (5555550201) Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Call list is now: outbound to  5555550101 : active,inbound from 5555550201 : active,OK Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Release one call in conference. Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Remote hanging up the call. Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'callschanged' event for the telephony Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'callschanged' event for the conference Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'disconnected' call event. Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'groupchange' event for the autoRemovedCall (5555550201) Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'callschanged' event for the conference Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'callschanged' event for the telephony Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'statechange' event for the conference Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Call list is now: inbound from 5555550201 : active,OK Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Remote hanging up the call. Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO Received 'disconnected' call event. Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  INFO == Test TearDown == Fri May 09 2014 17:45:47 GMT-0400 (EDT)
14:45:49     INFO -  END LOG:
14:45:49     INFO -  ======================================================================
14:45:49     INFO -  ERROR: None
14:45:49     INFO -  ----------------------------------------------------------------------
14:45:49     INFO -  Traceback (most recent call last):
14:45:49     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 170, in run
14:45:49     INFO -      testMethod()
14:45:49     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 497, in runTest
14:45:49     INFO -      filename=os.path.basename(self.jsFile))
14:45:49     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 1057, in execute_js_script
14:45:49     INFO -      line=None)
14:45:49     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/decorators.py", line 35, in _
14:45:49     INFO -      return func(*args, **kwargs)
14:45:49     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 631, in _send_message
14:45:49     INFO -      self._handle_error(response)
14:45:49     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 702, in _handle_error
14:45:49     INFO -      raise MarionetteException(message=message, status=status, stacktrace=stacktrace)
14:45:49     INFO -  TEST-UNEXPECTED-FAIL | test_conference_two_hangup_one.js | MarionetteException: MarionetteException: Emulator callback still pending when finish() called
14:45:49     INFO -  ----------------------------------------------------------------------
14:45:49     INFO -  Ran 1 test in 6.422s
14:45:49  WARNING -  FAILED (errors=1)
14:45:49     INFO -  TEST-START test_conference_two_hold_resume.js
14:45:57     INFO -  /builds/slave/test/build/tests/marionette/tests/dom/telephony/test/marionette/test_conference_two_hold_resume.js, runTest (marionette_test.MarionetteJSTestCase) ... ok
14:45:58     INFO -  START LOG:
14:45:58     INFO -  INFO TEST-START: /builds/slave/test/build/tests/marionette/tests/dom/telephony/test/marionette/test_conference_two_hold_resume.js Fri May 09 2014 17:45:51 GMT-0400 (EDT)
Only the Marionette harness sanity tests go in Testing::Marionette. Those are the ones in Gecko under:
testing/marionette/client/marionette/tests/unit/*

This is a Firefox OS product intermittent - moving to a component suggested by the hg log of this test.
Component: Marionette → RIL
Product: Testing → Firefox OS
Hardware: All → ARM
Broken the test_conference_two_hangup_one.js instances out to bug 1025266
Summary: Intermittent test_conference_remove_error.js | ScriptTimeoutException: ScriptTimeoutException: timed out | test_conference_two_hangup_one.js | MarionetteException: MarionetteException: Emulator callback still pending when finish() called → Intermittent test_conference_remove_error.js | TimeoutException: TimeoutException: Connection timed out
Inactive; closing (see bug 1180138).
Status: NEW → RESOLVED
Closed: 9 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.