Closed Bug 1822660 Opened 3 years ago Closed 3 years ago

Intermittent raise InvalidBinary('"%s" does not contain a valid binary.' % path)

Categories

(Core :: Layout, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2023-03-15T18:33:52.850Z] 18:33:52     INFO - Running pre-action listener: _resource_record_pre_action
[task 2023-03-15T18:33:52.850Z] 18:33:52     INFO - Running main action method: install
[task 2023-03-15T18:33:52.850Z] 18:33:52     INFO - Getting output from command: ['/builds/worker/workspace/build/venv/bin/pip', 'list', '--format', 'freeze', '--no-index']
[task 2023-03-15T18:33:52.851Z] 18:33:52     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/pip list --format freeze --no-index
[task 2023-03-15T18:33:53.095Z] 18:33:53     INFO - Reading from file tmpfile_stdout
[task 2023-03-15T18:33:53.096Z] 18:33:53     INFO - Return code: 0
[task 2023-03-15T18:33:53.096Z] 18:33:53     INFO - Detecting whether we're running mozinstall >=1.0...
[task 2023-03-15T18:33:53.097Z] 18:33:53     INFO - Getting output from command: ['/builds/worker/workspace/build/venv/bin/mozinstall', '-h']
[task 2023-03-15T18:33:53.097Z] 18:33:53     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/mozinstall -h
[task 2023-03-15T18:33:53.310Z] 18:33:53     INFO - Reading from file tmpfile_stdout
[task 2023-03-15T18:33:53.311Z] 18:33:53     INFO - Output received:
[task 2023-03-15T18:33:53.311Z] 18:33:53     INFO -  Usage: mozinstall [options] installer
[task 2023-03-15T18:33:53.311Z] 18:33:53     INFO -  Options:
[task 2023-03-15T18:33:53.311Z] 18:33:53     INFO -    -h, --help            show this help message and exit
[task 2023-03-15T18:33:53.311Z] 18:33:53     INFO -    -d DEST, --destination=DEST
[task 2023-03-15T18:33:53.311Z] 18:33:53     INFO -                          Directory to install application into. [default:
[task 2023-03-15T18:33:53.312Z] 18:33:53     INFO -                          "/builds/worker/workspace"]
[task 2023-03-15T18:33:53.312Z] 18:33:53     INFO -    --app=APP             Application being installed. [default: firefox]
[task 2023-03-15T18:33:53.313Z] 18:33:53     INFO - Return code: 0
[task 2023-03-15T18:33:53.313Z] 18:33:53     INFO - mkdir: /builds/worker/workspace/build/application
[task 2023-03-15T18:33:53.313Z] 18:33:53     INFO - Getting output from command: ['/builds/worker/workspace/build/venv/bin/mozinstall', '/builds/worker/workspace/build/installer.tar.bz2', '--destination', '/builds/worker/workspace/build/application']
[task 2023-03-15T18:33:53.314Z] 18:33:53     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/mozinstall /builds/worker/workspace/build/installer.tar.bz2 --destination /builds/worker/workspace/build/application
[task 2023-03-15T18:34:03.563Z] 18:34:03    ERROR - Errors received:
[task 2023-03-15T18:34:03.563Z] 18:34:03     INFO - Reading from file tmpfile_stderr
[task 2023-03-15T18:34:03.564Z] 18:34:03    ERROR -  Traceback (most recent call last):
[task 2023-03-15T18:34:03.564Z] 18:34:03    ERROR -    File "/builds/worker/workspace/build/venv/bin/mozinstall", line 33, in <module>
[task 2023-03-15T18:34:03.564Z] 18:34:03    ERROR -      sys.exit(load_entry_point('mozInstall==2.0.1', 'console_scripts', 'mozinstall')())
[task 2023-03-15T18:34:03.565Z] 18:34:03    ERROR -    File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/mozinstall/mozinstall.py", line 430, in install_cli
[task 2023-03-15T18:34:03.565Z] 18:34:03    ERROR -      binary = get_binary(install_path, app_name=options.app)
[task 2023-03-15T18:34:03.565Z] 18:34:03    ERROR -    File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/mozinstall/mozinstall.py", line 96, in get_binary
[task 2023-03-15T18:34:03.565Z] 18:34:03    ERROR -      raise InvalidBinary('"%s" does not contain a valid binary.' % path)
[task 2023-03-15T18:34:03.565Z] 18:34:03    ERROR -  mozinstall.mozinstall.InvalidBinary: "/builds/worker/workspace/build/application/firefox" does not contain a valid binary.
[task 2023-03-15T18:34:03.566Z] 18:34:03     INFO - Return code: 1
[task 2023-03-15T18:34:03.566Z] 18:34:03  WARNING - setting return code to 3
[task 2023-03-15T18:34:03.567Z] 18:34:03    FATAL - Halting on failure while running ['/builds/worker/workspace/build/venv/bin/mozinstall', '/builds/worker/workspace/build/installer.tar.bz2', '--destination', '/builds/worker/workspace/build/application']
[task 2023-03-15T18:34:03.567Z] 18:34:03    FATAL - Running post_fatal callback...
[task 2023-03-15T18:34:03.567Z] 18:34:03    FATAL - Exiting 3
[task 2023-03-15T18:34:03.567Z] 18:34:03     INFO - Running post-action listener: _resource_record_post_action
[task 2023-03-15T18:34:03.567Z] 18:34:03     INFO - [mozharness: 2023-03-15 18:34:03.566586Z] Finished install step (failed)
[task 2023-03-15T18:34:03.567Z] 18:34:03     INFO - Running post-run listener: _resource_record_post_run
[task 2023-03-15T18:34:03.602Z] 18:34:03     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2023-03-15T18:34:03.606Z] 18:34:03     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-plain.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 50.675}, {"name": "io_write_bytes", "value": 3723264}, {"name": "io.read_bytes", "value": 0}, {"name": "io_write_time", "value": 96}, {"name": "io_read_time", "value": 0}]}, {"name": "mochitest.mochitest-plain.start-pulseaudio", "subtests": [{"name": "time", "value": 0.029056072235107422}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-plain.install", "subtests": [{"name": "time", "value": 10.716374635696411}, {"name": "cpu_percent", "value": 50.56666666666666}]}]}
[task 2023-03-15T18:34:03.606Z] 18:34:03     INFO - Total resource usage - Wall time: 10s; CPU: 51%; Read bytes: 0; Write bytes: 3723264; Read time: 0; Write time: 96
[task 2023-03-15T18:34:03.607Z] 18:34:03     INFO - TinderboxPrint: CPU usage<br/>50.6%
[task 2023-03-15T18:34:03.607Z] 18:34:03     INFO - TinderboxPrint: I/O read bytes / time<br/>0 / 0
[task 2023-03-15T18:34:03.607Z] 18:34:03     INFO - TinderboxPrint: I/O write bytes / time<br/>3,723,264 / 96
[task 2023-03-15T18:34:03.608Z] 18:34:03     INFO - TinderboxPrint: CPU idle<br/>9.9 (49.3%)
[task 2023-03-15T18:34:03.608Z] 18:34:03     INFO - TinderboxPrint: CPU system<br/>0.4 (1.8%)
[task 2023-03-15T18:34:03.608Z] 18:34:03     INFO - TinderboxPrint: CPU user<br/>9.8 (48.9%)
[task 2023-03-15T18:34:03.608Z] 18:34:03     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2023-03-15T18:34:03.608Z] 18:34:03     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-03-15T18:34:03.609Z] 18:34:03     INFO - install - Wall time: 11s; CPU: 51%; Read bytes: 0; Write bytes: 1224704; Read time: 0; Write time: 24
[task 2023-03-15T18:34:03.628Z] cleanup

The Bugbug bot thinks this bug should belong to the 'Core::Layout' component, and is moving the bug to that component. Please correct in case you think the bot is wrong.

Component: General → Layout
Product: Firefox → Core
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.