Closed Bug 1622119 Opened 4 years ago Closed 10 months ago

Intermittent wpt Automation Error: mozprocess timed out after 1000 seconds running ['Z:\\task_1584044717\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1584044717\\build\\tests\\web-platform\\runtests.py', '--log-raw=-', '--log-raw=Z:\\task_158

Categories

(Testing :: web-platform-tests, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1612600

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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


[task 2020-03-12T21:41:13.671Z] 21:41:13 INFO - TEST-START | /_mozilla/html/syntax/parsing/math-parse01.html
[task 2020-03-12T21:41:13.692Z] 21:41:13 INFO - Setting pref mathml.disabled (true)
[task 2020-03-12T21:41:13.969Z] 21:41:13 INFO - .........
[task 2020-03-12T21:41:13.969Z] 21:41:13 INFO - TEST-OK | /_mozilla/html/syntax/parsing/math-parse01.html | took 299ms
[task 2020-03-12T21:41:13.969Z] 21:41:13 INFO - No more tests
[task 2020-03-12T21:41:13.984Z] 21:41:13 INFO - PID 6788 | 1584049273974 Marionette INFO Stopped listening on port 62380
[task 2020-03-12T21:41:14.519Z] 21:41:14 INFO - PID 6788 | [Parent 5924, Gecko_IOThread] WARNING: file Z:/task_1584040534/build/src/ipc/chromium/src/base/process_util_win.cc, line 166
[task 2020-03-12T21:41:14.734Z] 21:41:14 INFO - Browser exited with return code 0
[task 2020-03-12T21:41:14.734Z] 21:41:14 INFO - PROCESS LEAKS None
[task 2020-03-12T21:41:14.735Z] 21:41:14 INFO - Closing logging queue
[task 2020-03-12T21:41:14.735Z] 21:41:14 INFO - queue closed
[task 2020-03-12T21:41:14.735Z] 21:41:14 INFO - PROCESS LEAKS None
[task 2020-03-12T21:57:54.797Z] 21:57:54 INFO - Automation Error: mozprocess timed out after 1000 seconds running ['Z:\task_1584044717\build\venv\Scripts\python', '-u', 'Z:\task_1584044717\build\tests\web-platform\runtests.py', '--log-raw=-', '--log-raw=Z:\task_1584044717\build\blobber_upload_dir\wpt_raw.log', '--log-wptreport=Z:\task_1584044717\build\blobber_upload_dir\wptreport.json', '--log-errorsummary=Z:\task_1584044717\build\blobber_upload_dir\wpt_errorsummary.log', '--binary=Z:\task_1584044717\build\application\firefox\firefox.exe', u'--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TuSxbj-TTsymq1fiUwXVgQ/artifacts/public/build/target.crashreporter-symbols.zip', '--stackwalk-binary=Z:/task_1584044717/fetches\minidump_stackwalk\minidump_stackwalk.exe', '--stackfix-dir=Z:\task_1584044717\build\tests\bin', '--run-by-dir=3', '--no-pause-after-test', '--skip-implementation-status=not-implementing', '--instrument-to-file=Z:\task_1584044717\build\blobber_upload_dir\wpt_instruments.txt', '--test-type=testharness', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--total-chunks=12', '--this-chunk=10', '--prefs-root=Z:\task_1584044717\build\tests\web-platform/prefs', '--processes=1', '--config=Z:\task_1584044717\build\tests\web-platform/wptrunner.ini', '--ca-cert-path=Z:\task_1584044717\build\tests\web-platform/tests/tools/certs/cacert.pem', '--host-key-path=Z:\task_1584044717\build\tests\web-platform/tests/tools/certs/web-platform.test.key', '--host-cert-path=Z:\task_1584044717\build\tests\web-platform/tests/tools/certs/web-platform.test.pem', '--certutil-binary=Z:\task_1584044717\build\tests/bin/certutil']
[task 2020-03-12T21:57:54.855Z] 21:57:54 ERROR - timed out after 1000 seconds of no output
[task 2020-03-12T21:57:54.855Z] 21:57:54 ERROR - Return code: 572
[task 2020-03-12T21:57:54.856Z] 21:57:54 ERROR - No suite end message was emitted by this harness.
[task 2020-03-12T21:57:54.856Z] 21:57:54 ERROR - # TBPL FAILURE #
[task 2020-03-12T21:57:54.856Z] 21:57:54 WARNING - setting return code to 2
[task 2020-03-12T21:57:54.856Z] 21:57:54 INFO - Running post-action listener: _package_coverage_data
[task 2020-03-12T21:57:54.856Z] 21:57:54 INFO - Running post-action listener: _resource_record_post_action
[task 2020-03-12T21:57:54.856Z] 21:57:54 INFO - Running post-action listener: process_java_coverage_data
[task 2020-03-12T21:57:54.856Z] 21:57:54 INFO - Running post-action listener: stop_device
[task 2020-03-12T21:57:54.856Z] 21:57:54 INFO - [mozharness: 2020-03-12 21:57:54.856000Z] Finished run-tests step (success)
[task 2020-03-12T21:57:54.856Z] 21:57:54 INFO - Running post-run listener: _resource_record_post_run
[task 2020-03-12T21:57:55.138Z] 21:57:55 INFO - Total resource usage - Wall time: 2862s; CPU: 17.0%; Read bytes: 1139054080; Write bytes: 2621702656; Read time: 57; Write time: 99
[task 2020-03-12T21:57:55.138Z] 21:57:55 INFO - TinderboxPrint: CPU usage<br/>17.5%
[task 2020-03-12T21:57:55.138Z] 21:57:55 INFO - TinderboxPrint: I/O read bytes / time<br/>1,139,054,080 / 57
[task 2020-03-12T21:57:55.138Z] 21:57:55 INFO - TinderboxPrint: I/O write bytes / time<br/>2,621,702,656 / 99
[task 2020-03-12T21:57:55.138Z] 21:57:55 INFO - TinderboxPrint: CPU idle<br/>18,906.9 (82.5%)
[task 2020-03-12T21:57:55.138Z] 21:57:55 INFO - TinderboxPrint: CPU system<br/>1,530.2 (6.7%)
[task 2020-03-12T21:57:55.139Z] 21:57:55 INFO - TinderboxPrint: CPU user<br/>2,459.4 (10.7%)
[task 2020-03-12T21:57:55.140Z] 21:57:55 INFO - pull - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-03-12T21:57:55.142Z] 21:57:55 INFO - verify-device - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-03-12T21:57:55.144Z] 21:57:55 INFO - install - Wall time: 5s; CPU: 8.0%; Read bytes: 32768; Write bytes: 148585984; Read time: 0; Write time: 2
[task 2020-03-12T21:57:55.179Z] 21:57:55 INFO - run-tests - Wall time: 2858s; CPU: 17.0%; Read bytes: 1138198528; Write bytes: 2430024192; Read time: 57; Write time: 96
[task 2020-03-12T21:57:56.294Z] 21:57:56 WARNING - returning nonzero exit status 2

See Also: → 1545630

There are 61 failures in the last 2 days.
:jgraham, can you please take a look?

Flags: needinfo?(james)

Looks like it hangs after TEST-PASS | leakcheck | tab no leaks detected!

Flags: needinfo?(james)

The spike here should be fixed by the backout of Bug 1628527 https://bugzilla.mozilla.org/show_bug.cgi?id=1628527#c18

Flags: needinfo?(n.nethercote)

I don't know what went wrong.

Whiteboard: [stockwell disable-recommended] → [stockwell fixed:backout]
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Whiteboard: [stockwell fixed:backout]
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Severity: normal → S3
Status: REOPENED → RESOLVED
Closed: 2 years ago10 months ago
Duplicate of bug: 1612600
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.