1055095 - Intermittent test_expected.py TestExpected.test_element_selected_when_not_selected | 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-18 07:51:58 PDT for push 8cc06e7a1125

slave: tst-linux64-spot-545

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

{
08:19:32     INFO -  08-18 11:13:28.453 I/Gecko   (   44): MARIONETTE LOG: INFO: TEST-START: /builds/slave/test/build/tests/marionette/tests/testing/marionette/client/marionette/tests/unit/test_expected.py:test_element_selected
08:19:32     INFO -  08-18 11:13:28.473 I/Gecko   (   44): 1408374808473	Marionette	INFO	sendToClient: {"from":"0","value":null}, {b36989d3-d5f7-4aeb-ba71-e3bb9948b99b}, {b36989d3-d5f7-4aeb-ba71-e3bb9948b99b}
08:19:32     INFO -  08-18 11:13:28.953 I/Gecko   (   44): 1408374808954	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {92002158-ee84-450f-8e3f-a5993f6b31d0}, {92002158-ee84-450f-8e3f-a5993f6b31d0}
08:19:32     INFO -  08-18 11:13:28.983 I/Gecko   (   44): 1408374808993	Marionette	INFO	sendToClient: {"from":"0","value":{"ELEMENT":"{705dc9d8-49ea-43e9-9c70-14effdc52fc6}"}}, {5d3f3318-48e7-4f53-8f30-7edb9940ca27}, {5d3f3318-48e7-4f53-8f30-7edb9940ca27}
08:19:32     INFO -  08-18 11:13:29.083 I/Gecko   (   44): 1408374809085	Marionette	INFO	sendToClient: {"from":"0","value":true}, {84a9df76-30ab-4aa5-bae9-b01b5fb2d55f}, {84a9df76-30ab-4aa5-bae9-b01b5fb2d55f}
08:19:32     INFO -  08-18 11:13:29.563 I/Gecko   (   44): 1408374809568	Marionette	DEBUG	Got request: setContext, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140818073315","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":"setContext","parameters":{"value":"content"}}, id: {caead868-dd82-4cb0-a3a0-9ca468ce5616}
08:19:32     INFO -  08-18 11:13:29.563 I/Gecko   (   44): 1408374809570	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {caead868-dd82-4cb0-a3a0-9ca468ce5616}, {caead868-dd82-4cb0-a3a0-9ca468ce5616}
08:19:32     INFO -  08-18 11:13:29.653 I/Gecko   (   44): 1408374809661	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140818073315","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":"log('TEST-END: /builds/slave/test/build/tests/marionette/tests/testing/marionette/client/marionette/tests/unit/test_expected.py:test_element_selected')","newSandbox":true,"args":[],"filename":"marionette_test.py","line":356}}, id: {23fd3a6a-f69f-4a92-8404-8f616124d5c5}
08:19:32     INFO -  08-18 11:13:29.703 I/Gecko   (   44): MARIONETTE LOG: INFO: TEST-END: /builds/slave/test/build/tests/marionette/tests/testing/marionette/client/marionette/tests/unit/test_expected.py:test_element_selected
08:19:32     INFO -  08-18 11:13:29.723 I/Gecko   (   44): 1408374809723	Marionette	INFO	sendToClient: {"from":"0","value":null}, {23fd3a6a-f69f-4a92-8404-8f616124d5c5}, {23fd3a6a-f69f-4a92-8404-8f616124d5c5}
08:19:32     INFO -  08-18 11:13:29.753 I/Gecko   (   44): 1408374809754	Marionette	DEBUG	Got request: setTestName, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140818073315","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":"setTestName","parameters":{"value":null}}, id: {ca6a091d-517d-4809-b68f-2a0bcded4661}
08:19:32     INFO -  08-18 11:13:29.763 I/Gecko   (   44): 1408374809766	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {ca6a091d-517d-4809-b68f-2a0bcded4661}, {ca6a091d-517d-4809-b68f-2a0bcded4661}
08:19:32     INFO -  08-18 11:13:29.833 I/Gecko   (   44): 1408374809842	Marionette	INFO	sendToClient: {"from":"0","value":[["INFO","TEST-START: /builds/slave/test/build/tests/marionette/tests/testing/marionette/client/marionette/tests/unit/test_expected.py:test_element_selected","Mon Aug 18 2014 11:13:28 GMT-0400 (EDT)"],["INFO","TEST-END: /builds/slave/test/build/tests/marionette/tests/testing/marionette/client/marionette/tests/unit/test_expected.py:test_element_selected","Mon Aug 18 2014 11:13:29 GMT-0400 (EDT)"]]}, {1c05d9bb-e750-4545-9fe2-a00baaf455cb}, {1c05d9bb-e750-4545-9fe2-a00baaf455cb}
08:19:32     INFO -  08-18 11:13:29.903 I/Gecko   (   44): 1408374809912	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {24dde27c-b3f3-43f6-ba43-597e8ea24c70}, {24dde27c-b3f3-43f6-ba43-597e8ea24c70}
08:19:32     INFO -  08-18 11:13:30.443 I/Gecko   (   44): 1408374810445	Marionette	DEBUG	accepted connection on 127.0.0.1:35278
08:19:32     INFO -  08-18 11:13:30.563 I/Gecko   (   44): 1408374810569	Marionette	INFO	could not load listener into content for page: chrome://b2g/content/shell.html
08:19:32     INFO -  08-18 11:13:30.613 I/Gecko   (   44): 1408374810606	Marionette	INFO	sendToClient: {"from":"0","value":{"browserName":"B2G","browserVersion":"34.0a1","platformName":"ANDROID","platformVersion":"34.0a1","handlesAlerts":false,"nativeEvents":false,"rotatable":true,"secureSsl":false,"takesElementScreenshot":true,"takesScreenshot":true,"platform":"ANDROID","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","appBuildId":"20140818073315","device":"qemu","version":"34.0a1","b2g":true}}, {d59c9865-cbc3-43e0-91a2-598f2a197e9d}, {d59c9865-cbc3-43e0-91a2-598f2a197e9d}
08:19:32     INFO -  08-18 11:13:30.663 I/Gecko   (   44): 1408374810672	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {49006e26-9b37-4bac-bdb1-49e1f38e4851}, {49006e26-9b37-4bac-bdb1-49e1f38e4851}
08:19:32     INFO -  08-18 11:13:30.773 I/Gecko   (   44): 1408374810773	Marionette	DEBUG	Got request: setTestName, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"34.0a1","takesScreenshot":true,"appBuildId":"20140818073315","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":"setTestName","parameters":{"value":"test_expected.py TestExpected.test_element_selected_when_not_selected"}}, id: {15703d6c-8a13-47f3-a308-a6b82055ba20}
08:19:32     INFO -  08-18 11:13:30.773 I/Gecko   (   44): 1408374810779	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {15703d6c-8a13-47f3-a308-a6b82055ba20}, {15703d6c-8a13-47f3-a308-a6b82055ba20}
08:19:32     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.
08:19:32    ERROR -  TEST-UNEXPECTED-ERROR | test_expected.py TestExpected.test_element_selected_when_not_selected | TimeoutException: TimeoutException: Connection timed out
08:19:32     INFO -  Traceback (most recent call last):
08:19:32     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 153, in run
08:19:32     INFO -      self.setUp()
08:19:32     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 350, in setUp
08:19:32     INFO -      (self.filepath.replace('\\', '\\\\'), self.methodName))
08:19:32     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 1199, in execute_script
08:19:32     INFO -      filename=os.path.basename(frame[0]))
08:19:32     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/decorators.py", line 35, in _
08:19:32     INFO -      return func(*args, **kwargs)
08:19:32     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 617, in _send_message
08:19:32     INFO -      "Connection timed out", status=errors.ErrorCodes.TIMEOUT)
08:19:32     INFO -  TEST-INFO expected PASS | took 362201ms
}

Comment 1

[Ed Morley [:emorley]]

Mass-closing intermittent-failure bugs filed by me, that have not occurred recently and do not have the leave-open keyword set.

Comment 2

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=9604510
repository: mozilla-inbound
start_time: 2015-05-06T07:23:58
who: tomcat[at]mozilla[dot]com
machine: tst-linux32-spot-731
buildname: Ubuntu VM 12.04 mozilla-inbound opt test marionette-e10s
revision: 1ab2d798c5bb

TEST-UNEXPECTED-ERROR | test_expected.py TestExpected.test_element_present_not_present | TimeoutException: TimeoutException: Connection timed out
TEST-UNEXPECTED-ERROR | test_expected.py TestExpected.test_element_selected | TimeoutException: TimeoutException: Connection timed out
TEST-START | test_errors.py TestAllExceptions.test_properties
TEST-START | test_errors.py TestExceptionType.test_cause_string
TEST-START | test_errors.py TestExceptionType.test_cause_tuple
TEST-START | test_errors.py TestExceptionType.test_construction
TEST-START | test_errors.py TestExceptionType.test_defaults
TEST-START | test_errors.py TestExceptionType.test_str
Return code: 10

Comment 3

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=9772839
repository: mozilla-inbound
start_time: 2015-05-12T01:08:12
who: tomcat[at]mozilla[dot]com
machine: tst-linux32-spot-094
buildname: Ubuntu VM 12.04 mozilla-inbound pgo test marionette-e10s
revision: 48b7fceaeea9

TEST-UNEXPECTED-ERROR | test_expected.py TestExpected.test_element_present_not_present | TimeoutException: TimeoutException: Connection timed out
TEST-UNEXPECTED-ERROR | test_expected.py TestExpected.test_element_selected | TimeoutException: TimeoutException: Connection timed out
TEST-START | test_errors.py TestAllExceptions.test_properties
TEST-START | test_errors.py TestExceptionType.test_cause_string
TEST-START | test_errors.py TestExceptionType.test_cause_tuple
TEST-START | test_errors.py TestExceptionType.test_construction
TEST-START | test_errors.py TestExceptionType.test_defaults
TEST-START | test_errors.py TestExceptionType.test_str
Return code: 10

Comment 4

[BMO Automation]

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