1056626 - Intermittent test_touchcaret.py TouchCaretTest.test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner | TimeoutException: TimeoutException: Connection timed out

Status: RESOLVED WORKSFORME

(Testing :: Marionette Client and Harness, defect)

Description

[Ed Morley [:emorley]]

b2g_emulator_vm mozilla-inbound opt test marionette-webapi on 2014-08-20 15:16:03 PDT for push ac59c5f851dc

slave: tst-linux64-spot-468

https://tbpl.mozilla.org/php/getParsedLog.php?id=46404681&tree=Mozilla-Inbound

{
16:16:22     INFO -  08-20 19:10:17.829 I/Gecko   (   44): MARIONETTE LOG: INFO: TEST-START: /builds/slave/test/build/tests/marionette/tests/layout/base/tests/marionette/test_touchcaret.py:test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner
16:16:22     INFO -  08-20 19:10:17.849 I/Gecko   (   44): 1408576217851	Marionette	INFO	sendToClient: {"from":"0","value":null}, {aa278939-6a8e-418e-9f88-6e66426b4b99}, {aa278939-6a8e-418e-9f88-6e66426b4b99}
16:16:22     INFO -  08-20 19:10:17.869 I/Gecko   (   44): 1408576217874	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140820145817","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"34.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"34.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"return SpecialPowers.getIntPref(\"touchcaret.expiration.time\");","newSandbox":true,"args":[],"filename":"test_touchcaret.py","line":33}}, id: {797dcab5-a636-426e-ab6c-cd4d52dc50f2}
16:16:22     INFO -  08-20 19:10:17.909 I/Gecko   (   44): 1408576217910	Marionette	INFO	sendToClient: {"from":"0","value":3000}, {797dcab5-a636-426e-ab6c-cd4d52dc50f2}, {797dcab5-a636-426e-ab6c-cd4d52dc50f2}
16:16:22     INFO -  08-20 19:10:17.929 I/Gecko   (   44): 1408576217934	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140820145817","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"34.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"34.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"SpecialPowers.setBoolPref(\"touchcaret.enabled\", true);","newSandbox":true,"args":[],"filename":"test_touchcaret.py","line":49}}, id: {272dc894-2aa2-40cd-81cf-4bc318ff2e21}
16:16:22     INFO -  08-20 19:10:17.980 I/Gecko   (   44): 1408576217985	Marionette	INFO	sendToClient: {"from":"0","value":null}, {272dc894-2aa2-40cd-81cf-4bc318ff2e21}, {272dc894-2aa2-40cd-81cf-4bc318ff2e21}
16:16:22     INFO -  08-20 19:10:18.000 I/Gecko   (   44): 1408576218009	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140820145817","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"34.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"34.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"SpecialPowers.setIntPref(\"touchcaret.expiration.time\", arguments[0]);","newSandbox":true,"args":[60000],"filename":"test_touchcaret.py","line":40}}, id: {12c08684-48ee-4c64-8048-159d7d40f6a0}
16:16:22     INFO -  08-20 19:10:18.050 I/Gecko   (   44): 1408576218055	Marionette	INFO	sendToClient: {"from":"0","value":null}, {12c08684-48ee-4c64-8048-159d7d40f6a0}, {12c08684-48ee-4c64-8048-159d7d40f6a0}
16:16:22     INFO -  08-20 19:10:18.409 E/GeckoConsole(   44): [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://10.132.58.181:46490/test_touchcaret.html" line: 0}]
16:16:22     INFO -  08-20 19:10:18.929 I/Gecko   (   44): 1408576218932	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {f40991a5-a6a5-40bf-87ee-c17d24384460}, {f40991a5-a6a5-40bf-87ee-c17d24384460}
16:16:22     INFO -  08-20 19:10:18.969 I/Gecko   (   44): 1408576218976	Marionette	INFO	sendToClient: {"from":"0","value":{"ELEMENT":"{df78c849-bf7d-483c-b189-764046b80162}"}}, {e16ebd34-cd1d-4105-b830-af6ad0b647fe}, {e16ebd34-cd1d-4105-b830-af6ad0b647fe}
16:16:22     INFO -  08-20 19:10:19.089 I/Gecko   (   44): 1408576219090	Marionette	INFO	sendToClient: {"from":"0","value":{"ELEMENT":"{6aab736b-3226-45ec-979c-b4d4ca1428a9}"}}, {c91d92bc-a6c8-4e5d-9b89-53c581c8617f}, {c91d92bc-a6c8-4e5d-9b89-53c581c8617f}
16:16:22     INFO -  08-20 19:10:19.129 I/Gecko   (   44): 1408576219131	Marionette	INFO	sendToClient: {"from":"0","value":{"ELEMENT":"{d6663db6-befd-4076-9854-2f4e32fe5a67}"}}, {f9a58554-cde6-43d1-8060-27f2164c8bed}, {f9a58554-cde6-43d1-8060-27f2164c8bed}
16:16:22     INFO -  08-20 19:10:19.229 I/Gecko   (   44): 1408576219232	Marionette	INFO	sendToClient: {"from":"0","value":"textarea"}, {7a5822d0-049c-410c-83b4-7a07e71c5e82}, {7a5822d0-049c-410c-83b4-7a07e71c5e82}
16:16:22     INFO -  08-20 19:10:19.289 I/Gecko   (   44): 1408576219294	Marionette	INFO	sendToClient: {"from":"0","value":"ABCDEFGHI"}, {3b163968-1ce2-43d7-b57a-48d72a4f0374}, {3b163968-1ce2-43d7-b57a-48d72a4f0374}
16:16:22     INFO -  08-20 19:10:19.739 I/Gecko   (   44): 1408576219740 Marionette: emitting Touch event of type touchstart to element with id: textarea and tag name: TEXTAREA at coordinates (31, 79) relative to the viewport
16:16:22     INFO -  08-20 19:10:19.829 I/Gecko   (   44): 1408576219834 Marionette: emitting Touch event of type touchend to element with id: textarea and tag name: TEXTAREA at coordinates (31, 79) relative to the viewport
16:16:22     INFO -  08-20 19:10:19.849 I/Gecko   (   44): 1408576219851 Marionette: emitting Mouse event of type mousemove at coordinates (31.008331298828125, 79) relative to the viewport
16:16:22     INFO -  08-20 19:10:19.859 I/Gecko   (   44): 1408576219867 Marionette: emitting Mouse event of type mousedown at coordinates (31.008331298828125, 79) relative to the viewport
16:16:22     INFO -  08-20 19:10:19.949 I/Gecko   (   44): 1408576219955 Marionette: emitting Mouse event of type mouseup at coordinates (31.008331298828125, 79) relative to the viewport
16:16:22     INFO -  08-20 19:10:20.114 I/Gecko   (   44): 1408576220117	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {61b87be5-0a92-409e-9e62-cc2ddf41538b}, {61b87be5-0a92-409e-9e62-cc2ddf41538b}
16:16:22     INFO -  08-20 19:10:20.209 I/Gecko   (   44): 1408576220216	Marionette	INFO	sendToClient: {"from":"0","value":"textarea"}, {34f9e022-553a-4799-83cd-7a493d338cc3}, {34f9e022-553a-4799-83cd-7a493d338cc3}
16:16:22     INFO -  08-20 19:10:20.239 I/Gecko   (   44): 1408576220241	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140820145817","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"34.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"34.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"arguments[0].setSelectionRange(0, 0);","newSandbox":true,"args":[{"ELEMENT":"{6aab736b-3226-45ec-979c-b4d4ca1428a9}"}],"filename":"selection.py","line":63}}, id: {46926bd0-1682-4df3-9acf-73a06bce75b1}
16:16:22     INFO -  08-20 19:10:20.309 I/Gecko   (   44): 1408576220312	Marionette	INFO	sendToClient: {"from":"0","value":null}, {46926bd0-1682-4df3-9acf-73a06bce75b1}, {46926bd0-1682-4df3-9acf-73a06bce75b1}
16:16:22     INFO -  08-20 19:10:20.529 I/Gecko   (   44): 1408576220526	Marionette	INFO	sendToClient: {"from":"0","value":"textarea"}, {e14b4e84-c9a4-4c24-bd7a-9e5a09cf0fc2}, {e14b4e84-c9a4-4c24-bd7a-9e5a09cf0fc2}
16:16:22     INFO -  08-20 19:10:20.559 I/Gecko   (   44): 1408576220562	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140820145817","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"34.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"34.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"var sel = SpecialPowers.wrap(arguments[0]).editor.selection;\n                   sel = SpecialPowers.unwrap(sel);return sel.getRangeAt(0).getClientRects();","newSandbox":true,"args":[{"ELEMENT":"{6aab736b-3226-45ec-979c-b4d4ca1428a9}"}],"filename":"selection.py","line":86}}, id: {efff2751-b24a-4d9c-a3fc-84031e6337d9}
16:16:22     INFO -  08-20 19:10:20.729 I/Gecko   (   44): 1408576220729	Marionette	INFO	sendToClient: {"from":"0","value":{"0":{"x":10,"y":51,"width":0,"height":14,"top":51,"right":10,"bottom":65,"left":10},"item":null,"@@iterator":null,"length":1}}, {efff2751-b24a-4d9c-a3fc-84031e6337d9}, {efff2751-b24a-4d9c-a3fc-84031e6337d9}
16:16:22     INFO -  08-20 19:10:20.809 I/Gecko   (   44): 1408576220810	Marionette	INFO	sendToClient: {"from":"0","value":{"x":8,"y":48}}, {30151f7b-82c2-4ced-81ca-a38320ff0353}, {30151f7b-82c2-4ced-81ca-a38320ff0353}
16:16:22     INFO -  B2G is still running but Marionette can't respond due to either a Gecko, Gaia or Marionette error. Above, the 5 most recent errors are listed. Check logcat for all errors if these errors are not the cause of the failure.
16:16:22    ERROR -  TEST-UNEXPECTED-ERROR | test_touchcaret.py TouchCaretTest.test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner | TimeoutException: TimeoutException: Connection timed out
16:16:22     INFO -  Traceback (most recent call last):
16:16:22     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 171, in run
16:16:22     INFO -      testMethod()
16:16:22     INFO -    File "/builds/slave/test/build/tests/marionette/tests/layout/base/tests/marionette/test_touchcaret.py", line 225, in test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner
16:16:22     INFO -      self._test_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner(self._textarea, self.assertEqual)
16:16:22     INFO -    File "/builds/slave/test/build/tests/marionette/tests/layout/base/tests/marionette/test_touchcaret.py", line 94, in _test_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner
16:16:22     INFO -      el.tap(*sel.caret_location())
16:16:22     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/selection.py", line 155, in caret_location
16:16:22     INFO -      return self._selection_location_helper('center')[0]
16:16:22     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/selection.py", line 100, in _selection_location_helper
16:16:22     INFO -      origin_x, origin_y = self.element.location['x'], self.element.location['y']
16:16:22     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 173, in location
16:16:22     INFO -      return self.marionette._send_message("getElementLocation", "value", id=self.id)
16:16:22     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/decorators.py", line 35, in _
16:16:22     INFO -      return func(*args, **kwargs)
16:16:22     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 621, in _send_message
16:16:22     INFO -      "Connection timed out", status=errors.ErrorCodes.TIMEOUT)
16:16:22     INFO -  TEST-INFO expected PASS | took 365355ms
16:16:22    ERROR -  test_end for test_touchcaret.py TouchCaretTest.test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner logged while not in progress. Logged with data: {"status": "ERROR", "test": "test_touchcaret.py TouchCaretTest.test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner", "message": "Traceback (most recent call last):\n  File \"/builds/slave/test/build/tests/marionette/marionette/marionette_test.py\", line 199, in run\n    self.tearDown()\n  File \"/builds/slave/test/build/tests/marionette/tests/layout/base/tests/marionette/test_touchcaret.py\", line 26, in tearDown\n    self.expiration_time = self.original_expiration_time\n  File \"/builds/slave/test/build/tests/marionette/tests/layout/base/tests/marionette/test_touchcaret.py\", line 40, in expiration_time\n    script_args=[expiration_time])\n  File \"/builds/slave/test/build/tests/marionette/marionette/marionette.py\", line 1203, in execute_script\n    filename=os.path.basename(frame[0]))\n  File \"/builds/slave/test/build/tests/marionette/marionette/decorators.py\", line 35, in _\n    return func(*args, **kwargs)\n  File \"/builds/slave/test/build/tests/marionette/marionette/marionette.py\", line 606, in _send_message\n    raise errors.MarionetteException(\"Please start a session\")\nMarionetteException: MarionetteException: Please start a session\n\n", "expected": "PASS", "extra": {}}
}

Comment 1

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=48786779&tree=Mozilla-Inbound
b2g_emulator_vm mozilla-inbound opt test marionette-webapi on 2014-09-24 08:06:28
revision: 03b1abc4f5e7
slave: tst-linux64-spot-103

TEST-UNEXPECTED-ERROR | test_touchcaret.py TouchCaretTest.test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner | TimeoutException: TimeoutException: Connection timed out
Return code: 10

Comment 2

[Legacy TBPL/Treeherder Robot]

Tomcat
https://tbpl.mozilla.org/php/getParsedLog.php?id=49096718&tree=Fx-Team
b2g_emulator_vm fx-team opt test marionette on 2014-09-29 02:31:40
revision: c9ef2c9434b5
slave: tst-linux64-spot-416

TEST-UNEXPECTED-ERROR | test_touchcaret.py TouchCaretTest.test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner | TimeoutException: TimeoutException: Connection timed out
Return code: 10

Comment 3

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=49111514&tree=B2g-Inbound
b2g_emulator_vm b2g-inbound opt test marionette on 2014-09-29 06:13:57
revision: 5eb6756ae226
slave: tst-linux64-spot-1096

TEST-UNEXPECTED-ERROR | test_nfc_manager_tech_discovered.js | TimeoutException: TimeoutException: Connection timed out
TEST-UNEXPECTED-ERROR | test_touchcaret.py TouchCaretTest.test_textarea_move_caret_to_end_by_dragging_touch_caret_to_bottom_right_corner | TimeoutException: TimeoutException: Connection timed out
Return code: 10

Comment 4

[BMO Automation]

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.