Closed Bug 1780483 Opened 3 years ago Closed 3 years ago

Intermittent Automation Error: mozprocess timed out after 6500 seconds running ['marionette', '--testvars=/opt/worker/tasks/task_165835262482001/build/tests/awsy/conf/tp6-testvars.json', '--testvars=/opt/worker/tasks/task_165835262482001/build/tests/awsy/

Categories

(Testing :: AWSY, defect, P5)

Product:
Testing
Component:
AWSY
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [retriggered])

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

INFO - mozproxy b"INFO - Attempting to fetch from 'https://tooltool.mozilla-releng.net/'..."
[task 2022-07-20T23:29:32.097Z] 23:29:32     INFO - Automation Error: mozprocess timed out after 6500 seconds running ['marionette', '--testvars=/opt/worker/tasks/task_165835262482001/build/tests/awsy/conf/tp6-testvars.json', '--testvars=/opt/worker/tasks/task_165835262482001/build/tests/awsy/runtime-testvars.json', '--log-raw=-', '--log-errorsummary=/opt/worker/tasks/task_165835262482001/build/blobber_upload_dir/marionette_errorsummary.log', '--binary=/opt/worker/tasks/task_165835262482001/build/application/Firefox Nightly.app/Contents/MacOS/firefox', '--profile=/opt/worker/tasks/task_165835262482001/build/profile', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--gecko-log=/opt/worker/tasks/task_165835262482001/build/blobber_upload_dir/gecko.log', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/H6O_RmvfQJ6te_TU75mZhw/artifacts/public/build/target.crashreporter-symbols.zip', '--preferences=/opt/worker/tasks/task_165835262482001/build/tests/awsy/conf/tp6-prefs.json', '/opt/worker/tasks/task_165835262482001/build/tests/awsy/awsy/test_memory_usage.py']
[task 2022-07-20T23:29:32.098Z] 23:29:32    ERROR - timed out after 6500 seconds of no output
[task 2022-07-20T23:29:32.098Z] 23:29:32    ERROR - Return code: -15
[task 2022-07-20T23:29:32.098Z] 23:29:32    ERROR - No suite end message was emitted by this harness.
[task 2022-07-20T23:29:32.098Z] 23:29:32    ERROR - No checks run.
[task 2022-07-20T23:29:32.098Z] 23:29:32     INFO - AWSY exited with return code -15: FAILURE
[task 2022-07-20T23:29:32.098Z] 23:29:32    ERROR - # TBPL FAILURE #
[task 2022-07-20T23:29:32.099Z] 23:29:32  WARNING - setting return code to 2
[task 2022-07-20T23:29:32.099Z] 23:29:32     INFO - Running post-action listener: _package_coverage_data
[task 2022-07-20T23:29:32.099Z] 23:29:32     INFO - Running post-action listener: _resource_record_post_action
[task 2022-07-20T23:29:32.099Z] 23:29:32     INFO - Running post-action listener: process_java_coverage_data
[task 2022-07-20T23:29:32.099Z] 23:29:32     INFO - [mozharness: 2022-07-20 23:29:32.099427Z] Finished run-tests step (success)
[task 2022-07-20T23:29:32.099Z] 23:29:32     INFO - Running post-run listener: _resource_record_post_run
[task 2022-07-20T23:29:32.562Z] 23:29:32     INFO - Total resource usage - Wall time: 6842s; CPU: 1%; Read bytes: 211664896; Write bytes: 741306368; Read time: 2006; Write time: 6315
[task 2022-07-20T23:29:32.562Z] 23:29:32     INFO - TinderboxPrint: CPU usage<br/>1.2%
[task 2022-07-20T23:29:32.562Z] 23:29:32     INFO - TinderboxPrint: I/O read bytes / time<br/>211,664,896 / 2,006
[task 2022-07-20T23:29:32.562Z] 23:29:32     INFO - TinderboxPrint: I/O write bytes / time<br/>741,306,368 / 6,315
[task 2022-07-20T23:29:32.562Z] 23:29:32     INFO - TinderboxPrint: CPU idle<br/>80,923.2 (98.6%)
[task 2022-07-20T23:29:32.563Z] 23:29:32     INFO - TinderboxPrint: CPU system<br/>920.0 (1.1%)
[task 2022-07-20T23:29:32.563Z] 23:29:32     INFO - TinderboxPrint: Swap in / out<br/>633,434,112 / 0
[task 2022-07-20T23:29:32.566Z] 23:29:32     INFO - install - Wall time: 33s; CPU: 14%; Read bytes: 442029568; Write bytes: 434270208; Read time: 29533; Write time: 837
[task 2022-07-20T23:29:32.651Z] 23:29:32     INFO - run-tests - Wall time: 6811s; CPU: 1%; Read bytes: 187412480; Write bytes: 289505280; Read time: 1667; Write time: 5235
[task 2022-07-20T23:29:34.178Z] 23:29:34  WARNING - returning nonzero exit status 2
[taskcluster 2022-07-20T23:29:34.253Z]    Exit Code: 2
[taskcluster 2022-07-20T23:29:34.253Z]    User Time: 5m18.888914s
[taskcluster 2022-07-20T23:29:34.253Z]  Kernel Time: 21.008245s
[taskcluster 2022-07-20T23:29:34.253Z]    Wall Time: 1h59m5.505127s
[taskcluster 2022-07-20T23:29:34.253Z]       Result: FAILED

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

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.