Closed Bug 1465727 Opened 6 years ago Closed 6 years ago

Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_cookies.py CookieTest.test_we_get_required_elements_when_available | IOError: Process has been unexpectedly closed (Exit code: 0) (Reason: No data received over socket)

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1454680

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=181005504&repo=mozilla-central

https://queue.taskcluster.net/v1/task/R9z7GbfpTZSH5LaeuJKPcQ/runs/0/artifacts/public/logs/live_backing.log

[task 2018-05-30T23:27:22.137Z] 23:27:22     INFO -  TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_cookies.py CookieTest.test_we_get_required_elements_when_available
[task 2018-05-30T23:27:26.486Z] 23:27:26  WARNING -  TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_cookies.py CookieTest.test_we_get_required_elements_when_available | IOError: Process has been unexpectedly closed (Exit code: 0) (Reason: No data received over socket)
[task 2018-05-30T23:27:26.486Z] 23:27:26     INFO -  Traceback (most recent call last):
[task 2018-05-30T23:27:26.487Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 140, in run
[task 2018-05-30T23:27:26.487Z] 23:27:26     INFO -      self.setUp()
[task 2018-05-30T23:27:26.488Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_cookies.py", line 20, in setUp
[task 2018-05-30T23:27:26.489Z] 23:27:26     INFO -      self.marionette.navigate(test_url)
[task 2018-05-30T23:27:26.490Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1592, in navigate
[task 2018-05-30T23:27:26.491Z] 23:27:26     INFO -      {"url": url})
[task 2018-05-30T23:27:26.491Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 31, in _
[task 2018-05-30T23:27:26.492Z] 23:27:26     INFO -      m._handle_socket_failure()
[task 2018-05-30T23:27:26.492Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 825, in _handle_socket_failure
[task 2018-05-30T23:27:26.493Z] 23:27:26     INFO -      reraise(IOError, message.format(returncode=returncode, reason=val), tb)
[task 2018-05-30T23:27:26.494Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2018-05-30T23:27:26.494Z] 23:27:26     INFO -      return func(*args, **kwargs)
[task 2018-05-30T23:27:26.494Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 733, in _send_message
[task 2018-05-30T23:27:26.495Z] 23:27:26     INFO -      msg = self.client.request(name, params)
[task 2018-05-30T23:27:26.495Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 273, in request
[task 2018-05-30T23:27:26.496Z] 23:27:26     INFO -      return self.receive()
[task 2018-05-30T23:27:26.496Z] 23:27:26     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 159, in receive
[task 2018-05-30T23:27:26.497Z] 23:27:26     INFO -      raise socket.error("No data received over socket")
[task 2018-05-30T23:27:26.497Z] 23:27:26     INFO -  TEST-INFO took 4349ms
[task 2018-05-30T23:27:26.912Z] 23:27:26     INFO -  TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_context.py TestSetContext.test_invalid_context
[task 2018-05-30T23:27:27.119Z] 23:27:27  WARNING -  TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_context.py TestSetContext.test_invalid_context | IOError: Port localhost:2828 is unavailable.
[task 2018-05-30T23:27:27.120Z] 23:27:27     INFO -  Traceback (most recent call last):
[task 2018-05-30T23:27:27.120Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 140, in run
[task 2018-05-30T23:27:27.120Z] 23:27:27     INFO -      self.setUp()
[task 2018-05-30T23:27:27.121Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_context.py", line 15, in setUp
[task 2018-05-30T23:27:27.122Z] 23:27:27     INFO -      super(ContextTestCase, self).setUp()
[task 2018-05-30T23:27:27.123Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 331, in setUp
[task 2018-05-30T23:27:27.123Z] 23:27:27     INFO -      super(MarionetteTestCase, self).setUp()
[task 2018-05-30T23:27:27.123Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 254, in setUp
[task 2018-05-30T23:27:27.123Z] 23:27:27     INFO -      self.marionette.start_session()
[task 2018-05-30T23:27:27.124Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2018-05-30T23:27:27.124Z] 23:27:27     INFO -      return func(*args, **kwargs)
[task 2018-05-30T23:27:27.124Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1211, in start_session
[task 2018-05-30T23:27:27.125Z] 23:27:27     INFO -      self.start_binary(timeout)
[task 2018-05-30T23:27:27.125Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 627, in start_binary
[task 2018-05-30T23:27:27.126Z] 23:27:27     INFO -      raise IOError("Port {0}:{1} is unavailable.".format(self.host, self.port))
[task 2018-05-30T23:27:27.126Z] 23:27:27     INFO -  TEST-INFO took 206ms
[task 2018-05-30T23:27:27.330Z] 23:27:27     INFO -  TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_context.py TestSetContext.test_switch_context
[task 2018-05-30T23:27:27.436Z] 23:27:27  WARNING -  TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_context.py TestSetContext.test_switch_context | IOError: Port localhost:2828 is unavailable.
[task 2018-05-30T23:27:27.437Z] 23:27:27     INFO -  Traceback (most recent call last):
[task 2018-05-30T23:27:27.437Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 140, in run
[task 2018-05-30T23:27:27.437Z] 23:27:27     INFO -      self.setUp()
[task 2018-05-30T23:27:27.438Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_context.py", line 15, in setUp
[task 2018-05-30T23:27:27.439Z] 23:27:27     INFO -      super(ContextTestCase, self).setUp()
[task 2018-05-30T23:27:27.439Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 331, in setUp
[task 2018-05-30T23:27:27.439Z] 23:27:27     INFO -      super(MarionetteTestCase, self).setUp()
[task 2018-05-30T23:27:27.439Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 254, in setUp
[task 2018-05-30T23:27:27.439Z] 23:27:27     INFO -      self.marionette.start_session()
[task 2018-05-30T23:27:27.440Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2018-05-30T23:27:27.440Z] 23:27:27     INFO -      return func(*args, **kwargs)
[task 2018-05-30T23:27:27.441Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1211, in start_session
[task 2018-05-30T23:27:27.441Z] 23:27:27     INFO -      self.start_binary(timeout)
[task 2018-05-30T23:27:27.441Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 627, in start_binary
[task 2018-05-30T23:27:27.442Z] 23:27:27     INFO -      raise IOError("Port {0}:{1} is unavailable.".format(self.host, self.port))
[task 2018-05-30T23:27:27.442Z] 23:27:27     INFO -  TEST-INFO took 106ms
[task 2018-05-30T23:27:27.648Z] 23:27:27     INFO -  TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_context.py TestUsingContext.test_exception_raised_while_in_with_block_is_propagated
[task 2018-05-30T23:27:27.855Z] 23:27:27  WARNING -  TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_context.py TestUsingContext.test_exception_raised_while_in_with_block_is_propagated | IOError: Port localhost:2828 is unavailable.
[task 2018-05-30T23:27:27.855Z] 23:27:27     INFO -  Traceback (most recent call last):
[task 2018-05-30T23:27:27.855Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 140, in run
[task 2018-05-30T23:27:27.856Z] 23:27:27     INFO -      self.setUp()
[task 2018-05-30T23:27:27.857Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_context.py", line 15, in setUp
[task 2018-05-30T23:27:27.857Z] 23:27:27     INFO -      super(ContextTestCase, self).setUp()
[task 2018-05-30T23:27:27.858Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 331, in setUp
[task 2018-05-30T23:27:27.858Z] 23:27:27     INFO -      super(MarionetteTestCase, self).setUp()
[task 2018-05-30T23:27:27.858Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 254, in setUp
[task 2018-05-30T23:27:27.859Z] 23:27:27     INFO -      self.marionette.start_session()
[task 2018-05-30T23:27:27.859Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2018-05-30T23:27:27.860Z] 23:27:27     INFO -      return func(*args, **kwargs)
[task 2018-05-30T23:27:27.860Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1211, in start_session
[task 2018-05-30T23:27:27.861Z] 23:27:27     INFO -      self.start_binary(timeout)
[task 2018-05-30T23:27:27.861Z] 23:27:27     INFO -    File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 627, in start_binary
[task 2018-05-30T23:27:27.862Z] 23:27:27     INFO -      raise IOError("Port {0}:{1} is unavailable.".format(self.host, self.port))
[task 2018-05-30T23:27:27.862Z] 23:27:27     INFO -  TEST-INFO took 207ms
The logcat log shows a crash with a probably helpful stack. Maybe this is related to the crashes we see on bug 1454680.

https://taskcluster-artifacts.net/R9z7GbfpTZSH5LaeuJKPcQ/0/public/test_info//logcat-emulator-5554.log


05-30 16:27:25.286   783   783 D GeckoToolbar: onTabChanged: LOCATION_CHANGE
05-30 16:27:25.306   783   783 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: LOCATION_CHANGE
05-30 16:27:25.527   783   783 D AndroidRuntime: Shutting down VM
05-30 16:27:25.547   783   783 W dalvikvm: threadid=1: thread exiting with uncaught exception (group=0x414c8700)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: >>> REPORTING UNCAUGHT EXCEPTION FROM THREAD 1 ("main")
05-30 16:27:25.656   783   783 E GeckoCrashHandler: java.lang.NullPointerException
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.FrameLayout.onLayout(FrameLayout.java:400)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.setChildFrame(LinearLayout.java:1671)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.layoutVertical(LinearLayout.java:1525)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.onLayout(LinearLayout.java:1434)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.RelativeLayout.onLayout(RelativeLayout.java:1076)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at org.mozilla.gecko.GeckoApp$MainLayout.onLayout(GeckoApp.java:2374)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.RelativeLayout.onLayout(RelativeLayout.java:1076)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.FrameLayout.onLayout(FrameLayout.java:448)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.setChildFrame(LinearLayout.java:1671)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.layoutVertical(LinearLayout.java:1525)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.onLayout(LinearLayout.java:1434)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.FrameLayout.onLayout(FrameLayout.java:448)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.setChildFrame(LinearLayout.java:1671)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.layoutVertical(LinearLayout.java:1525)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.LinearLayout.onLayout(LinearLayout.java:1434)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.widget.FrameLayout.onLayout(FrameLayout.java:448)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.View.layout(View.java:14289)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewGroup.layout(ViewGroup.java:4562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewRootImpl.performLayout(ViewRootImpl.java:1976)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewRootImpl.performTraversals(ViewRootImpl.java:1730)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewRootImpl.doTraversal(ViewRootImpl.java:1004)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.ViewRootImpl$TraversalRunnable.run(ViewRootImpl.java:5481)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.Choreographer$CallbackRecord.run(Choreographer.java:749)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.Choreographer.doCallbacks(Choreographer.java:562)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.Choreographer.doFrame(Choreographer.java:532)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.view.Choreographer$FrameDisplayEventReceiver.run(Choreographer.java:735)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.os.Handler.handleCallback(Handler.java:730)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.os.Handler.dispatchMessage(Handler.java:92)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.os.Looper.loop(Looper.java:137)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at android.app.ActivityThread.main(ActivityThread.java:5103)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at java.lang.reflect.Method.invokeNative(Native Method)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at java.lang.reflect.Method.invoke(Method.java:525)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:737)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:553)
05-30 16:27:25.656   783   783 E GeckoCrashHandler: 	at dalvik.system.NativeStart.main(Native Method)

https://dxr.mozilla.org/mozilla-central/rev/5866d6685849311f057e7e229b9ace63a2641c29/mobile/android/base/java/org/mozilla/gecko/GeckoApp.java#2374

Is that something we could work with to figure out what's wrong?
Flags: needinfo?(snorp)
This one also looks like an Android bug to me. I don't see anything actionable.
Flags: needinfo?(snorp)
In that case it might be good to completely stop running the tests for Android debug builds. 

Duping to bug 1454680.
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
Product: Testing → Remote Protocol
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in before you can comment on or make changes to this bug.