Closed Bug 1770189 Opened 3 years ago Closed 3 years ago

Intermittent mozdevice.adb.ADBProcessError: args: adb wait-for-device shell getprop ro.build.version.sdk; echo adb_returncode=$?, exitcode: 1, stdout: error: closed

Categories

(Testing :: General, defect, P5)

Product:
Testing
Component:
General
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED FIXED

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=378630081&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dT02fSQPRWa7Osmaci1Qxg/runs/0/artifacts/public/logs/live_backing.log

[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO -   wheel == 0.37.0
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO -   yarl == 1.6.3
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO - Running post-action listener: _resource_record_post_action
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO - Running post-action listener: _start_resource_monitoring
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO - Starting resource monitoring.
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO - [mozharness: 2022-05-19 14:59:42.496482Z] Finished create-virtualenv step (success)
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO - [mozharness: 2022-05-19 14:59:42.496832Z] Running verify-device step.
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO - Running pre-action listener: _resource_record_pre_action
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO - Running main action method: verify_device
[task 2022-05-19T14:59:47.761Z] 14:59:42     INFO - mkdir: /builds/task_165297223838425/workspace/build/blobber_upload_dir
[task 2022-05-19T14:59:47.761Z] Using adb 1.0.41
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - Running post-action listener: _resource_record_post_action
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - [mozharness: 2022-05-19 14:59:47.653953Z] Finished verify-device step (failed)
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL - Uncaught exception: Traceback (most recent call last):
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/mozharness/mozharness/base/script.py", line 2358, in run
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     self.run_action(action)
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/mozharness/mozharness/base/script.py", line 2292, in run_action
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     self._possibly_run_method(method_name, error_if_missing=True)
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/mozharness/mozharness/base/script.py", line 2244, in _possibly_run_method
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     return getattr(self, method_name)()
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/mozharness/mozharness/mozilla/testing/android.py", line 681, in verify_device
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     self.dump_perf_info()
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/mozharness/mozharness/mozilla/testing/android.py", line 317, in dump_perf_info
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     out = self.shell_output(cmd)
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/mozharness/mozharness/mozilla/testing/android.py", line 471, in shell_output
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     return self.device.shell_output(
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/mozharness/mozharness/mozilla/testing/android.py", line 72, in device
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     adb=adb, device=self.device_serial
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/build/venv/lib/python3.6/site-packages/mozdevice/adb.py", line 679, in ADBDeviceFactory
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     run_as_package=run_as_package,
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/build/venv/lib/python3.6/site-packages/mozdevice/adb.py", line 887, in __init__
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     version = self.get_prop("ro.build.version.sdk", timeout=timeout)
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/build/venv/lib/python3.6/site-packages/mozdevice/adb.py", line 2362, in get_prop
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     output = self.shell_output("getprop %s" % prop, timeout=timeout)
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -   File "/builds/task_165297223838425/workspace/build/venv/lib/python3.6/site-packages/mozdevice/adb.py", line 2222, in shell_output
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL -     raise ADBProcessError(adb_process)
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL - mozdevice.adb.ADBProcessError: args: adb wait-for-device shell getprop ro.build.version.sdk; echo adb_returncode=$?, exitcode: 1, stdout: error: closed
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL - Running post_fatal callback...
[task 2022-05-19T14:59:47.761Z] 14:59:47    FATAL - Exiting -1
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - Running post-run listener: _resource_record_post_run
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - Total resource usage - Wall time: 5s; CPU: 4%; Read bytes: 53248; Write bytes: 221184; Read time: 0; Write time: 20
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - TinderboxPrint: CPU usage<br/>3.9%
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - TinderboxPrint: I/O read bytes / time<br/>53,248 / 0
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - TinderboxPrint: I/O write bytes / time<br/>221,184 / 20
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - TinderboxPrint: CPU idle<br/>19.0 (95.9%)
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - TinderboxPrint: CPU system<br/>0.3 (1.3%)
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - TinderboxPrint: CPU user<br/>0.5 (2.4%)
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2022-05-19T14:59:47.761Z] 14:59:47     INFO - verify-device - Wall time: 5s; CPU: 4%; Read bytes: 53248; Write bytes: 221184; Read time: 0; Write time: 20
You need to log in before you can comment on or make changes to this bug.