Closed Bug 1358759 Opened 8 years ago Closed 6 years ago

Intermittent layout/base/tests/test_bug114649.html | got last event - got 410, expected 400

Categories

(Core :: Layout, defect, P3)

defect

Tracking

()

RESOLVED FIXED
mozilla68
Tracking Status
firefox67 --- fixed
firefox68 --- fixed

People

(Reporter: aryx, Assigned: jwatt)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Attachments

(1 file)

https://treeherder.mozilla.org/logviewer.html#?job_id=93486810&repo=mozilla-central [task 2017-04-22T01:28:05.822147Z] 01:28:05 INFO - 9 INFO TEST-START | layout/base/tests/test_bug114649.html [task 2017-04-22T01:28:26.605626Z] 01:28:26 INFO - Buffered messages logged at 01:28:09 [task 2017-04-22T01:28:26.605991Z] 01:28:26 INFO - 10 INFO TEST-PASS | layout/base/tests/test_bug114649.html | A valid string reason is expected [task 2017-04-22T01:28:26.606527Z] 01:28:26 INFO - 11 INFO TEST-PASS | layout/base/tests/test_bug114649.html | Reason cannot be empty [task 2017-04-22T01:28:26.607542Z] 01:28:26 INFO - Buffered messages logged at 01:28:16 [task 2017-04-22T01:28:26.607978Z] 01:28:26 INFO - 12 INFO TEST-FAIL | layout/base/tests/test_bug114649.html | The author of the test has indicated that flaky timeouts are expected. Reason: untriaged [task 2017-04-22T01:28:26.608658Z] 01:28:26 INFO - Buffered messages logged at 01:28:17 [task 2017-04-22T01:28:26.609550Z] 01:28:26 INFO - 13 INFO TEST-PASS | layout/base/tests/test_bug114649.html | got continuous events [task 2017-04-22T01:28:26.610389Z] 01:28:26 INFO - 14 INFO TEST-PASS | layout/base/tests/test_bug114649.html | got continuous events [task 2017-04-22T01:28:26.610803Z] 01:28:26 INFO - Buffered messages finished [task 2017-04-22T01:28:26.611737Z] 01:28:26 INFO - 15 INFO TEST-UNEXPECTED-FAIL | layout/base/tests/test_bug114649.html | got last event - got 410, expected 400 [task 2017-04-22T01:28:26.612227Z] 01:28:26 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:310:5 [task 2017-04-22T01:28:26.612880Z] 01:28:26 INFO - check_for_resize_events@layout/base/tests/test_bug114649.html:60:2
Priority: -- → P3

There are 25 total failures in the last 7 days and 51 total failures in the last 30 on android-em-4-3-armv7-api16 debug.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=227095843&repo=autoland&lineNumber=1452

[task 2019-02-08T05:19:18.908Z] 05:19:18 INFO - TEST-START | layout/forms/test/test_bug348236.html
[task 2019-02-08T05:19:18.908Z] 05:19:18 INFO - TEST-SKIP | layout/forms/test/test_bug348236.html | took 0ms
[task 2019-02-08T05:19:18.909Z] 05:19:18 INFO - Running manifest: layout/base/tests/mochitest.ini
[task 2019-02-08T05:19:20.421Z] 05:19:20 INFO - adb Ignoring attempt to chmod external storage
[task 2019-02-08T05:19:20.479Z] 05:19:20 INFO - runtests.py | Failed to copy /builds/worker/workspace/build/tests/mochitest/hyphenation to profile
[task 2019-02-08T05:19:20.969Z] 05:19:20 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2019-02-08T05:19:21.041Z] 05:19:21 INFO - MochitestServer : launching [u'/builds/worker/workspace/build/hostutils/host-utils-66.0a1.en-US.linux-x86_64/xpcshell', '-g', '/builds/worker/workspace/build/hostutils/host-utils-66.0a1.en-US.linux-x86_64', '-f', '/builds/worker/workspace/build/hostutils/host-utils-66.0a1.en-US.linux-x86_64/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpObTJXZ.mozrunner'; const _SERVER_PORT = '8854'; const _SERVER_ADDR = '10.0.2.2'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2019-02-08T05:19:21.041Z] 05:19:21 INFO - runtests.py | Server pid: 1060
[task 2019-02-08T05:19:21.046Z] 05:19:21 INFO - runtests.py | Websocket server pid: 1063
[task 2019-02-08T05:19:21.055Z] 05:19:21 INFO - runtests.py | SSL tunnel pid: 1066
[task 2019-02-08T05:19:24.601Z] 05:19:24 INFO - adb Ignoring attempt to chmod external storage
[task 2019-02-08T05:19:24.602Z] 05:19:24 INFO - runtests.py | Running with scheme: http
[task 2019-02-08T05:19:24.602Z] 05:19:24 INFO - runtests.py | Running with e10s: False
[task 2019-02-08T05:19:24.602Z] 05:19:24 INFO - runtests.py | Running with serviceworker_e10s: False
[task 2019-02-08T05:19:24.602Z] 05:19:24 INFO - runtests.py | Running tests: start.
[task 2019-02-08T05:19:25.018Z] 05:19:25 INFO - adb launch_application: am start -W -n org.mozilla.fennec_aurora/org.mozilla.gecko.BrowserApp -a android.intent.action.VIEW --es env9 MOZ_UPLOAD_DIR=/sdcard/tests/mozlog --es env8 R_LOG_DESTINATION=stderr --es args "-no-remote -profile /sdcard/tests/profile//" --es env3 DISABLE_UNSAFE_CPOW_WARNINGS=1 --es env2 R_LOG_VERBOSE=1 --es env1 XPCOM_DEBUG_BREAK=stack --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_LOG_FILE=/sdcard/tests/mozlog/moz.log --es env6 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env5 MOZ_IN_AUTOMATION=1 --es env4 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env12 MOZ_HIDE_RESULTS_TABLE=1 --es env11 R_LOG_LEVEL=6 --es env10 MOZ_CRASHREPORTER_NO_REPORT=1 -d "http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&logFile=%2Fsdcard%2Ftests%2Flogs%2Fmochitest.log&fileLevel=INFO&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fsdcard%2Ftests"
[task 2019-02-08T05:19:37.454Z] 05:19:37 INFO - remoteautomation.py | Application pid: 857
[task 2019-02-08T05:22:15.103Z] 05:22:15 INFO - 0 INFO SimpleTest START
[task 2019-02-08T05:22:15.104Z] 05:22:15 INFO - 1 INFO TEST-START | layout/base/tests/test_bug1093686.html
[task 2019-02-08T05:22:36.241Z] 05:22:36 INFO - 2 INFO TEST-OK | layout/base/tests/test_bug1093686.html | took 29001ms
[task 2019-02-08T05:22:46.465Z] 05:22:46 INFO - 3 INFO TEST-START | layout/base/tests/test_bug114649.html
[task 2019-02-08T05:22:56.680Z] 05:22:56 INFO - Buffered messages logged at 05:22:43
[task 2019-02-08T05:22:56.680Z] 05:22:56 INFO - 4 INFO TEST-PASS | layout/base/tests/test_bug114649.html | A valid string reason is expected
[task 2019-02-08T05:22:56.680Z] 05:22:56 INFO - 5 INFO TEST-PASS | layout/base/tests/test_bug114649.html | Reason cannot be empty
[task 2019-02-08T05:22:56.680Z] 05:22:56 INFO - Buffered messages logged at 05:22:47
[task 2019-02-08T05:22:56.680Z] 05:22:56 INFO - 6 INFO TEST-FAIL | layout/base/tests/test_bug114649.html | The author of the test has indicated that flaky timeouts are expected. Reason: untriaged
[task 2019-02-08T05:22:56.680Z] 05:22:56 INFO - 7 INFO TEST-PASS | layout/base/tests/test_bug114649.html | got continuous events
[task 2019-02-08T05:22:56.680Z] 05:22:56 INFO - 8 INFO TEST-PASS | layout/base/tests/test_bug114649.html | got continuous events
[task 2019-02-08T05:22:56.681Z] 05:22:56 INFO - Buffered messages finished
[task 2019-02-08T05:22:56.681Z] 05:22:56 INFO - 9 INFO TEST-UNEXPECTED-FAIL | layout/base/tests/test_bug114649.html | got last event - got 410, expected 400
[task 2019-02-08T05:22:56.682Z] 05:22:56 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:5
[task 2019-02-08T05:22:56.683Z] 05:22:56 INFO - check_for_resize_events@layout/base/tests/test_bug114649.html:60:2
[task 2019-02-08T05:22:56.684Z] 05:22:56 INFO - 10 INFO TEST-OK | layout/base/tests/test_bug114649.html | took 9361ms

Seam can you please take a look at this or assign someone else to?

Flags: needinfo?(svoisen)
Whiteboard: [stockwell needswork:owner]

@jwatt: Do you know much about this test? Seems like it's only failing on Android. It looks pretty old but I believe dbaron applied your patch.

Flags: needinfo?(svoisen) → needinfo?(jwatt)

Yes, dbaron landed my patch along with a test:

https://bugzilla.mozilla.org/show_bug.cgi?id=114649#c30

The test decreases the width of an iframe from 500 to 400 px in steps of 10 px, repeated every 50 ms using setInterval. Once the function call that sets a width of 400 is run, it then waits 250 ms before running its checks. The failing check is testing that the last processed 'resize' event was processed when the value set was 400, but it's intermittently getting 410. That seems to indicate that, on mobile, 250 ms is not always enough time for the last event to be processed.

Flags: needinfo?(jwatt)

I think we should change this test to simply finish once we get the 'resize' event that is for 400. And probably change it to use setTimeout instead of setInterval too, so we can't get resizes backing up.

Assignee: nobody → jwatt
Pushed by jwatt@jwatt.org: https://hg.mozilla.org/integration/mozilla-inbound/rev/1136382f98f4 Fix intermittent failures of layout/base/tests/test_bug114649.html. r=dholbert
Pushed by jwatt@jwatt.org: https://hg.mozilla.org/integration/mozilla-inbound/rev/4f7bfdae5246 follow-up. Prevent multiple SimpleTest.finish() calls. r=orange
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla68
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: