1532903 - [mozdevice] Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_date_time_value.py TestDateTime.test_set_time | ADBTimeoutError: args: /builds/worker/workspace/build/android-sdk-linux/platform-tools/adb-5554 wait-for-device shell ps

Status: RESOLVED DUPLICATE of bug 1500266

(Firefox for Android Graveyard :: Testing, defect, P5)

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: rgurzau [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=232086239&repo=autoland

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

[task 2019-03-06T06:51:31.908Z] 06:51:31 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_date_time_value.py TestDateTime.test_set_time
[task 2019-03-06T06:56:36.531Z] 06:56:36 WARNING - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_date_time_value.py TestDateTime.test_set_time | ADBTimeoutError: args: /builds/worker/workspace/build/android-sdk-linux/platform-tools/adb-5554 wait-for-device shell ps; echo adb_returncode=$?, exitcode: None, stdout:
[task 2019-03-06T06:56:36.531Z] 06:56:36 INFO - Traceback (most recent call last):
[task 2019-03-06T06:56:36.531Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run
[task 2019-03-06T06:56:36.531Z] 06:56:36 INFO - testMethod()
[task 2019-03-06T06:56:36.532Z] 06:56:36 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_date_time_value.py", line 30, in test_set_time
[task 2019-03-06T06:56:36.532Z] 06:56:36 INFO - self.marionette.navigate(inline("<input id='time-test' type='time'/>"))
[task 2019-03-06T06:56:36.533Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1638, in navigate
[task 2019-03-06T06:56:36.533Z] 06:56:36 INFO - {"url": url})
[task 2019-03-06T06:56:36.533Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 36, in _
[task 2019-03-06T06:56:36.533Z] 06:56:36 INFO - m._handle_socket_failure()
[task 2019-03-06T06:56:36.534Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 826, in _handle_socket_failure
[task 2019-03-06T06:56:36.534Z] 06:56:36 INFO - returncode = self.instance.runner.wait(timeout=self.shutdown_timeout)
[task 2019-03-06T06:56:36.534Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozrunner/base/device.py", line 131, in wait
[task 2019-03-06T06:56:36.534Z] 06:56:36 INFO - while self.is_running():
[task 2019-03-06T06:56:36.535Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozrunner/base/runner.py", line 176, in is_running
[task 2019-03-06T06:56:36.535Z] 06:56:36 INFO - return self.returncode is None
[task 2019-03-06T06:56:36.535Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozrunner/base/device.py", line 114, in returncode
[task 2019-03-06T06:56:36.535Z] 06:56:36 INFO - if self.app_ctx.device.process_exist(self.app_ctx.remote_process):
[task 2019-03-06T06:56:36.535Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py", line 2513, in process_exist
[task 2019-03-06T06:56:36.535Z] 06:56:36 INFO - if self._pidof(app, timeout=timeout):
[task 2019-03-06T06:56:36.536Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py", line 854, in _pidof
[task 2019-03-06T06:56:36.536Z] 06:56:36 INFO - procs = self.get_process_list(timeout=timeout)
[task 2019-03-06T06:56:36.536Z] 06:56:36 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py", line 2352, in get_process_list
[task 2019-03-06T06:56:36.536Z] 06:56:36 CRITICAL - raise ADBTimeoutError("%s" % adb_process)
[task 2019-03-06T06:56:36.536Z] 06:56:36 INFO - TEST-INFO took 304623ms
[task 2019-03-06T07:01:36.549Z] 07:01:36 CRITICAL - test_end for testing/marionette/harness/marionette_harness/tests/unit/test_date_time_value.py TestDateTime.test_set_time logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_date_time_value.TestDateTime", "method_name": "test_set_time"}, "expected": "PASS", "test": "testing/marionette/harness/marionette_harness/tests/unit/test_date_time_value.py TestDateTime.test_set_time", "message": "ADBTimeoutError: args: /builds/worker/workspace/build/android-sdk-linux/platform-tools/adb-5554 wait-for-device shell ps; echo adb_returncode=$?, exitcode: None, stdout:", "stack": "Traceback (most recent call last):\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py\", line 190, in run\n self.tearDown()\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py\", line 338, in tearDown\n self.marionette.start_session()\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py\", line 26, in _\n return func(*args, **kwargs)\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 1270, in start_session\n returncode = self.instance.runner.returncode\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozrunner/base/device.py\", line 114, in returncode\n if self.app_ctx.device.process_exist(self.app_ctx.remote_process):\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py\", line 2513, in process_exist\n if self._pidof(app, timeout=timeout):\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py\", line 854, in _pidof\n procs = self.get_process_list(timeout=timeout)\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py\", line 2352, in get_process_list\n raise ADBTimeoutError(\"%s\" % adb_process)\n"}
[task 2019-03-06T07:16:37.067Z] 07:16:37 CRITICAL - {"thread": "MainThread", "level": "ERROR", "pid": 1104, "source": "Marionette test runner", "time": 1551856597064, "action": "log", "message": "Failure during harness execution", "stack": "Traceback (most recent call last):\n\n File \"/builds/worker/workspace/build/tests/marionette/harness/marionette_harness/runtests.py\", line 94, in cli\n failed = harness_instance.run()\n\n File \"/builds/worker/workspace/build/tests/marionette/harness/marionette_harness/runtests.py\", line 76, in run\n runner.cleanup()\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/base.py\", line 1120, in cleanup\n self.marionette.instance.close(clean=True)\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/geckoinstance.py\", line 486, in close\n super(FennecInstance, self).close(clean)\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/geckoinstance.py\", line 351, in close\n self.runner.stop()\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozrunner/base/device.py\", line 88, in stop\n if not sig and self.is_running():\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozrunner/base/runner.py\", line 176, in is_running\n return self.returncode is None\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozrunner/base/device.py\", line 114, in returncode\n if self.app_ctx.device.process_exist(self.app_ctx.remote_process):\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py\", line 2513, in process_exist\n if self._pidof(app, timeout=timeout):\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py\", line 854, in _pidof\n procs = self.get_process_list(timeout=timeout)\n\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/adb.py\", line 2352, in get_process_list\n raise ADBTimeoutError(\"%s\" % adb_process)\n\nADBTimeoutError: args: /builds/worker/workspace/build/android-sdk-linux/platform-tools/adb-5554 wait-for-device shell ps; echo adb_returncode=$?, exitcode: None, stdout: \n"}
[task 2019-03-06T07:16:37.082Z] 07:16:37 ERROR - Return code: 1
[task 2019-03-06T07:16:37.082Z] 07:16:37 ERROR - No tests run or test summary not found
[task 2019-03-06T07:16:37.082Z] 07:16:37 INFO - TinderboxPrint: marionette<br/><em class="testfail">T-FAIL</em>

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+1]]

This is more a mozdevice issue as it looks like. Geoff or Bob, any ideas?

Comment 2

[Bob Clary [:bc] (inactive)]

whimboo: Yes, the basic error is the emulator (in this case) crashed or became unresponsive and after an attempt to execute ps on the device failed to respond within the time limit (5 minutes), we raised an ADBTimeoutError. The problem for Sheriffs with this error is it can occur for any test and any location in the code where we contact the device. The particular test being run and the traceback aren't of any help and if fact cause confusion in that it appears different problems are occurring when it is really a single issue of the emulator/device failing.

The bug suggestion in the Failure Summary for this job did suggest the appropriate bug 1500266. Duping there.

Comment 3

[Intermittent Failures Robot]

1 failures in 2818 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-em-4-3-armv7-api16: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1532903&startday=2019-03-04&endday=2019-03-10&tree=all