Closed Bug 1234743 Opened 9 years ago Closed 7 years ago

Intermittent jittest timed out after 1000 seconds of no output

Categories

(Core :: JavaScript Engine, defect, P3)

Product:
Core
Component:
JavaScript Engine
Type:
defect

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: philor, Unassigned)

References

Details

(Whiteboard: [stockwell fixed:backout]) 

Bulk assigning P3 to all open intermittent bugs without a priority set in Firefox components per bug 1298978.
Priority: -- → P3
Flags: needinfo?(nihsanullah)
Whiteboard: [stockwell needswork]
There are 37 failures in the past 7 days, mostly on LinuxX64 (opt, debug and pgo), linux64-jsdcov (opt), OS X 10.10 (opt and debug).
One of the most recent logs: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=144135515&lineNumber=1213
Here is a relevant part of the log where the test failed:
[task 2017-11-12T23:20:10.460Z] 23:20:10     INFO - TEST-START | /webdriver/tests/actions/key_shortcuts.py
1213
[task 2017-11-12T23:36:50.506Z] 23:36:50     INFO - Automation Error: mozprocess timed out after 1000 seconds running ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/web-platform/runtests.py', '--log-raw=-', '--log-raw=/builds/worker/workspace/build/blobber_upload_dir/wpt_raw.log', '--log-wptreport=/builds/worker/workspace/build/blobber_upload_dir/wptreport.json', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/wpt_errorsummary.log', '--binary=/builds/worker/workspace/build/application/firefox/firefox', '--symbols-path=https://queue.taskcluster.net/v1/task/aJg6X1b5TbmVaw0e463COw/artifacts/public/build/target.crashreporter-symbols.zip', '--stackwalk-binary=/usr/local/bin/linux64-minidump_stackwalk', '--stackfix-dir=/builds/worker/workspace/build/tests/bin', '--run-by-dir=3', '--test-type=wdspec', '--stylo-threads=4', '--webdriver-binary=/builds/worker/workspace/build/tests/bin/geckodriver', '--prefs-root=/builds/worker/workspace/build/tests/web-platform/prefs', '--processes=1', '--config=/builds/worker/workspace/build/tests/web-platform/wptrunner.ini', '--ca-cert-path=/builds/worker/workspace/build/tests/web-platform/certs/cacert.pem', '--host-key-path=/builds/worker/workspace/build/tests/web-platform/certs/web-platform.test.key', '--host-cert-path=/builds/worker/workspace/build/tests/web-platform/certs/web-platform.test.pem', '--certutil-binary=/builds/worker/workspace/build/tests/bin/certutil']
1214
[task 2017-11-12T23:36:50.520Z] 23:36:50    ERROR - timed out after 1000 seconds of no output
1215
[task 2017-11-12T23:36:50.521Z] 23:36:50    ERROR - Return code: -15
1216
[task 2017-11-12T23:36:50.521Z] 23:36:50    ERROR - No suite end message was emitted by this harness.
1217
[task 2017-11-12T23:36:50.522Z] 23:36:50    ERROR - # TBPL FAILURE #
1218
[task 2017-11-12T23:36:50.522Z] 23:36:50  WARNING - setting return code to 2
1219
[task 2017-11-12T23:36:50.522Z] 23:36:50     INFO - Running post-action listener: _package_coverage_data
1220
[task 2017-11-12T23:36:50.523Z] 23:36:50     INFO - Running post-action listener: _resource_record_post_action
1221
[task 2017-11-12T23:36:50.523Z] 23:36:50     INFO - [mozharness: 2017-11-12 23:36:50.523354Z] Finished run-tests step (success)
1222
[task 2017-11-12T23:36:50.523Z] 23:36:50     INFO - Running post-run listener: _resource_record_post_run
1223
[task 2017-11-12T23:36:50.623Z] 23:36:50     INFO - Total resource usage - Wall time: 1020s; CPU: 1.0%; Read bytes: 1777664; Write bytes: 559079424; Read time: 120; Write time: 1018064
1224
[task 2017-11-12T23:36:50.623Z] 23:36:50     INFO - TinderboxPrint: CPU usage<br/>0.6%
1225
[task 2017-11-12T23:36:50.624Z] 23:36:50     INFO - TinderboxPrint: I/O read bytes / time<br/>1,777,664 / 120
1226
[task 2017-11-12T23:36:50.624Z] 23:36:50     INFO - TinderboxPrint: I/O write bytes / time<br/>559,079,424 / 1,018,064
1227
[task 2017-11-12T23:36:50.624Z] 23:36:50     INFO - TinderboxPrint: CPU idle<br/>4,041.0 (99.4%)
1228
[task 2017-11-12T23:36:50.625Z] 23:36:50     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
1229
[task 2017-11-12T23:36:50.626Z] 23:36:50     INFO - pull - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
1230
[task 2017-11-12T23:36:50.627Z] 23:36:50     INFO - install - Wall time: 16s; CPU: 26.0%; Read bytes: 0; Write bytes: 143990784; Read time: 0; Write time: 487668
1231
[task 2017-11-12T23:36:50.638Z] 23:36:50     INFO - run-tests - Wall time: 1004s; CPU: 0.0%; Read bytes: 1777664; Write bytes: 414744576; Read time: 120; Write time: 530340
1232
[task 2017-11-12T23:36:50.967Z] 23:36:50     INFO - Running post-run listener: _upload_blobber_files
1233
[task 2017-11-12T23:36:50.967Z] 23:36:50  WARNING - Blob upload gear skipped. Missing cmdline options.
1234
[task 2017-11-12T23:36:50.967Z] 23:36:50     INFO - Running post-run listener: copy_logs_to_upload_dir
1235
[task 2017-11-12T23:36:50.968Z] 23:36:50     INFO - Copying logs to upload dir...
1236
[task 2017-11-12T23:36:50.968Z] 23:36:50     INFO - mkdir: /builds/worker/workspace/build/upload/logs
1237
[task 2017-11-12T23:36:50.970Z] 23:36:50     INFO - Copying logs to upload dir...
1238
[task 2017-11-12T23:36:50.972Z] 23:36:50  WARNING - returning nonzero exit status 2
1239
[task 2017-11-12T23:36:50.996Z] cleanup
1240
[task 2017-11-12T23:36:50.996Z] + cleanup
1241
[task 2017-11-12T23:36:50.996Z] + local rv=2
1242
[task 2017-11-12T23:36:50.996Z] + [[ -s /builds/worker/.xsession-errors ]]
1243
[task 2017-11-12T23:36:50.997Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
1244
[task 2017-11-12T23:36:50.998Z] + true
1245
[task 2017-11-12T23:36:50.999Z] + cleanup_xvfb
1246
[task 2017-11-12T23:36:50.999Z] pidof Xvfb
1247
[task 2017-11-12T23:36:50.999Z] ++ pidof Xvfb
1248
[task 2017-11-12T23:36:51.002Z] + local xvfb_pid=25
1249
[task 2017-11-12T23:36:51.003Z] + local vnc=false
1250
[task 2017-11-12T23:36:51.003Z] + local interactive=false
1251
[task 2017-11-12T23:36:51.003Z] + '[' -n 25 ']'
1252
[task 2017-11-12T23:36:51.003Z] + [[ false == false ]]
1253
[task 2017-11-12T23:36:51.003Z] + [[ false == false ]]
1254
[task 2017-11-12T23:36:51.003Z] + kill 25
1255
[task 2017-11-12T23:36:51.003Z] + screen -XS xvfb quit
1256
[task 2017-11-12T23:36:51.015Z] XIO:  fatal IO error 11 (Resource temporarily unavailable) on X server ":0"
1257
[task 2017-11-12T23:36:51.015Z]       after 797 requests (797 known processed) with 0 events remaining.
1258
[task 2017-11-12T23:36:51.015Z] compizconfig - Info: Backend     : ini
1259
[task 2017-11-12T23:36:51.015Z] compizconfig - Info: Integration : true
1260
[task 2017-11-12T23:36:51.015Z] compizconfig - Info: Profile     : default
1261
[task 2017-11-12T23:36:51.062Z] No screen session found.
1262
[task 2017-11-12T23:36:51.062Z] + true
1263
[task 2017-11-12T23:36:51.062Z] + exit 2
1264
[taskcluster 2017-11-12 23:36:51.308Z] === Task Finished ===
1265
[taskcluster 2017-11-12 23:36:56.166Z] Unsuccessful task run with exit code: 2 completed in 1525.621 seconds
:naveed Could you please take a look at this?
(In reply to Noemi Erli[:noemi_erli] from comment #61)
> [task 2017-11-12T23:20:10.460Z] 23:20:10     INFO - TEST-START |
> /webdriver/tests/actions/key_shortcuts.py

That is a misstar, as is every single thing starred as this bug in the last week. That and most of them are bug 1407383, the Marionette ones were bug 1391545, the others are other things, but as you can tell by the summary where the second word is "jittests", this bug is about the jittest suite. It was starred correctly once in early October, and the last time before that was in June. This is nothing but an attractive nuisance bug at this point; go file new bugs for unhandled hangs in suites that are not jittest.
Status: NEW → RESOLVED
Closed: 7 years ago
Flags: needinfo?(nihsanullah)
Keywords: intermittent-failure
Resolution: --- → WORKSFORME
Whiteboard: [stockwell needswork] → [stockwell fixed:backout]
You need to log in before you can comment on or make changes to this bug.