Closed Bug 859417 Opened 8 years ago Closed 4 years ago

[Tracking bug] Marionette socket.timeout errors

Categories

(Testing :: Marionette, defect)

Product:
Testing
Component:
Marionette
Platform:
x86_64
Windows 7
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED FIXED

People

(Reporter: jgriffin, Unassigned)

References

Details

This is a tracking bug that will attempt to make sense out of all the socket.timeout errors we're seeing.
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
Depends on: 855458
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
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
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
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
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
Depends on: 845291
Depends on: 839842, 830622, 852709, 843262
bug 842543: another apparent transport problem near an actorpool error.

https://tbpl.mozilla.org/php/getParsedLog.php?id=19868582&full=1&branch=mozilla-central
Depends on: 842543
(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.
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
Depends on: 835540
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
Depends on: 823076
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
Depends on: 842274
Depends on: 844416
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.
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
Depends on: 830149
Depends on: 832662
Depends on: 837828
Depends on: 837912
Depends on: 838353
See also bug 898074
Depends on: 967252
Depends on: 967021
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.
All dependent bugs are closed
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
You need to log in before you can comment on or make changes to this bug.