Closed Bug 1465727 Opened 7 years ago Closed 7 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)

Product:
Testing
Component:
Marionette Client and Harness
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
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: 7 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.