Closed Bug 1662816 Opened 4 years ago Closed 3 years ago

Intermittent /intersection-observer/timestamp.html | Generate notifications. - assert_between_inclusive: Notification to top window observer is within the expected range. expected a number greater than or equal to 630.22 and less than or equal to 673.18 b

Categories

(Core :: Layout, defect, P5)

defect

Tracking

()

RESOLVED FIXED
85 Branch
Tracking Status
firefox85 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: dholbert)

Details

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

Attachments

(1 file)

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=314686953&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/azjEErTZRcmgR4TO2VYVCQ/runs/0/artifacts/public/logs/live_backing.log


[task 2020-09-02T12:02:03.547Z] 12:02:03 INFO - TEST-START | /intersection-observer/timestamp.html
[task 2020-09-02T12:02:03.548Z] 12:02:03 INFO - Clearing pref dom.IntersectionObserverExplicitDocumentRoot.enabled
[task 2020-09-02T12:02:03.563Z] 12:02:03 INFO - Clearing pref dom.IntersectionObserver.enabled
[task 2020-09-02T12:02:03.592Z] 12:02:03 INFO - Setting pref dom.IntersectionObserverExplicitDocumentRoot.enabled (true)
[task 2020-09-02T12:02:03.628Z] 12:02:03 INFO - Setting pref privacy.reduceTimerPrecision (false)
[task 2020-09-02T12:02:03.676Z] 12:02:03 INFO - Setting pref dom.IntersectionObserver.enabled (true)
[task 2020-09-02T12:02:03.692Z] 12:02:03 INFO - Closing window 108
[task 2020-09-02T12:02:03.732Z] 12:02:03 INFO - .
[task 2020-09-02T12:02:03.733Z] 12:02:03 INFO - TEST-OK | /mathml/presentation-markup/direction/direction.html | took 1210ms
[task 2020-09-02T12:02:03.741Z] 12:02:03 INFO - Restarting browser for new test group
[task 2020-09-02T12:02:03.809Z] 12:02:03 INFO - PID 15172 | 1599048123803 Marionette INFO Stopped listening on port 44418
[task 2020-09-02T12:02:04.154Z] 12:02:04 INFO - PID 15172 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-09-02T12:02:04.182Z] 12:02:04 INFO - PID 15172 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-09-02T12:02:04.283Z] 12:02:04 INFO - PID 15172 | [2020-09-02T12:02:04Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-09-02T12:02:04.285Z] 12:02:04 INFO - PID 15172 | [2020-09-02T12:02:04Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-09-02T12:02:04.505Z] 12:02:04 INFO - PID 15172 | [Child 15307, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-02T12:02:04.526Z] 12:02:04 INFO - PID 15172 | [Child 15307, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4286
[task 2020-09-02T12:02:04.647Z] 12:02:04 INFO - PID 15172 | [Child 15332, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-02T12:02:04.703Z] 12:02:04 INFO - PID 15172 | [Child 15332, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4286
[task 2020-09-02T12:02:04.948Z] 12:02:04 INFO -
[task 2020-09-02T12:02:04.948Z] 12:02:04 INFO - TEST-PASS | /intersection-observer/timestamp.html | Check that timestamps correspond to the to execution context that created the observer.
[task 2020-09-02T12:02:04.948Z] 12:02:04 INFO - TEST-PASS | /intersection-observer/timestamp.html | First rAF after iframe is loaded.
[task 2020-09-02T12:02:04.948Z] 12:02:04 INFO - TEST-UNEXPECTED-FAIL | /intersection-observer/timestamp.html | Generate notifications. - assert_between_inclusive: Notification to top window observer is within the expected range. expected a number greater than or equal to 630.22 and less than or equal to 673.18 but got 619.88
[task 2020-09-02T12:02:04.948Z] 12:02:04 INFO - step2@http://web-platform.test:8000/intersection-observer/timestamp.html:87:27
[task 2020-09-02T12:02:04.948Z] 12:02:04 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:1977:25
[task 2020-09-02T12:02:04.948Z] 12:02:04 INFO - Test.prototype.step_func_done/<@http://web-platform.test:8000/resources/testharness.js:2018:32
[task 2020-09-02T12:02:04.949Z] 12:02:04 INFO - Test.prototype.step_timeout/<@http://web-platform.test:8000/resources/testharness.js:2036:22
[task 2020-09-02T12:02:04.949Z] 12:02:04 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:1977:25
[task 2020-09-02T12:02:04.949Z] 12:02:04 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2002:35

There are 57 total failures in the last 7 days on

  • android-em-7-0-x86_64-qr debug and opt
  • linux1804-64 debug
  • linux1804-64-qr opt
  • linux1804-64-shippable-qr opt
  • windows10-64 debug
  • windows10-64-qr opt and debug

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=323980903&repo=mozilla-central&lineNumber=16485

[task 2020-12-08T22:22:27.084Z] 22:22:27 INFO - TEST-START | /intersection-observer/timestamp.html
[task 2020-12-08T22:22:27.089Z] 22:22:27 INFO - Clearing pref dom.IntersectionObserverExplicitDocumentRoot.enabled
[task 2020-12-08T22:22:27.099Z] 22:22:27 INFO - Clearing pref dom.IntersectionObserver.enabled
[task 2020-12-08T22:22:27.118Z] 22:22:27 INFO - Setting pref dom.IntersectionObserverExplicitDocumentRoot.enabled (true)
[task 2020-12-08T22:22:27.137Z] 22:22:27 INFO - Setting pref privacy.reduceTimerPrecision (false)
[task 2020-12-08T22:22:27.156Z] 22:22:27 INFO - Setting pref dom.IntersectionObserver.enabled (true)
[task 2020-12-08T22:22:27.161Z] 22:22:27 INFO - Closing window 119
[task 2020-12-08T22:22:27.299Z] 22:22:27 INFO - PID 8968 | [Parent 220, IPDL Background] WARNING: Cannot record shutdown step, mConnection is nullptr: file /builds/worker/checkouts/gecko/dom/localstorage/ActorsParent.cpp:8027
[task 2020-12-08T22:22:27.358Z] 22:22:27 INFO - PID 8968 | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2020-12-08T22:22:27.478Z] 22:22:27 INFO - PID 8968 | [Parent 220, IPDL Background] WARNING: Cannot record shutdown step, mConnection is nullptr: file /builds/worker/checkouts/gecko/dom/localstorage/ActorsParent.cpp:8027
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO -
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - TEST-PASS | /intersection-observer/timestamp.html | Check that timestamps correspond to the to execution context that created the observer.
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - TEST-PASS | /intersection-observer/timestamp.html | First rAF after iframe is loaded.
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - TEST-UNEXPECTED-FAIL | /intersection-observer/timestamp.html | Generate notifications. - assert_between_inclusive: Notification to top window observer is within the expected range. expected a number greater than or equal to 304.38 and less than or equal to 324.2 but got 303.7
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - step2@http://web-platform.test:8000/intersection-observer/timestamp.html:87:27
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2044:25
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - Test.prototype.step_func_done/<@http://web-platform.test:8000/resources/testharness.js:2085:32
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - Test.prototype.step_timeout/<@http://web-platform.test:8000/resources/testharness.js:2103:22
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2044:25
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2069:35
[task 2020-12-08T22:22:27.701Z] 22:22:27 INFO - TEST-OK | /intersection-observer/timestamp.html | took 616ms

Sean can you take a look?

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

There have been 61 failures in the last 7 days.

  • 23 failures on android-em-7-0-x86_64
  • 17 failures on windows10-64
  • 16 failures on linux1804-64
  • 4 failures on windows7-32
  • 1 failure on macosx1014-64

Daniel, can you please take a look?

Flags: needinfo?(dholbert)

The test failure text quoted in 15 says (emphasis added):
"expected a number greater than or equal to 304.38 and less than or equal to 324.2 but got 303.7

Without having investigated super-thoroughly, this looks like a pretty near miss and not a catastrophic failure. Could even be a bug in the test's kinda-arbitrary-looking upper and lower bounds, but it would take some additional investigation to determine that.

Until someone has time to investigate, we should probably just annotate this as a known failure. (The test has one other annotated known failure, too.)

Flags: needinfo?(sean)

Actually, this failure (Generate notifications...) is just a version of the test's already-annotated failure. It's just that the annotated failure has some "only on these platforms" limitations/configurations, which are a bit too specific and need broadening, I think. (It looks like they were added in an ad-hoc way in bug 1664225.

Based on the existing annotation, combined with the failures noted here: it looks like this just intermittently fails on all platforms/configurations, so we should just drop the platform-specific qualifiers.

Assignee: nobody → dholbert
Status: NEW → ASSIGNED
Pushed by rmaries@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/9482a52c5b88
Remove qualifiers on intermittent-failure annotation for intersection-observer/timestamp.html. r=emilio
Status: ASSIGNED → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 85 Branch
Flags: needinfo?(dholbert)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: