https://tbpl.mozilla.org/php/getParsedLog.php?id=42295241&tree=B2g-Inbound b2g_emulator_vm b2g-inbound opt test marionette-webapi on 2014-06-23 13:03:49 PDT for push 43af4669b425 slave: tst-linux64-spot-135 13:57:15 INFO - TEST-START test_cellbroadcast_gsm.js 13:57:47 INFO - /builds/slave/test/build/tests/marionette/tests/dom/cellbroadcast/tests/marionette/test_cellbroadcast_gsm.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR 13:57:48 INFO - START LOG: 13:57:48 INFO - INFO TEST-START: /builds/slave/test/build/tests/marionette/tests/dom/cellbroadcast/tests/marionette/test_cellbroadcast_gsm.js Mon Jun 23 2014 16:57:16 GMT-0400 (EDT) 13:57:48 INFO - INFO Test GSM Cell Broadcast message attributes Mon Jun 23 2014 16:57:17 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Geographical Scope Mon Jun 23 2014 16:57:17 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Message Code Mon Jun 23 2014 16:57:18 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Message Identifier Mon Jun 23 2014 16:57:19 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Language & Body Mon Jun 23 2014 16:57:20 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Timestamp Mon Jun 23 2014 16:57:38 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Warning Type Mon Jun 23 2014 16:57:38 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Emergency User Alert Mon Jun 23 2014 16:57:39 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Popup Mon Jun 23 2014 16:57:39 GMT-0400 (EDT) 13:57:48 INFO - INFO Test receiving GSM Cell Broadcast - Multipart Messages Mon Jun 23 2014 16:57:39 GMT-0400 (EDT) 13:57:48 INFO - END LOG: 13:57:48 INFO - ====================================================================== 13:57:48 INFO - ERROR: None 13:57:48 INFO - ---------------------------------------------------------------------- 13:57:48 ERROR - TEST-UNEXPECTED-FAIL | test_cellbroadcast_gsm.js | ScriptTimeoutException: ScriptTimeoutException: timed out 13:57:48 INFO - ---------------------------------------------------------------------- 13:57:48 INFO - Ran 1 test in 32.534s 13:57:48 WARNING - FAILED (errors=1) 13:57:48 INFO - TEST-START test_cellbroadcast_multi_sim.js 13:57:50 INFO - /builds/slave/test/build/tests/marionette/tests/dom/cellbroadcast/tests/marionette/test_cellbroadcast_multi_sim.js, runTest (marionette_test.MarionetteJSTestCase) ... ERROR 13:57:51 INFO - START LOG: 13:57:51 INFO - INFO TEST-START: /builds/slave/test/build/tests/marionette/tests/dom/cellbroadcast/tests/marionette/test_cellbroadcast_multi_sim.js Mon Jun 23 2014 16:57:49 GMT-0400 (EDT) 13:57:51 INFO - INFO navigator.mozCellBroadcast is instance of [object XrayWrapper function MozCellBroadcast() { 13:57:51 INFO - [native code] 13:57:51 INFO - }] Mon Jun 23 2014 16:57:50 GMT-0400 (EDT) 13:57:51 INFO - INFO Not a Multi-SIM environment. Test is skipped. Mon Jun 23 2014 16:57:50 GMT-0400 (EDT) 13:57:51 INFO - END LOG: 13:57:51 INFO - ====================================================================== 13:57:51 INFO - ERROR: None 13:57:51 INFO - ---------------------------------------------------------------------- 13:57:51 INFO - Traceback (most recent call last): 13:57:51 INFO - File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 170, in run 13:57:51 INFO - testMethod() 13:57:51 INFO - File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 497, in runTest 13:57:51 INFO - filename=os.path.basename(self.jsFile)) 13:57:51 INFO - File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 1039, in execute_js_script 13:57:51 INFO - line=None) 13:57:51 INFO - File "/builds/slave/test/build/tests/marionette/marionette/decorators.py", line 35, in _ 13:57:51 INFO - return func(*args, **kwargs) 13:57:51 INFO - File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 613, in _send_message 13:57:51 INFO - self._handle_error(response) 13:57:51 INFO - File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 687, in _handle_error 13:57:51 INFO - raise errors.MarionetteException(message=message, status=status, stacktrace=stacktrace) 13:57:51 ERROR - TEST-UNEXPECTED-FAIL | test_cellbroadcast_multi_sim.js | MarionetteException: MarionetteException: Emulator callback still pending when finish() called 13:57:51 INFO - ---------------------------------------------------------------------- 13:57:51 INFO - Ran 1 test in 2.276s 13:57:51 WARNING - FAILED (errors=1) 13:57:51 INFO - TEST-START test_touchcaret.py

(In reply to Wes Kocher (:KWierso) from comment #0) > 13:57:15 INFO - TEST-START test_cellbroadcast_gsm.js Timeout is too short. Others have 60 seconds, but this one has only 30. > 13:57:48 INFO - TEST-START test_cellbroadcast_multi_sim.js > 13:57:51 ERROR - TEST-UNEXPECTED-FAIL | test_cellbroadcast_multi_sim.js > | MarionetteException: MarionetteException: Emulator callback still pending > when finish() called Looks we didn't call sendEmulatorCommand at all. Don't know why it's happening yet.

I just recall that When fixing bug 921326, I found that the marionette test case of cellbroadcast was not enabled. Hence, I enable it by adding it into unit-tests.ini: https://bugzilla.mozilla.org/page.cgi?id=splinter.html&bug=921326&attachment=8441232 Shall we disable it until bug 1028791 is done?

The original MARIONETTE_TIMEOUT is 30 and is too closed to the time this test case to be executed in the try server. I've expanded it to 60 seconds while rewriting the CBS test cases with promise in bug 1028791 and the solution has been phased in. We can keep monitoring if it still happens. :)

Inactive; closing (see bug 1180138).