Open
Bug 1992529
Opened 3 months ago
Updated 4 days ago
Intermittent mozdevice.adb.ADBProcessError: args: adb wait-for-device reverse tcp:41663 tcp:41663, exitcode: 1, stdout: adb: error: cannot bind listener: Address already in use
Categories
(Testing :: Raptor, defect, P5)
Testing
Raptor
Tracking
(Not tracked)
NEW
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: chorotan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=530021395&repo=autoland&task=FQnDaRLDTBaKMpUBdRwPtg.0
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FQnDaRLDTBaKMpUBdRwPtg/runs/0/artifacts/public/logs/live_backing.log
[task 2025-10-03T22:28:23.635+00:00] 22:28:23 INFO - reversed MOZHTTP2_PORT connection for port tcp:32893
[task 2025-10-03T22:28:23.643+00:00] 22:28:23 INFO - Traceback (most recent call last):
[task 2025-10-03T22:28:23.644+00:00] 22:28:23 INFO - File "/builds/worker/workspace/build/tests/xpcshell/remotexpcshelltests.py", line 788, in <module>
[task 2025-10-03T22:28:23.644+00:00] 22:28:23 INFO - main()
[task 2025-10-03T22:28:23.644+00:00] 22:28:23 INFO - File "/builds/worker/workspace/build/tests/xpcshell/remotexpcshelltests.py", line 781, in main
[task 2025-10-03T22:28:23.644+00:00] 22:28:23 INFO - if not xpcsh.runTests(
[task 2025-10-03T22:28:23.644+00:00] 22:28:23 INFO - ^^^^^^^^^^^^^^^
[task 2025-10-03T22:28:23.645+00:00] 22:28:23 INFO - File "/builds/worker/workspace/build/tests/xpcshell/runxpcshelltests.py", line 2025, in runTests
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - self.trySetupNode()
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - File "/builds/worker/workspace/build/tests/xpcshell/remotexpcshelltests.py", line 699, in trySetupNode
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - self.device.create_socket_connection(
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - File "/builds/worker/workspace/build/venv/lib/python3.12/site-packages/mozdevice/adb.py", line 1807, in create_socket_connection
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - cmd_output = self.command_output(cmd, timeout=timeout)
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - File "/builds/worker/workspace/build/venv/lib/python3.12/site-packages/mozdevice/adb.py", line 1744, in command_output
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - return ADBCommand.command_output(
[task 2025-10-03T22:28:23.646+00:00] 22:28:23 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2025-10-03T22:28:23.647+00:00] 22:28:23 INFO - File "/builds/worker/workspace/build/venv/lib/python3.12/site-packages/mozdevice/adb.py", line 375, in command_output
[task 2025-10-03T22:28:23.647+00:00] 22:28:23 INFO - raise ADBProcessError(adb_process)
[task 2025-10-03T22:28:23.647+00:00] 22:28:23 INFO - mozdevice.adb.ADBProcessError: args: adb wait-for-device reverse tcp:41663 tcp:41663, exitcode: 1, stdout: adb: error: cannot bind listener: Address already in use
[task 2025-10-03T22:28:23.715+00:00] 22:28:23 INFO - Return code: 1
[task 2025-10-03T22:28:23.716+00:00] 22:28:23 ERROR - No suite end message was emitted by this harness.
[task 2025-10-03T22:28:23.717+00:00] 22:28:23 ERROR - No checks run.
[task 2025-10-03T22:28:23.717+00:00] 22:28:23 INFO - TinderboxPrint: xpcshell<br/><em class="testfail">T-FAIL</em>
[task 2025-10-03T22:28:23.717+00:00] 22:28:23 INFO - ##### xpcshell log ends
[task 2025-10-03T22:28:23.717+00:00] 22:28:23 WARNING - setting return code to 2
[task 2025-10-03T22:28:23.718+00:00] 22:28:23 INFO - The xpcshell suite: xpcshell ran with return status: FAILURE
[task 2025-10-03T22:28:23.718+00:00] 22:28:23 INFO - Running post-action listener: _package_coverage_data
[task 2025-10-03T22:28:23.718+00:00] 22:28:23 INFO - Running post-action listener: _resource_record_post_action
[task 2025-10-03T22:28:23.719+00:00] 22:28:23 INFO - Running post-action listener: process_java_coverage_data
[task 2025-10-03T22:28:23.720+00:00] 22:28:23 INFO - Running post-action listener: stop_device
[task 2025-10-03T22:28:24.522+00:00] 22:28:24 INFO - Killing logcat pid 1921.
[task 2025-10-03T22:28:24.522+00:00] 22:28:24 INFO - Killing every process called qemu-system-x86_64
[task 2025-10-03T22:28:24.538+00:00] 22:28:24 INFO - [mozharness: 2025-10-03 22:28:24.537542Z] Finished run-tests step (success)
[task 2025-10-03T22:28:24.538+00:00] 22:28:24 INFO - Running post-run listener: _resource_record_post_run
[task 2025-10-03T22:28:24.679+00:00] 22:28:24 INFO - Total resource usage - Wall time: 103s; CPU: 66%; Read bytes: 3900358656; Write bytes: 8084647936; Read time: 22881; Write time: 2181408
[task 2025-10-03T22:28:24.679+00:00] 22:28:24 INFO - TinderboxPrint: CPU usage<br/>66.0%
[task 2025-10-03T22:28:24.679+00:00] 22:28:24 INFO - TinderboxPrint: I/O read bytes / time<br/>3,900,358,656 / 22,881
[task 2025-10-03T22:28:24.679+00:00] 22:28:24 INFO - TinderboxPrint: I/O write bytes / time<br/>8,084,647,936 / 2,181,408
[task 2025-10-03T22:28:24.680+00:00] 22:28:24 INFO - TinderboxPrint: CPU guest<br/>344.7 (30.2%)
[task 2025-10-03T22:28:24.680+00:00] 22:28:24 INFO - TinderboxPrint: CPU idle<br/>258.9 (22.7%)
[task 2025-10-03T22:28:24.680+00:00] 22:28:24 INFO - TinderboxPrint: CPU iowait<br/>14.5 (1.3%)
[task 2025-10-03T22:28:24.680+00:00] 22:28:24 INFO - TinderboxPrint: CPU system<br/>68.4 (6.0%)
[task 2025-10-03T22:28:24.680+00:00] 22:28:24 INFO - TinderboxPrint: CPU user<br/>452.3 (39.7%)
[task 2025-10-03T22:28:24.680+00:00] 22:28:24 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2025-10-03T22:28:24.681+00:00] 22:28:24 INFO - start-emulator - Wall time: 1s; CPU: 0%; Read bytes: 0; Write bytes: 769155072; Read time: 0; Write time: 213835
[task 2025-10-03T22:28:24.691+00:00] 22:28:24 INFO - verify-device - Wall time: 73s; CPU: 62%; Read bytes: 2107277312; Write bytes: 4943941632; Read time: 9724; Write time: 1627171
[task 2025-10-03T22:28:24.692+00:00] 22:28:24 INFO - install - Wall time: 6s; CPU: 80%; Read bytes: 730791936; Write bytes: 8192; Read time: 3114; Write time: 0
[task 2025-10-03T22:28:24.696+00:00] 22:28:24 INFO - run-tests - Wall time: 22s; CPU: 77%; Read bytes: 1026490368; Write bytes: 2321170432; Read time: 9925; Write time: 335325
[task 2025-10-03T22:28:25.110+00:00] 22:28:25 WARNING - returning nonzero exit status 2
[task 2025-10-03T22:28:25.208+00:00] cleanup
[task 2025-10-03T22:28:25.209+00:00] + cleanup
[task 2025-10-03T22:28:25.209+00:00] + local rv=2
[task 2025-10-03T22:28:25.209+00:00] + true
[task 2025-10-03T22:28:25.209+00:00] + cleanup_pipewire
[task 2025-10-03T22:28:25.209+00:00] + '[' -n 1015 ']'
[task 2025-10-03T22:28:25.209+00:00] + '[' false = false ']'
[task 2025-10-03T22:28:25.209+00:00] + kill 1015 1022 1023
[task 2025-10-03T22:28:25.210+00:00] + '[' ']'
[task 2025-10-03T22:28:25.210+00:00] + true
[task 2025-10-03T22:28:25.210+00:00] + cleanup_xvfb
[task 2025-10-03T22:28:25.210+00:00] ++ pidof Xvfb
[task 2025-10-03T22:28:25.212+00:00] M 22:28:25.211052 wireplumber ../src/main.c:372:signal_handler: stopped by signal: Terminated
[task 2025-10-03T22:28:25.220+00:00] M 22:28:25.217277 wireplumber ../src/main.c:364:on_disconnected: disconnected from pipewire
[task 2025-10-03T22:28:25.220+00:00] + local xvfb_pid=50
[task 2025-10-03T22:28:25.220+00:00] + local vnc=false
[task 2025-10-03T22:28:25.220+00:00] + local interactive=false
[task 2025-10-03T22:28:25.220+00:00] + '[' -n 50 ']'
[task 2025-10-03T22:28:25.221+00:00] + [[ false == false ]]
[task 2025-10-03T22:28:25.221+00:00] + [[ false == false ]]
[task 2025-10-03T22:28:25.221+00:00] + kill 50
[task 2025-10-03T22:28:25.221+00:00] + exit 2
[taskcluster 2025-10-03T22:28:26.019Z] Exit Code: 2
[taskcluster 2025-10-03T22:28:26.019Z] User Time: 41.248ms
[taskcluster 2025-10-03T22:28:26.019Z] Kernel Time: 55.428ms
[taskcluster 2025-10-03T22:28:26.019Z] Wall Time: 3m10.76390261s
[taskcluster 2025-10-03T22:28:26.019Z] Average Available System Memory: 28.18 GiB
[taskcluster 2025-10-03T22:28:26.019Z] Average System Memory Used: 2.68 GiB
[taskcluster 2025-10-03T22:28:26.019Z] Peak System Memory Used: 5.00 GiB
[taskcluster 2025-10-03T22:28:26.019Z] Total System Memory: 31.34 GiB
[taskcluster 2025-10-03T22:28:26.019Z] Result: FAILED
[taskcluster 2025-10-03T22:28:26.019Z] === Task Finished ===
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
You need to log in
before you can comment on or make changes to this bug.
Description
•