859417 - [Tracking bug] Marionette socket.timeout errors

Status: RESOLVED FIXED

Description

[Jonathan Griffin (:jgriffin)]

This is a tracking bug that will attempt to make sense out of all the socket.timeout errors we're seeing.

Comment 1

[Jonathan Griffin (:jgriffin)]

bug 855458:  this looks like a transport problem; socket.timeout occurred during finish() even though the actor had sent the correct data to the client.  A little later in the log we also see:

12:11:48  WARNING -  E/GeckoConsole(  237): [JavaScript Error: "this._actorPool is null" {file: "chrome://global/content/devtools/dbg-server.js" line: 582}]

https://tbpl.mozilla.org/php/getParsedLog.php?id=21169440&full=1&branch=mozilla-inbound#error1

Comment 2

[Jonathan Griffin (:jgriffin)]

bug 845291:  this is caused by a B2G crash; hopefully crash detection will help us with that soon.

https://tbpl.mozilla.org/php/getParsedLog.php?id=20894770&full=1&branch=mozilla-inbound

Comment 3

[Jonathan Griffin (:jgriffin)]

bug 839842:  another case in which it looks like the actor is behaving correctly, but the client isn't receiving some data.

https://tbpl.mozilla.org/php/getParsedLog.php?id=20120216&full=1&branch=mozilla-inbound

Comment 4

[Jonathan Griffin (:jgriffin)]

bug 830622:  a case on desktop Firefox in which we may not be waiting long enough after the browser starts before running the test.

https://tbpl.mozilla.org/php/getParsedLog.php?id=21411807&full=1&branch=mozilla-aurora

Comment 5

[Jonathan Griffin (:jgriffin)]

bug 852709:  another case in which it looks like the actor is sending the correct data, but shortly after we see:

E/GeckoConsole(  247): [JavaScript Error: "this._actorPool is null" {file: "chrome://global/content/devtools/dbg-server.js" line: 589}]

https://tbpl.mozilla.org/php/getParsedLog.php?id=20834959&full=1&branch=mozilla-inbound

Comment 6

[Jonathan Griffin (:jgriffin)]

bug 843262: another case in which it seems the actor is doing the right thing.  Here, though, we have an actorpool error showing up before the test failure:

14:17:39  WARNING -  E/GeckoConsole(  277): [JavaScript Error: "this._actorPool is null" {file: "chrome://global/content/devtools/dbg-server.js" line: 589}]

https://tbpl.mozilla.org/php/getParsedLog.php?id=20262625&full=1&branch=mozilla-central

Comment 7

[Jonathan Griffin (:jgriffin)]

bug 842543: another apparent transport problem near an actorpool error.

https://tbpl.mozilla.org/php/getParsedLog.php?id=19868582&full=1&branch=mozilla-central

Comment 8

[Jonathan Griffin (:jgriffin)]

bug 842294: same transport problem + actorPool error:

https://tbpl.mozilla.org/php/getParsedLog.php?id=19840908&full=1&branch=mozilla-central

Comment 9

[Jonathan Griffin (:jgriffin)]

(In reply to Jonathan Griffin (:jgriffin) from comment #8)
> bug 842294: same transport problem + actorPool error:
> 
> https://tbpl.mozilla.org/php/getParsedLog.
> php?id=19840908&full=1&branch=mozilla-central

That should have been bug 842297.

Comment 10

[Jonathan Griffin (:jgriffin)]

bug 835540:  similar to 855458 in that it looks like a transport error during finish(), but in this case there is no actorPool error in the log.

https://tbpl.mozilla.org/php/getParsedLog.php?id=20184732&full=1&branch=mozilla-b2g18_v1_0_1

Comment 11

[Jonathan Griffin (:jgriffin)]

bug 835183: transport problem + actorPool error

https://tbpl.mozilla.org/php/getParsedLog.php?id=20044014&full=1&branch=mozilla-inbound

Comment 12

[Jonathan Griffin (:jgriffin)]

bug 823086: transport problem + actorPool error

https://tbpl.mozilla.org/php/getParsedLog.php?id=20735418&full=1&branch=mozilla-inbound

Comment 13

[Jonathan Griffin (:jgriffin)]

bug 823076: transport problem + actorPool error:

09:55:02  WARNING -  E/GeckoConsole(  276): [JavaScript Error: "this._actorPool is null" {file: "chrome://global/content/devtools/dbg-server.js" line: 589}]

https://tbpl.mozilla.org/php/getParsedLog.php?id=20108931&full=1&branch=mozilla-inbound

Comment 14

[Jonathan Griffin (:jgriffin)]

bug 842274: this is unlike any of the others.  We get a socket.timeout during newSession(); no actorPool error in the logs.  Possibly a (now-fixed) sync issue?

https://tbpl.mozilla.org/php/getParsedLog.php?id=19837398&full=1&branch=mozilla-central

Comment 15

[Jonathan Griffin (:jgriffin)]

bug 820802: transport problem + actorPool error

https://tbpl.mozilla.org/php/getParsedLog.php?id=21043835&full=1&branch=mozilla-inbound

Comment 16

[Jonathan Griffin (:jgriffin)]

bug 844416: more of the same (actorPool error)

https://tbpl.mozilla.org/php/getParsedLog.php?id=20013566&full=1&branch=mozilla-inbound

Comment 17

[Jonathan Griffin (:jgriffin)]

bug 844327: actorPool error

https://tbpl.mozilla.org/php/getParsedLog.php?id=21163160&full=1&branch=mozilla-inbound

Comment 18

[Jonathan Griffin (:jgriffin)]

bug 842453: our perennial actorPool favorite!

https://tbpl.mozilla.org/php/getParsedLog.php?id=19863092&full=1&branch=mozilla-inbound

Comment 19

[Jonathan Griffin (:jgriffin)]

bug 837978: ye olde actorPool error strikes again

https://tbpl.mozilla.org/php/getParsedLog.php?id=20909697&full=1&branch=mozilla-b2g18

Comment 20

[Jonathan Griffin (:jgriffin)]

bug 837827: actorPool

https://tbpl.mozilla.org/php/getParsedLog.php?id=21429443&full=1&branch=mozilla-b2g18

Comment 21

[Jonathan Griffin (:jgriffin)]

Zac pointed out a socket.timeout error that occurred recently during a Gaia-UI-test on v1-train (with mozilla-b2g18 gecko):

http://qa-selenium.mv.mozilla.com:8080/job/b2g.unagi.gaia.v1-train.ui.xfail/238/testReport/junit/%28root%29/TestDialerAirplaneMode/test_dialer_airplane_mode/

Traceback (most recent call last):
  File "/var/jenkins/workspace/b2g.unagi.gaia.v1-train.ui.xfail/gaia-ui-tests/gaiatest/tests/dialer/test_dialer_airplane_mode.py", line 27, in test_dialer_airplane_mode
    phone.keypad.call_number(test_phone_number)
  File "/var/jenkins/workspace/b2g.unagi.gaia.v1-train.ui.xfail/gaia-ui-tests/gaiatest/apps/phone/regions/keypad.py", line 40, in call_number
    return self.tap_call_button()
  File "/var/jenkins/workspace/b2g.unagi.gaia.v1-train.ui.xfail/gaia-ui-tests/gaiatest/apps/phone/regions/keypad.py", line 45, in tap_call_button
    return CallScreen(self.marionette)
  File "/var/jenkins/workspace/b2g.unagi.gaia.v1-train.ui.xfail/gaia-ui-tests/gaiatest/apps/phone/regions/call_screen.py", line 25, in __init__
    self.marionette.switch_to_frame(call_screen)
  File "/var/jenkins/workspace/b2g.unagi.gaia.v1-train.ui.xfail/.env/local/lib/python2.7/site-packages/marionette_client-0.5.23-py2.7.egg/marionette/marionette.py", line 475, in switch_to_frame
    response = self._send_message('switchToFrame', 'ok', element=frame.id, focus=focus)
  File "/var/jenkins/workspace/b2g.unagi.gaia.v1-train.ui.xfail/.env/local/lib/python2.7/site-packages/marionette_client-0.5.23-py2.7.egg/marionette/marionette.py", line 300, in _send_message
    raise TimeoutException(message='socket.timeout', status=ErrorCodes.TIMEOUT, stacktrace=None)
TimeoutException: socket.timeout

This does not look similar to any of the cases I've already listed above.

Comment 22

[Robert Wood [:rwood]]

I am seeing a similar issue with the gaia-ui add_contact endurance test (Inari with b2g18_v1_0_1):

Traceback (most recent call last):
  File "/home/rwood/gaia-ui-tests/gaiatest/tests/endurance/test_endurance_add_contact.py", line 22, in test_endurance_add_contact
    self.drive(test=self.add_contact, app='contacts')
  File "/home/rwood/gaia-ui-tests/gaiatest/gaia_test.py", line 701, in drive
    self.test_method()
  File "/home/rwood/gaia-ui-tests/gaiatest/tests/endurance/test_endurance_add_contact.py", line 29, in add_contact
    new_contact_form = self.contacts.tap_new_contact()
  File "/home/rwood/gaia-ui-tests/gaiatest/apps/contacts/app.py", line 37, in tap_new_contact
    self.marionette.tap(self.marionette.find_element(*self._new_contact_button_locator))
  File "/usr/local/lib/python2.7/dist-packages/marionette_client-0.5.27-py2.7.egg/marionette/marionette_touch.py", line 31, in tap
    self.execute_script("%s.tap(arguments[0], null, null, null, null, arguments[1]);" % self.library_name, [element, send_all])
  File "/usr/local/lib/python2.7/dist-packages/marionette_client-0.5.27-py2.7.egg/marionette/marionette.py", line 605, in execute_script
    scriptTimeout=script_timeout)
  File "/usr/local/lib/python2.7/dist-packages/marionette_client-0.5.27-py2.7.egg/marionette/marionette.py", line 334, in _send_message
    raise TimeoutException(message='socket.timeout', status=ErrorCodes.TIMEOUT, stacktrace=None)
TEST-UNEXPECTED-FAIL | test_endurance_add_contact.py TestEnduranceAddContact.test_endurance_add_contact | TimeoutException: socket.timeout

Comment 23

[Jonathan Griffin (:jgriffin)]

See also bug 898074

Comment 24

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

Bug 871475 hits a perm-orange that the some test pages loading get stuck in calling syscall epoll_wait.  The main functionality in that pull request is actually not taking effect during the process.  Actually we can reproduce the same error without that pull request but by appending "-net nic -net nic -net user" as qemu tail args when running "./run-emulator.sh".  Adb shell is still working at the time and test process continues after timed out.  This is happening on all emulator versions -- ics, jb, and kk.  Have cherry-picked some smc91c11x fixes from upstream kernel but still in vain.  Don't know if that's related to this bug.

Comment 25

[David Burns :automatedtester]

All dependent bugs are closed