1924421 - Intermittent [tier 2] mozdevice.adb.ADBTimeoutError: args: adb wait-for-device install -r /builds/task_172886884894544/fetches/target.arm64-v8a.apk, exitcode: None, stdout:

Status: NEW

(Testing :: mozperftest, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2024-10-14T01:24:10.016Z] Creating local state directory: /builds/worker/.mozbuild/srcdirs/gecko-7ee7f4198eae
[task 2024-10-14T01:24:10.016Z]  0:00.00 DEBUG ADBLoggedDevice: {'_provided_logger': <mozlog.structuredlog.StructuredLogger object at 0x7f12dff4bbb0>, '_logger': <mozlog.structuredlog.StructuredLogger object at 0x7f12dff4bbb0>, '_verbose': False, '_use_root': True, '_adb_path': 'adb', '_adb_host': None, '_adb_port': None, '_timeout': 60, '_polling_interval': 0.001, '_adb_version': ''}
[task 2024-10-14T01:24:10.016Z]  0:00.01 INFO Using adb 1.0.41
[task 2024-10-14T01:24:10.016Z] {"action": "log", "time": 1728868979832, "thread": "MainThread", "pid": 428, "source": "mozperftest", "component": "adb", "message": "ADBHost: {'_logger': <mozlog.structuredlog.StructuredLogger object at 0x7f12d56005e0>, '_verbose': False, '_use_root': True, '_adb_path': 'adb', '_adb_host': None, '_adb_port': None, '_timeout': 300, '_polling_interval': 0.001, '_adb_version': ''}", "level": "DEBUG"}
[task 2024-10-14T01:24:10.016Z]  0:00.33 INFO /system/bin/ls -1A supported
[task 2024-10-14T01:24:10.016Z]  0:00.41 DEBUG Removing pwd from BUILTINS
[task 2024-10-14T01:24:10.016Z]  0:00.48 DEBUG Removing true from BUILTINS
[task 2024-10-14T01:24:10.016Z]  0:01.46 DEBUG Removing printf from BUILTINS
[task 2024-10-14T01:24:10.016Z]  0:02.43 DEBUG Removing false from BUILTINS
[task 2024-10-14T01:24:10.016Z]  0:02.90 DEBUG Removing kill from BUILTINS
[task 2024-10-14T01:24:10.016Z]  0:03.56 DEBUG Removing test from BUILTINS
[task 2024-10-14T01:24:10.016Z]  0:03.69 DEBUG Removing echo from BUILTINS
[task 2024-10-14T01:24:10.016Z]  0:03.95 DEBUG Removing ulimit from BUILTINS
[task 2024-10-14T01:24:10.016Z]  0:04.38 INFO Native cp support: True
[task 2024-10-14T01:24:10.016Z]  0:04.45 INFO Native chmod -R support: True
[task 2024-10-14T01:24:10.016Z]  0:04.51 INFO Native chown -R support: True
[task 2024-10-14T01:24:10.016Z]  0:04.74 INFO Native normal pidof support: True
[task 2024-10-14T01:24:10.016Z]  0:04.98 INFO adbd not restarted as root
[task 2024-10-14T01:24:10.016Z]  0:05.09 INFO su -c setenforce 0 exitcode 0, stdout: None
[task 2024-10-14T01:24:10.016Z]  0:05.20 INFO su -c supported
[task 2024-10-14T01:24:10.016Z]  0:05.58 INFO Setting SELinux Permissive
[task 2024-10-14T01:24:10.016Z]  0:05.58 DEBUG ADBDevice: {'_provided_logger': <mozlog.structuredlog.StructuredLogger object at 0x7f12dff4bbb0>, '_logger': <mozlog.structuredlog.StructuredLogger object at 0x7f12dff4bbb0>, '_verbose': False, '_use_root': True, '_adb_path': 'adb', '_adb_host': None, '_adb_port': None, '_timeout': 60, '_polling_interval': 0.001, '_adb_version': '1.0.41', '_device_serial': 'RZCX6066TMV', '_initial_test_root': None, '_share_test_root': True, '_test_root': None, '_run_as_package': None, '_debuggable_packages': {}, '_device_ready_retry_wait': 20, '_device_ready_retry_attempts': 3, '_have_root_shell': False, '_have_su': True, '_have_android_su': False, '_selinux': None, '_re_internal_storage': None, 'version': 34, '_mkdir_p': None, '_ls': '/system/bin/ls -1A', '_have_cp': True, '_chmod_R': True, '_chown_R': True, '_have_pidof': True, '_have_flaky_pidof': False, 'stack_trace_dir': '/data/anr'}
[task 2024-10-14T01:24:10.016Z]  0:13.09 Uninstalling old version
[task 2024-10-14T01:24:10.016Z]  0:13.25 Installing /builds/task_172886884894544/fetches/target.arm64-v8a.apk
[task 2024-10-14T01:24:16.146Z] Traceback (most recent call last):
[task 2024-10-14T01:24:16.146Z]   File "python/mozperftest/mozperftest/runner.py", line 299, in <module>
[task 2024-10-14T01:24:16.146Z]     sys.exit(main())
[task 2024-10-14T01:24:16.146Z]   File "python/mozperftest/mozperftest/runner.py", line 295, in main
[task 2024-10-14T01:24:16.146Z]     run_tests(mach_cmd, args, user_args)
[task 2024-10-14T01:24:16.146Z]   File "python/mozperftest/mozperftest/runner.py", line 188, in run_tests
[task 2024-10-14T01:24:16.146Z]     e.run(metadata)
[task 2024-10-14T01:24:16.146Z]   File "/builds/task_172886884894544/checkouts/gecko/python/mozperftest/mozperftest/environment.py", line 92, in run
[task 2024-10-14T01:24:16.146Z]     metadata = testlayer(syslayer(metadata))
[task 2024-10-14T01:24:16.146Z]   File "/builds/task_172886884894544/checkouts/gecko/python/mozperftest/mozperftest/layers.py", line 157, in __call__
[task 2024-10-14T01:24:16.146Z]     metadata = layer(metadata)
[task 2024-10-14T01:24:16.146Z]   File "/builds/task_172886884894544/checkouts/gecko/python/mozperftest/mozperftest/layers.py", line 69, in __call__
[task 2024-10-14T01:24:16.146Z]     metadata = self.run(metadata)
[task 2024-10-14T01:24:16.146Z]   File "/builds/task_172886884894544/checkouts/gecko/python/mozperftest/mozperftest/system/android.py", line 257, in run
[task 2024-10-14T01:24:16.146Z]     self.install_application(self.get_arg("android-install-apk"))
[task 2024-10-14T01:24:16.146Z]   File "/builds/task_172886884894544/checkouts/gecko/python/mozperftest/mozperftest/system/android.py", line 210, in install_application
[task 2024-10-14T01:24:16.146Z]     self.device.install_app(apk, replace=True)
[task 2024-10-14T01:24:16.146Z]   File "/builds/task_172886884894544/checkouts/gecko/testing/mozbase/mozdevice/mozdevice/adb.py", line 4059, in install_app
[task 2024-10-14T01:24:16.146Z]     data = self.command_output(cmd, timeout=timeout)
[task 2024-10-14T01:24:16.146Z]   File "/builds/task_172886884894544/checkouts/gecko/testing/mozbase/mozdevice/mozdevice/adb.py", line 1749, in command_output
[task 2024-10-14T01:24:16.146Z]     return ADBCommand.command_output(
[task 2024-10-14T01:24:16.146Z]   File "/builds/task_172886884894544/checkouts/gecko/testing/mozbase/mozdevice/mozdevice/adb.py", line 377, in command_output
[task 2024-10-14T01:24:16.146Z]     raise ADBTimeoutError("%s" % adb_process)
[task 2024-10-14T01:24:16.146Z] mozdevice.adb.ADBTimeoutError: args: adb wait-for-device install -r /builds/task_172886884894544/fetches/target.arm64-v8a.apk, exitcode: None, stdout:
[task 2024-10-14T01:24:16.146Z] script.py: command finished
[task 2024-10-14T01:24:16.146Z] 
[task 2024-10-14T01:24:16.146Z] netstat -aop
[task 2024-10-14T01:24:16.146Z] Active Internet connections (servers and established)
[task 2024-10-14T01:24:16.146Z] Proto Recv-Q Send-Q Local Address           Foreign Address         State       PID/Program name     Timer
[task 2024-10-14T01:24:16.146Z] tcp        0      0 localhost:8099          0.0.0.0:*               LISTEN      78/taskcluster-prox  off (0.00/0/0)
[task 2024-10-14T01:24:16.146Z] tcp        0      0 127.0.0.11:45593        0.0.0.0:*               LISTEN      -                    off (0.00/0/0)
[task 2024-10-14T01:24:16.146Z] tcp        0   1165 bitbar-ubuntu-166:46218 hg.public.mdc1.mo:https LAST_ACK    -                    on (4.85/8/0)
[task 2024-10-14T01:24:16.146Z] tcp        0      0 localhost:5037          localhost:41841         TIME_WAIT   -                    timewait (59.98/0/0)
[task 2024-10-14T01:24:16.146Z] tcp        0      0 bitbar-ubuntu-166:43240 137.80.201.35.bc.:https ESTABLISHED 26/generic-worker    keepalive (4.23/0/0)
[task 2024-10-14T01:24:16.146Z] tcp6       0      0 [::]:60099              [::]:*                  LISTEN      71/livelog           off (0.00/0/0)
[task 2024-10-14T01:24:16.146Z] tcp6       0      0 [::]:60098              [::]:*                  LISTEN      71/livelog           off (0.00/0/0)
[task 2024-10-14T01:24:16.146Z] tcp6       0      0 localhost:60098         localhost:50528         ESTABLISHED 71/livelog           keepalive (4.29/0/0)
[task 2024-10-14T01:24:16.146Z] tcp6       0      0 cool_poitras.shar:49792 nuq04s45-in-f10.1:https ESTABLISHED 20/python3           off (0.00/0/0)
[task 2024-10-14T01:24:16.146Z] tcp6       0      0 localhost:50528         localhost:60098         ESTABLISHED 26/generic-worker    keepalive (6.24/0/0)
[task 2024-10-14T01:24:16.146Z] udp        0      0 127.0.0.11:44538        0.0.0.0:*                           -                    off (0.00/0/0)
[task 2024-10-14T01:24:16.146Z] Active UNIX domain sockets (servers and established)
[task 2024-10-14T01:24:16.146Z] Proto RefCnt Flags       Type       State         I-Node   PID/Program name     Path
[task 2024-10-14T01:24:16.146Z] 
[task 2024-10-14T01:24:16.146Z] 
[task 2024-10-14T01:24:16.146Z] 
[task 2024-10-14T01:24:16.146Z] 
[task 2024-10-14T01:24:16.146Z] df -h
[task 2024-10-14T01:24:16.146Z] Filesystem                         Size  Used Avail Use% Mounted on
[task 2024-10-14T01:24:16.146Z] overlay                            914G   55G  821G   7% /
[task 2024-10-14T01:24:16.146Z] tmpfs                               64M     0   64M   0% /dev
[task 2024-10-14T01:24:16.146Z] shm                                 64M     0   64M   0% /dev/shm
[task 2024-10-14T01:24:16.146Z] /dev/mapper/ubuntu--vg-ubuntu--lv  914G   55G  821G   7% /test
[task 2024-10-14T01:24:16.146Z] tmpfs                               32G     0   32G   0% /proc/asound
[task 2024-10-14T01:24:16.146Z] tmpfs                               32G     0   32G   0% /proc/acpi
[task 2024-10-14T01:24:16.146Z] tmpfs                               32G     0   32G   0% /proc/scsi
[task 2024-10-14T01:24:16.146Z] tmpfs                               32G     0   32G   0% /sys/firmware
[task 2024-10-14T01:24:16.146Z] tmpfs                               32G     0   32G   0% /sys/devices/virtual/powercap
[task 2024-10-14T01:24:16.146Z] 
[task 2024-10-14T01:24:16.146Z] 
[task 2024-10-14T01:24:16.146Z] 
[task 2024-10-14T01:24:16.146Z] script.py: exiting with exitcode 1.
[taskcluster 2024-10-14T01:24:16.215Z]    Exit Code: 1
[taskcluster 2024-10-14T01:24:16.215Z]    User Time: 1m11.910743s
[taskcluster 2024-10-14T01:24:16.215Z]  Kernel Time: 37.072338s
[taskcluster 2024-10-14T01:24:16.215Z]    Wall Time: 3m26.490929465s
[taskcluster 2024-10-14T01:24:16.215Z]       Result: FAILED
[taskcluster 2024-10-14T01:24:16.215Z] === Task Finished ===
[taskcluster 2024-10-14T01:24:16.215Z] Task Duration: 3m26.492246166s
[taskcluster 2024-10-14T01:24:16.216Z] [mounts] Preserving cache: Moving "/builds/task_172886884894544/checkouts" to "caches/Ucr5a5ZtQD6dZFqDNhp3dA"
[taskcluster 2024-10-14T01:24:16.280Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ZhJKZP_LRI6ZOIbgMEbKtg/runs/0/artifacts/public%2Flogs%2Flive_backing.log with mime type "text/plain; charset=utf-8" and expiry 2025-01-11T21:23:00.419Z
[taskcluster:error] exit status 1

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• android-hw-a55-14-0-aarch64-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1924421&startday=2024-10-07&endday=2024-10-13&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-hw-a55-14-0-aarch64-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1924421&startday=2024-10-14&endday=2024-10-20&tree=all