1548765 - Intermittent layout/style/test/test_property_syntax_errors.html | application ran for longer than allowed maximum time | application crashed [@ libc.so + 0x1c3dc]

Status: RESOLVED INCOMPLETE

(Core :: CSS Parsing and Computation, defect, P5)

Description

Filed by: dvarga [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=244411682&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/Swh69n7rQ62ERnibI2fc5Q/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/Swh69n7rQ62ERnibI2fc5Q/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2019-05-03T06:55:00.260Z] 06:55:00     INFO -  64 INFO TEST-START | layout/style/test/test_property_syntax_errors.html
[task 2019-05-03T07:08:37.084Z] 07:08:37     INFO -  65 INFO TEST-OK | layout/style/test/test_property_syntax_errors.html | took 756668ms
[task 2019-05-03T07:08:37.161Z] 07:08:37     INFO -  66 INFO TEST-START | Shutdown
[task 2019-05-03T07:08:37.162Z] 07:08:37     INFO -  67 INFO Passed:  404096
[task 2019-05-03T07:08:37.162Z] 07:08:37     INFO -  68 INFO Failed:  0
[task 2019-05-03T07:08:37.162Z] 07:08:37     INFO -  69 INFO Todo:    0
[task 2019-05-03T07:08:37.163Z] 07:08:37     INFO -  70 INFO Mode:    non-e10s
[task 2019-05-03T07:08:37.163Z] 07:08:37     INFO -  71 INFO Slowest: 795966ms - /tests/layout/style/test/test_property_syntax_errors.html
[task 2019-05-03T07:08:37.164Z] 07:08:37     INFO -  72 INFO SimpleTest START Loop 9
[task 2019-05-03T07:08:37.165Z] 07:08:37     INFO -  73 INFO TEST-START | layout/style/test/test_property_syntax_errors.html
[task 2019-05-03T07:15:33.967Z] 07:15:33     INFO -  wait for org.mozilla.fennec_aurora complete; top activity=org.mozilla.fennec_aurora
[task 2019-05-03T07:15:34.581Z] 07:15:34     INFO -  org.mozilla.fennec_aurora unexpectedly found running. Killing...
[task 2019-05-03T07:15:34.582Z] 07:15:34     INFO -  TEST-INFO | started process screentopng
[task 2019-05-03T07:15:34.949Z] 07:15:34     INFO -  TEST-INFO | screentopng: exit 0
[task 2019-05-03T07:15:49.912Z] 07:15:49  WARNING -  TEST-UNEXPECTED-FAIL | layout/style/test/test_property_syntax_errors.html | application ran for longer than allowed maximum time
[task 2019-05-03T07:15:49.912Z] 07:15:49     INFO -  remoteautomation.py | Application ran for: 1:51:50.944032
[task 2019-05-03T07:15:50.659Z] 07:15:50     INFO -  mozcrash Copy/paste: /builds/worker/workspace/build/linux64-minidump_stackwalk /tmp/tmpKFK4I2/658ca5d2-cbbd-3d39-7e6e-387edec7cf60.dmp /builds/worker/workspace/build/symbols
[task 2019-05-03T07:15:57.924Z] 07:15:57     INFO -  mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/658ca5d2-cbbd-3d39-7e6e-387edec7cf60.dmp
[task 2019-05-03T07:15:57.924Z] 07:15:57     INFO -  mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/658ca5d2-cbbd-3d39-7e6e-387edec7cf60.extra
[task 2019-05-03T07:15:57.928Z] 07:15:57  WARNING -  PROCESS-CRASH | layout/style/test/test_property_syntax_errors.html | application crashed [@ libc.so + 0x1c3dc]
[task 2019-05-03T07:15:57.928Z] 07:15:57     INFO -  Crash dump filename: /tmp/tmpKFK4I2/658ca5d2-cbbd-3d39-7e6e-387edec7cf60.dmp
[task 2019-05-03T07:15:57.928Z] 07:15:57     INFO -  Operating system: Android

Comment 1

[Daniel Holbert [:dholbert]]

Likely regressed by bug 1547939 which added a bunch of test data that this test will have to churn through, as noted in bug 1521253 comment 10. This is similar to that bug (bug 1521253), though a different timeout limit was exceeded (here, we're exceeding the limit for the whole test bucket, I think?)

Though the test bucket here isn't really a "real" test bucket -- this was a "test-verify" run which tries to run the test N times (I don't know what the value of N is), and since this is a particularly-long-running test, apparently N times the test duration is longer than our limit.

We probably won't see this happening in practice on TreeHerder, except when this test gets modified (and run through test-verify).

Comment 2

[Daniel Holbert [:dholbert]]

FWIW, in this interrupted run, the test durations were all in the 750-800 second range, which is around 12-13 minutes.

That's quite long, though this is also Android debug, which is known to be terribly slow at everything.

Comment 3

[Intermittent Failures Robot]

2 failures in 4131 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 1
• mozilla-central: 1

Platform breakdown:

• android-em-4-3-armv7-api16: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1548765&startday=2019-04-29&endday=2019-05-05&tree=all

Comment 4

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup