835186 - Intermittent test_mobile_iccinfo.js | TimeoutException: socket.timeout

Status: RESOLVED WONTFIX

(Firefox OS Graveyard :: RIL, defect)

Description

[Phil Ringnalda (:philor)]

https://tbpl.mozilla.org/php/getParsedLog.php?id=19179455&tree=Fx-Team
b2g_ics_armv7a_gecko_emulator fx-team opt test marionette-webapi on 2013-01-27 04:36:11 PST for push 47684913d63d
slave: talos-r3-fed-032

04:45:51     INFO -  TEST-START test_mobile_iccinfo.js
04:48:53     INFO -  /home/cltbld/talos-slave/test/build/tests/marionette/tests/dom/network/tests/marionette/test_mobile_iccinfo.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR
04:48:53     INFO -  ======================================================================
04:48:53     INFO -  ERROR: /home/cltbld/talos-slave/test/build/tests/marionette/tests/dom/network/tests/marionette/test_mobile_iccinfo.js, runTest (marionette_test.MarionetteJSTestCase)
04:48:53     INFO -  ----------------------------------------------------------------------
04:48:53    ERROR -  Traceback (most recent call last):
04:48:53     INFO -    File "/home/cltbld/talos-slave/test/build/tests/marionette/marionette/marionette_test.py", line 97, in tearDown
04:48:53     INFO -      self.marionette.delete_session()
04:48:53     INFO -    File "/home/cltbld/talos-slave/test/build/tests/marionette/marionette/marionette.py", line 341, in delete_session
04:48:53     INFO -      response = self._send_message('deleteSession', 'ok')
04:48:53     INFO -    File "/home/cltbld/talos-slave/test/build/tests/marionette/marionette/marionette.py", line 233, in _send_message
04:48:53    ERROR -      raise TimeoutException(message='socket.timeout', status=ErrorCodes.TIMEOUT, stacktrace=None)
04:48:53    ERROR -  TEST-UNEXPECTED-FAIL | test_mobile_iccinfo.js | TimeoutException: socket.timeout
04:48:53     INFO -  ----------------------------------------------------------------------
04:48:53     INFO -  Ran 1 test in 181.619s
04:48:53    ERROR -  FAILED (errors=1)

Comment 1

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

Attachment: Timeout log

Comment 2

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

Attachment: Success log

Comment 3

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

For the Timeout log,
in line 1465, test scripts sends "operator set 0 Android,Android,46692"
although emulator responses OK in line 1484,
but operatorchange unsolicited response is not received,
seems being dropped by emulator.

Comment 4

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

Forgot comment 3, seems 46692 is not being dropped,
the MCC/MNC showed in line 1597 have been changed to 466/92.

Comment 5

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

I think line 1487 is for the MCC/MNC(46692) changed, but ril is still processing the VoiceInfo changed in last test session, so the event is dropped.

Comment 6

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

Attachment: Patch

Comment 7

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

Comment on attachment 708491 [details] [diff] [review]
Patch

Please see comment 5 for the root cause.

Comment 8

[Vicamo Yang [:vicamo][:vyang]]

The best solution for this should be bug 831637, which will ensure every parcel being processed.

Comment 9

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

https://hg.mozilla.org/integration/mozilla-inbound/rev/0c6f78d3f0a4

Comment 10

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/mozilla-central/rev/0c6f78d3f0a4

Comment 11

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=19569948&tree=Mozilla-Inbound
b2g_ics_armv7a_gecko_emulator mozilla-inbound opt test marionette-webapi on 2013-02-08 05:30:15
slave: talos-r3-fed-062

05:43:57    ERROR -  Traceback (most recent call last):
05:43:57    ERROR -      raise TimeoutException(message='socket.timeout', status=ErrorCodes.TIMEOUT, stacktrace=None)
05:43:57    ERROR -  TEST-UNEXPECTED-FAIL | test_mobile_iccinfo.js | TimeoutException: socket.timeout
05:43:57    ERROR -  FAILED (errors=1)
05:44:42    ERROR - Return code: 10

Comment 12

[Ryan VanderMeulen [:RyanVM]]

Just hit this on inbound again.

Comment 13

[Jonathan Griffin (:jgriffin)]

The most recent log is no longer available, and we haven't seen this in 2+ months, so I'm closing it.

Comment 14

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=23248732&tree=Mozilla-Inbound
b2g_ics_armv7a_gecko_emulator_vm mozilla-inbound opt test marionette-webapi on 2013-05-22 01:52:30
slave: tst-linux64-ec2-359

02:17:28    ERROR -      raise TimeoutException(message='socket.timeout', status=ErrorCodes.TIMEOUT, stacktrace=None)
02:17:28    ERROR -  TEST-UNEXPECTED-FAIL | test_mobile_iccinfo.js | TimeoutException: socket.timeout
02:19:27    ERROR - Return code: 10

Comment 15

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=24534865&tree=Mozilla-B2g18
b2g_ics_armv7a_gecko_emulator mozilla-b2g18 opt test marionette-webapi on 2013-06-24 14:41:50
revision: d80a28cf0364
slave: talos-r3-fed-046

14:54:20     INFO -  TEST-UNEXPECTED-FAIL | test_mobile_iccinfo.js | MarionetteException: Emulator callback still pending when finish() called
14:54:37    ERROR - Return code: 10

Comment 16

[Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh][:yoshi]]

I'll close this as WONTFIX:
as we have already moved the test to test_icc_info.js,
and AFAIK, this error doesn't happen since Ed Morley met in 2013 May.

Hi, Ed Morley, or any Sheriff
If you meet this problem again, please file a new bug for this.

Thanks