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)

defect

Tracking

(Not tracked)

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 ===
You need to log in before you can comment on or make changes to this bug.