Intermittent TEST-UNEXPECTED-NOTRUN | /resize-observer/observe.html | guard - expected PASS
Categories
(Core :: Layout, defect, P5)
Tracking
()
Tracking | Status | |
---|---|---|
firefox70 | --- | fixed |
People
(Reporter: intermittent-bug-filer, Assigned: boris)
References
(Regression)
Details
(Keywords: intermittent-failure, regression, Whiteboard: [retriggered])
Attachments
(2 files)
#[markdown(off)]
Filed by: rmaries [at] mozilla.com
https://treeherder.mozilla.org/logviewer.html#?job_id=243136536&repo=mozilla-central
[task 2019-04-28T00:31:24.524Z] 00:31:24 INFO - TEST-START | /resize-observer/observe.html
[task 2019-04-28T00:31:24.526Z] 00:31:24 INFO - Closing window 6442450949
[task 2019-04-28T00:31:34.944Z] 00:31:34 INFO -
[task 2019-04-28T00:31:34.945Z] 00:31:34 INFO - TEST-PASS | /resize-observer/observe.html | ResizeObserver implemented
[task 2019-04-28T00:31:34.945Z] 00:31:34 INFO - TEST-UNEXPECTED-NOTRUN | /resize-observer/observe.html | guard - expected PASS
[task 2019-04-28T00:31:34.946Z] 00:31:34 INFO -
[task 2019-04-28T00:31:34.946Z] 00:31:34 INFO - TEST-PASS | /resize-observer/observe.html | test0: simple observation
[task 2019-04-28T00:31:34.946Z] 00:31:34 INFO - TEST-PASS | /resize-observer/observe.html | test1: multiple observation on same element trigger only one
[task 2019-04-28T00:31:34.946Z] 00:31:34 INFO - TEST-PASS | /resize-observer/observe.html | test2: throw exception when observing non-element
[task 2019-04-28T00:31:34.946Z] 00:31:34 INFO - TEST-PASS | /resize-observer/observe.html | test3: disconnect stops all notifications
[task 2019-04-28T00:31:34.946Z] 00:31:34 INFO - TEST-PASS | /resize-observer/observe.html | test4: unobserve target stops notifications, unobserve non-observed does nothing
[task 2019-04-28T00:31:34.947Z] 00:31:34 INFO - TEST-UNEXPECTED-FAIL | /resize-observer/observe.html | test5: observe img - assert_unreached: Timed out waiting for notification. (100ms) Reached unreachable code
[task 2019-04-28T00:31:34.947Z] 00:31:34 INFO - _handleTimeout/<@http://web-platform.test:8000/resize-observer/resources/resizeTestHelper.js:98:9
[task 2019-04-28T00:31:34.947Z] 00:31:34 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:1587:25
[task 2019-04-28T00:31:34.947Z] 00:31:34 INFO - _handleTimeout@http://web-platform.test:8000/resize-observer/resources/resizeTestHelper.js:93:23
[task 2019-04-28T00:31:34.948Z] 00:31:34 INFO - Test.prototype.step_timeout/<@http://web-platform.test:8000/resources/testharness.js:1645:22
[task 2019-04-28T00:31:34.948Z] 00:31:34 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:1587:25
[task 2019-04-28T00:31:34.948Z] 00:31:34 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:1611:35
[task 2019-04-28T00:31:34.948Z] 00:31:34 INFO - setTimeout handler*Test.prototype.step_timeout@http://web-platform.test:8000/resources/testharness.js:1644:16
[task 2019-04-28T00:31:34.948Z] 00:31:34 INFO - _nextStep@http://web-platform.test:8000/resize-observer/resources/resizeTestHelper.js:65:41
[task 2019-04-28T00:31:34.948Z] 00:31:34 INFO - _handleNotification/<@http://web-platform.test:8000/resize-observer/resources/resizeTestHelper.js:87:14
[task 2019-04-28T00:31:34.949Z] 00:31:34 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:1587:25
[task 2019-04-28T00:31:34.949Z] 00:31:34 INFO - _handleNotification@http://web-platform.test:8000/resize-observer/resources/resizeTestHelper.js:82:23
[task 2019-04-28T00:31:34.949Z] 00:31:34 INFO - ResizeObserverCallback*ResizeTestHelper@http://web-platform.test:8000/resize-observer/resources/resizeTestHelper.js:50:22
[task 2019-04-28T00:31:34.949Z] 00:31:34 INFO - test5@http://web-platform.test:8000/resize-observer/observe.html:113:16
[task 2019-04-28T00:31:34.949Z] 00:31:34 INFO - @http://web-platform.test:8000/resize-observer/observe.html:202:24
[task 2019-04-28T00:31:34.949Z] 00:31:34 INFO - promise callback*@http://web-platform.test:8000/resize-observer/observe.html:202:4
[task 2019-04-28T00:31:34.950Z] 00:31:34 INFO - TEST-UNEXPECTED-TIMEOUT | /resize-observer/observe.html | expected OK
[task 2019-04-28T00:31:34.950Z] 00:31:34 INFO - TEST-INFO took 10425ms
[task 2019-04-28T00:31:34.967Z] 00:31:34 INFO - PID 27608 | 1556411494956 Marionette INFO Stopped listening on port 2828
[task 2019-04-28T00:31:35.613Z] 00:31:35 INFO - Browser exited with return code 0
[task 2019-04-28T00:31:35.613Z] 00:31:35 INFO - PROCESS LEAKS None
[task 2019-04-28T00:31:35.614Z] 00:31:35 INFO - Closing logging queue
[task 2019-04-28T00:31:35.614Z] 00:31:35 INFO - queue closed
[task 2019-04-28T00:31:35.635Z] 00:31:35 INFO - Setting up ssl
[task 2019-04-28T00:31:35.663Z] 00:31:35 INFO - certutil |
[task 2019-04-28T00:31:35.691Z] 00:31:35 INFO - certutil |
[task 2019-04-28T00:31:35.711Z] 00:31:35 INFO - certutil |
[task 2019-04-28T00:31:35.711Z] 00:31:35 INFO - Certificate Nickname Trust Attributes
[task 2019-04-28T00:31:35.713Z] 00:31:35 INFO - SSL,S/MIME,JAR/XPI
[task 2019-04-28T00:31:35.713Z] 00:31:35 INFO -
[task 2019-04-28T00:31:35.713Z] 00:31:35 INFO - web-platform-tests CT,,
[task 2019-04-28T00:31:35.713Z] 00:31:35 INFO -
[task 2019-04-28T00:31:35.729Z] 00:31:35 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpMB6YvR.mozrunner
[task 2019-04-28T00:31:35.733Z] 00:31:35 INFO - Starting runner
[task 2019-04-28T00:31:36.257Z] 00:31:36 INFO - PID 27910 | 1556411496245 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-04-28T00:31:36.258Z] 00:31:36 INFO - PID 27910 | 1556411496246 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-04-28T00:31:36.259Z] 00:31:36 INFO - PID 27910 | 1556411496246 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
[task 2019-04-28T00:31:42.314Z] 00:31:42 INFO - PID 27910 | 1556411502310 Marionette INFO Listening on port 2828
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 2•6 years ago
|
||
I'd like to add a real image src and wait image.onload event, and then start the test in https://phabricator.services.mozilla.com/D29889. Hope this is helpful.
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 8•6 years ago
|
||
It started appearing also outside of tier 2: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=250103294&repo=autoland&lineNumber=29944
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 11•6 years ago
•
|
||
This has now a pretty high occurrence rate on beta-sims: https://treeherder.mozilla.org/#/jobs?repo=try&selectedJob=253019978&resultStatus=testfailed%2Cbusted%2Cexception&revision=0086e113bb96fbb383f5b8eb8b235776e7463a46&searchStr=linux%2Cx64%2Cquantumrender%2Cshippable%2Copt%2Cweb%2Cplatform%2Ctests%2Ctest-linux64-shippable-qr%2Fopt-web-platform-tests-e10s-5%2Cw%28wpt5%29
Failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=253019978&repo=try&lineNumber=7011
Emilio, could you please take a look into it? Could it be from Bug 1536871? Thank you.
Comment 12•6 years ago
|
||
Sounds more likely to be from bug 1543839 and co. Boris any idea?
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 14•6 years ago
|
||
(In reply to Emilio Cobos Álvarez (:emilio) from comment #12)
Sounds more likely to be from bug 1543839 and co. Boris any idea?
I'm trying to reproduce this. However, I think this high occurrence rate (~20%) on beta-sims is not related to bug 1543839 because we always run the wpt with the pref enabled [1], and we don't have any new update in the train (i.e. only enable the pref for normal usage). This log looks like the callback was not called for <img>
element [2], and it only happened on web-reneder? if so, the recently change on web-render on image may cause this high occurrence rate. Or maybe it is just a test case issue (e.g. the creation of ResizeObserver is too early because the image is not ready?)
[1] https://searchfox.org/mozilla-beta/rev/dd3976557cf0a37e156f09be4e757af13820b510/testing/web-platform/meta/resize-observer/__dir__.ini#1
[2] https://searchfox.org/mozilla-beta/rev/dd3976557cf0a37e156f09be4e757af13820b510/testing/web-platform/tests/resize-observer/observe.html#133-150
Assignee | ||
Comment 15•6 years ago
|
||
I also see this high occurrence rate in m-c, since June 20, and only happened on linux64 quantum-render.
Assignee | ||
Comment 16•6 years ago
|
||
Based on the suggestion in html spec:
https://html.spec.whatwg.org/multipage/embedded-content.html#dom-img-decode
We decode the image in parallel, and only inserting it into the DOM (and thus
causing it to be painted) once the decoding process is complete.
Because the decode() method attempts to ensure that the decoded image data is
available for at least one frame, it can be combined with the
requestAnimationFrame() API. This means it can be used with coding styles or
frameworks that ensure that all DOM modifications are batched together as
animation frame callbacks.
Hope this could reduce the occurrence rate of this intermittent.
Assignee | ||
Comment 17•6 years ago
|
||
Updated•6 years ago
|
Assignee | ||
Updated•6 years ago
|
Assignee | ||
Comment 18•6 years ago
•
|
||
(In reply to Boris Chiou [:boris] from comment #17)
try: https://treeherder.mozilla.org/#/jobs?repo=try&revision=22d25fb4aa59a779743d40ccd8bd986b6887e138
Just tried 20 times, and didn't see this failure with this patch. (Those oranges are caused by another intermittent.)
Without this patch, it's easier to reproduce this in m-c, especially on "Linux x64 QuantumRender Shippable opt".
Updated•6 years ago
|
Comment 19•6 years ago
|
||
Comment 21•6 years ago
|
||
bugherder |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 25•6 years ago
|
||
Comment hidden (Intermittent Failures Robot) |
Comment 27•6 years ago
|
||
No occurrences here since the 24th of July.
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 33•6 years ago
|
||
Comment 34•6 years ago
|
||
Comment 35•6 years ago
|
||
bugherder |
Comment hidden (Intermittent Failures Robot) |
Comment 37•6 years ago
|
||
bugherder uplift |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 53•4 years ago
|
||
Close this because https://searchfox.org/mozilla-central/source/testing/web-platform/meta/resize-observer/observe.html.ini has mark test5 as intermittent on windows, and the timeout of test5() shouldn't make guard failed now.
Updated•4 years ago
|
Updated•4 years ago
|
Updated•3 years ago
|
Description
•