1025306 - Intermittent test_log.py test_log.TestLogChrome.test_that_we_can_clear_the_logs | TimeoutException: TimeoutException: Connection timed out

Status: RESOLVED WORKSFORME

(Testing :: Marionette Client and Harness, defect)

Description

[Ed Morley [:emorley]]

b2g_emulator_vm try opt test marionette-webapi on 2014-06-09 02:24:21 PDT for push 8fa5dc94a2d2

slave: tst-linux64-spot-1064

https://tbpl.mozilla.org/php/getParsedLog.php?id=41345361&tree=Try

{
02:33:24     INFO -  TEST-START test_log.py
02:33:27     INFO -  test_log_basic (test_log.TestLog) ... ok
02:33:29     INFO -  test_log_script (test_log.TestLog) ... ok
02:33:30     INFO -  test_that_we_can_clear_the_logs (test_log.TestLog) ... ok
02:33:32     INFO -  test_log_basic (test_log.TestLogChrome) ... ok
02:33:34     INFO -  test_log_script (test_log.TestLogChrome) ... ok
02:39:38     INFO -  test_that_we_can_clear_the_logs (test_log.TestLogChrome) ... ERROR
02:39:38     INFO -  ======================================================================
02:39:38     INFO -  ERROR: None
02:39:38     INFO -  ----------------------------------------------------------------------
02:39:38     INFO -  Traceback (most recent call last):
02:39:38     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 198, in run
02:39:38     INFO -      self.tearDown()
02:39:38     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 349, in tearDown
02:39:38     INFO -      self.marionette.test_name = None
02:39:38     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 758, in test_name
02:39:38     INFO -      if self._send_message('setTestName', 'ok', value=test_name):
02:39:38     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/decorators.py", line 35, in _
02:39:38     INFO -      return func(*args, **kwargs)
02:39:38     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 616, in _send_message
02:39:38     INFO -      "Connection timed out", status=ErrorCodes.TIMEOUT)
02:39:38     INFO -  TimeoutException: TimeoutException: Connection timed out
02:39:38     INFO -  Most recent errors/exceptions are:
02:39:38     INFO -  06-09 05:33:33.438 I/Gecko   (   44): 1402306413445	Marionette	INFO	sendToClient: {"from":"0","value":[["ERROR","some error","Mon Jun 09 2014 05:33:33 GMT-0400 (EDT)"]]}, {79af1436-ef8c-4fca-9f7b-7b489ce1545a}, {79af1436-ef8c-4fca-9f7b-7b489ce1545a}
02:39:38     INFO -  06-09 05:33:33.629 I/Gecko   (   44): 1402306413629	Marionette	DEBUG	Got request: executeWithCallback, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"32.0a1","takesScreenshot":true,"appBuildId":"20140608143320","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"32.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"32.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeAsyncScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"log('some more errors', 'ERROR'); finish();","newSandbox":true,"args":[],"filename":"test_log.py","line":41}}, id: {3c493217-3f90-4c3c-9b20-f3fa54872395}
02:39:38     INFO -  06-09 05:33:33.639 I/Gecko   (   44): MARIONETTE LOG: ERROR: some more errors
02:39:38     INFO -  06-09 05:33:33.668 I/Gecko   (   44): 1402306413671	Marionette	INFO	sendToClient: {"from":"0","value":[["ERROR","some more errors","Mon Jun 09 2014 05:33:33 GMT-0400 (EDT)"]]}, {c009da2a-c274-401b-9a03-771ed1bc95da}, {c009da2a-c274-401b-9a03-771ed1bc95da}
02:39:38     INFO -  06-09 05:33:35.178 I/Gecko   (   44): 1402306415184	Marionette	INFO	sendToClient: {"from":"0","value":[["ERROR","I AM ERROR","Mon Jun 09 2014 05:33:35 GMT-0400 (EDT)"]]}, {a0586027-8e0e-40b6-ada2-847c72f29627}, {a0586027-8e0e-40b6-ada2-847c72f29627}
02:39:38    ERROR -  TEST-UNEXPECTED-FAIL | test_log.py test_log.TestLogChrome.test_that_we_can_clear_the_logs | TimeoutException: TimeoutException: Connection timed out
02:39:38     INFO -  ----------------------------------------------------------------------
02:39:38     INFO -  Ran 6 tests in 372.666s
}

Comment 1

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=42356974&tree=Mozilla-B2g28-v1.3
b2g_emulator_vm mozilla-b2g28_v1_3 opt test marionette-webapi on 2014-06-24 06:33:09
revision: 380b3bdb890c
slave: tst-linux64-spot-225

TEST-UNEXPECTED-FAIL | test_log.py test_log.TestLogChrome.test_that_we_can_clear_the_logs | TimeoutException: TimeoutException: socket.timeout
TEST-UNEXPECTED-FAIL | test_outgoing_answer_hangup_oncallschanged.js | ScriptTimeoutException: ScriptTimeoutException: timed out
TEST-UNEXPECTED-FAIL | test_outgoing_hangup_alerting.js | MarionetteException: MarionetteException: Emulator callback still pending when finish() called
Exception TypeError: TypeError("'NoneType' object is not callable",) in <bound method MarionetteTestRunner.cleanup of <__main__.MarionetteTestRunner object at 0x1ccb6d0>> ignored
Exception TypeError: TypeError("'NoneType' object is not callable",) in <bound method MozHttpd.stop of <mozhttpd.mozhttpd.MozHttpd object at 0x1d13050>> ignored
Return code: 10

Comment 2

[David Burns :automatedtester]

Mass closing as we have not seen these intermittents in over 2 months

Comment 3

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=48784209&tree=B2g-Inbound
b2g_emulator_vm b2g-inbound opt test marionette-webapi on 2014-09-24 07:33:28
revision: fd8fbe038ccc
slave: tst-linux64-spot-1111

TEST-UNEXPECTED-ERROR | test_log.py TestLogChrome.test_that_we_can_clear_the_logs | TimeoutException: TimeoutException: Connection timed out
Return code: 10

Comment 4

[BMO Automation]

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