Closed Bug 1512068 Opened 7 years ago Closed 5 years ago

Intermittent layout/style/test/test_animations_effect_timing_duration.html | OMTA should work

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

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

Filed by: aciure [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=215458445&repo=autoland https://queue.taskcluster.net/v1/task/A8ffIGaMTMCIQuUj-hZQ9w/runs/0/artifacts/public/logs/live_backing.log 20:58:17 INFO - TEST-START | layout/style/test/test_animations_effect_timing_duration.html 20:58:17 INFO - TEST-INFO | started process screenshot 20:58:41 INFO - TEST-INFO | screenshot: exit 0 20:58:41 INFO - TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_effect_timing_duration.html | OMTA should work 20:58:41 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:7 20:58:41 INFO - runOMTATest/<@layout/style/test/animation_utils.js:217:9 20:58:41 INFO - promise callback*runOMTATest@layout/style/test/animation_utils.js:209:19 20:58:41 INFO - @layout/style/test/test_animations_effect_timing_duration.html:34:1 20:58:41 INFO - GECKO(4340) | MEMORY STAT | vsize 1439MB | vsizeMaxContiguous 132061697MB | residentFast 106MB | heapAllocated 14MB 20:58:41 INFO - TEST-OK | layout/style/test/test_animations_effect_timing_duration.html | took 64ms 20:58:41 INFO - GECKO(4340) | JavaScript error: resource://gre/modules/WebProgressChild.jsm, line 58: TypeError: this.mm.content is null; can't access its "document" property 20:58:41 INFO - TEST-START | layout/style/test/test_animations_effect_timing_enddelay.html 20:58:41 INFO - Not taking screenshot here: see the one that was previously logged 20:58:41 INFO - TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_effect_timing_enddelay.html | OMTA should work 20:58:41 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:7 20:58:41 INFO - runOMTATest/<@layout/style/test/animation_utils.js:217:9 20:58:41 INFO - promise callback*runOMTATest@layout/style/test/animation_utils.js:209:19 20:58:41 INFO - @layout/style/test/test_animations_effect_timing_enddelay.html:30:1 20:58:41 INFO - GECKO(4340) | MEMORY STAT | vsize 1439MB | vsizeMaxContiguous 132061697MB | residentFast 106MB | heapAllocated 14MB 20:58:41 INFO - TEST-OK | layout/style/test/test_animations_effect_timing_enddelay.html | took 45ms
birtles, it looks like this failure means "isWorking" is false in this code: https://searchfox.org/mozilla-central/source/layout/style/test/animation_utils.js#209 ...which I think means isOMTAWorking() returned false (or rejected, or something). Do you know what that means? Note that this was for a run on "Windows 10 x64 pgo", so it's not an uber-slow ASAN build or anything like that.
Flags: needinfo?(bbirtles)
BTW the log shows we're hitting the same failure in several consecutive tests: TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_effect_timing_duration.html | OMTA should work TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_effect_timing_enddelay.html | OMTA should work TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_effect_timing_iterations.html | OMTA should work TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_iterationstart.html | OMTA should work TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_omta.html | OMTA should work TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_omta_start.html | OMTA should work TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_pausing.html | OMTA should work TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_playbackrate.html | OMTA should work TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_reverse.html | OMTA should work
Let's see what the retriggers produce. It looks like the `opacity == 0.5` is failing. Not sure if it's a floating point difference or if OMTA is genuinely not working. I wonder if there have been any graphics-related changes that triggered this (and perhaps this is the first PGO build since such changes?).
Apparently the retriggers were green. Treating this as a one-off for now. If it happens again we can hopefully find a pattern.
Flags: needinfo?(bbirtles)
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE

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

17:25:10 INFO - TEST-START | layout/style/test/test_animations_effect_timing_duration.html
17:25:10 INFO - TEST-INFO | started process screencapture
17:25:10 INFO - TEST-INFO | screencapture: exit 0
17:25:10 INFO - TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_effect_timing_duration.html | OMTA should work
17:25:10 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:7
17:25:10 INFO - runOMTATest/<@layout/style/test/animation_utils.js:217:9
17:25:10 INFO - promise callback*runOMTATest@layout/style/test/animation_utils.js:209:19
17:25:10 INFO - @layout/style/test/test_animations_effect_timing_duration.html:34:1
17:25:10 INFO - GECKO(2015) | MEMORY STAT | vsize 3955MB | residentFast 122MB | heapAllocated 14MB
17:25:10 INFO - TEST-OK | layout/style/test/test_animations_effect_timing_duration.html | took 132ms

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 years ago7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Whiteboard: [retriggered] → [retriggered][stockwell unknown]
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
No longer regressed by: 1554499
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=318911050&repo=mozilla-central&lineNumber=2376

[task 2020-10-17T09:45:16.360Z] 09:45:16 INFO - TEST-START | http://mochi.test:8888/tests/layout/style/test/test_animations_effect_timing_duration.html
[task 2020-10-17T09:45:16.425Z] 09:45:16 INFO - GECKO(1508) | [Child 1995, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2020-10-17T09:45:16.446Z] 09:45:16 INFO - GECKO(1508) | [Child 1995, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-17T09:45:16.517Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.532Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.533Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.534Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.536Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.544Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.545Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.545Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.546Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.549Z] 09:45:16 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: Not implemented in the parent process!: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:503
[task 2020-10-17T09:45:16.578Z] 09:45:16 INFO - GECKO(1508) | [2109, Unnamed thread 7f4a53d5f5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2020-10-17T09:45:16.579Z] 09:45:16 INFO - GECKO(1508) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp2eFpSc.mozrunner/runtests_leaks.log
[task 2020-10-17T09:45:16.580Z] 09:45:16 INFO - GECKO(1508) | [2109, Unnamed thread 7f4a53d5f5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2020-10-17T09:45:16.581Z] 09:45:16 INFO - GECKO(1508) | [2109, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:249
[task 2020-10-17T09:45:16.826Z] 09:45:16 INFO - TEST-INFO | started process screentopng
[task 2020-10-17T09:45:17.450Z] 09:45:17 INFO - TEST-INFO | screentopng: exit 0
[task 2020-10-17T09:45:17.450Z] 09:45:17 INFO - TEST-UNEXPECTED-FAIL | layout/style/test/test_animations_effect_timing_duration.html | OMTA should work
[task 2020-10-17T09:45:17.451Z] 09:45:17 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-10-17T09:45:17.451Z] 09:45:17 INFO - runOMTATest/<@layout/style/test/animation_utils.js:246:13
[task 2020-10-17T09:45:17.451Z] 09:45:17 INFO - GECKO(1508) | MEMORY STAT | vsize 2559MB | residentFast 157MB | heapAllocated 9MB
[task 2020-10-17T09:45:17.451Z] 09:45:17 INFO - GECKO(1508) | [Child 2109, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:626
[task 2020-10-17T09:45:17.451Z] 09:45:17 INFO - TEST-OK | http://mochi.test:8888/tests/layout/style/test/test_animations_effect_timing_duration.html | took 586ms
[task 2020-10-17T09:45:17.451Z] 09:45:17 INFO - GECKO(1508) | [Parent 1508, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/obj-build/dist/include/mozilla/ipc/ProtocolUtils.h:295
[task 2020-10-17T09:45:17.452Z] 09:45:17 INFO - GECKO(1508) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

That issue (a timeout) looks like it's unrelated to the original issue here (a test failure). Would you mind spinning that off as a separate bug?

Flags: needinfo?(cpeterson)

(In reply to Daniel Holbert [:dholbert] from comment #49)

That issue (a timeout) looks like it's unrelated to the original issue here (a test failure). Would you mind spinning that off as a separate bug?

Sorry about that! I will file a separate bug.

No longer blocks: 1700781
Flags: needinfo?(cpeterson)
Summary: [M-fis-xorig] Intermittent layout/style/test/test_animations_effect_timing_duration.html | OMTA should work → Intermittent layout/style/test/test_animations_effect_timing_duration.html | OMTA should work
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.