1499123 - WPT Intermittent TEST-UNEXPECTED-PASS | /feature-policy/experimental-features/lazyload/lazyload-disabled-tentative.sub.html | Sanity-check: Contents do not load immediately (no eager-loading) when the lazyload attribute is 'on' and frame is in viewport.

Status: RESOLVED FIXED

(Core :: DOM: Core & HTML, defect, P3)

Description

[Treeherder Bug Filer]

Filed by: archaeopteryx [at] coole-files.de

https://treeherder.mozilla.org/logviewer.html#?job_id=204706353&repo=mozilla-central

https://queue.taskcluster.net/v1/task/aGHuFQbhROOnLalpXSyiRQ/runs/0/artifacts/public/logs/live_backing.log

05:39:04     INFO - TEST-START | /feature-policy/experimental-features/lazyload/lazyload-disabled-tentative.sub.html
05:39:04     INFO - Setting pref dom.security.featurePolicy.enabled (true)
05:39:05     INFO - Setting pref dom.payments.request.enabled (true)
05:39:05     INFO - PID 4756 | ++DOCSHELL 000001938A808800 == 2 [pid = 4964] [id = {cd719a19-eccb-4a3b-8e95-15b5f2fc2468}]
05:39:05     INFO - PID 4756 | ++DOMWINDOW == 5 (000001938372D400) [pid = 4964] [serial = 5] [outer = 0000000000000000]
05:39:05     INFO - PID 4756 | ++DOMWINDOW == 6 (0000019389DB3000) [pid = 4964] [serial = 6] [outer = 000001938372D400]
05:39:05     INFO - PID 4756 | ++DOMWINDOW == 7 (0000019389DB5000) [pid = 4964] [serial = 7] [outer = 000001938372D400]
05:39:05     INFO - PID 4756 | ++DOCSHELL 000001938A80E000 == 3 [pid = 4964] [id = {c025e60c-720b-48d3-a81d-9958f6383c3e}]
05:39:05     INFO - PID 4756 | ++DOMWINDOW == 8 (0000019389DDF400) [pid = 4964] [serial = 8] [outer = 0000000000000000]
05:39:05     INFO - PID 4756 | ++DOMWINDOW == 9 (0000019389DB8400) [pid = 4964] [serial = 9] [outer = 0000019389DDF400]
05:39:06     INFO - PID 4756 | ++DOMWINDOW == 10 (0000019389DBA000) [pid = 4964] [serial = 10] [outer = 0000019389DDF400]
05:39:06     INFO - PID 4756 | ++DOCSHELL 000001938AB14000 == 4 [pid = 4964] [id = {5ab85472-f9e0-465f-b7c0-8ddb18ecd51c}]
05:39:06     INFO - PID 4756 | ++DOMWINDOW == 11 (000001938ABA9800) [pid = 4964] [serial = 11] [outer = 0000000000000000]
05:39:06     INFO - PID 4756 | ++DOMWINDOW == 12 (000001938A8E4400) [pid = 4964] [serial = 12] [outer = 000001938ABA9800]
05:39:06     INFO - PID 4756 | --DOCSHELL 000002607036D000 == 0 [pid = 7300] [id = {f4b2f842-79cb-4f7c-af1f-97be9e95b4a8}]
05:39:08     INFO - PID 4756 | ++DOCSHELL 00000193898B4000 == 5 [pid = 4964] [id = {d45f763e-2ad7-413f-8403-495f6760d2c8}]
05:39:08     INFO - PID 4756 | ++DOMWINDOW == 13 (0000019383728000) [pid = 4964] [serial = 13] [outer = 0000000000000000]
05:39:08     INFO - PID 4756 | ++DOMWINDOW == 14 (000001938B32A800) [pid = 4964] [serial = 14] [outer = 0000019383728000]
05:39:08     INFO - PID 4756 | ++DOCSHELL 000001938B378800 == 6 [pid = 4964] [id = {d58d2213-f701-4895-ad15-1f20e78603c8}]
05:39:08     INFO - PID 4756 | ++DOMWINDOW == 15 (00000193898ED000) [pid = 4964] [serial = 15] [outer = 0000000000000000]
05:39:08     INFO - PID 4756 | ++DOMWINDOW == 16 (0000019389DB6400) [pid = 4964] [serial = 16] [outer = 00000193898ED000]
05:39:09     INFO - 
05:39:09     INFO - TEST-UNEXPECTED-PASS | /feature-policy/experimental-features/lazyload/lazyload-disabled-tentative.sub.html | Sanity-check: Contents do not load immediately (no eager-loading) when the lazyload attribute is 'on' and frame is in viewport. - expected FAIL
05:39:09     INFO - TEST-INFO | expected FAIL

Comment 1

[Firefox Bug Husbandry Bot]

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

Comment 2

[Andreea Pavel [:apavel]]

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

Comment 3

[Intermittent Failures Robot]

5 failures in 4004 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5

Platform breakdown:

• linux1804-64-qr: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform breakdown:

• linux1804-64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-01-27&endday=2020-02-02&tree=all

Comment 5

[Intermittent Failures Robot]

6 failures in 3364 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6

Platform breakdown:

• linux1804-64-qr: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-02-03&endday=2020-02-09&tree=all

Comment 6

[Intermittent Failures Robot]

6 failures in 4690 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6

Platform breakdown:

• linux1804-64-qr: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-02-10&endday=2020-02-16&tree=all

Comment 7

[Intermittent Failures Robot]

9 failures in 4045 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8
• mozilla-central: 1

Platform breakdown:

• linux1804-64-qr: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-02-17&endday=2020-02-23&tree=all

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-03-02&endday=2020-03-08&tree=all

Comment 9

[Intermittent Failures Robot]

7 failures in 5336 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7

Platform breakdown:

• linux1804-64-qr: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-03-09&endday=2020-03-15&tree=all

Comment 10

[Intermittent Failures Robot]

7 failures in 4955 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• mozilla-central: 1

Platform breakdown:

• linux1804-64-qr: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-03-16&endday=2020-03-22&tree=all

Comment 11

[Intermittent Failures Robot]

15 failures in 4773 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 13
• mozilla-central: 2

Platform breakdown:

• linux1804-64-qr: 15

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-03-23&endday=2020-03-29&tree=all

Comment 12

[Intermittent Failures Robot]

22 failures in 923 pushes (0.024 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 22

Platform breakdown:

• linux1804-64-qr: 22

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-03-31&endday=2020-03-31&tree=all

Comment 13

[Andrei Ciure[:aciure]]

There are 41 failures associated to this bug in the last 7 days. These are occurring on linux1804-64-qr debug builds.

recent log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=295777955&repo=autoland&lineNumber=9645
[task 2020-04-01T16:00:37.723Z] 16:00:37 INFO - PID 10206 | [Child 10903, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/checkouts/gecko/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2020-04-01T16:00:38.280Z] 16:00:38 INFO -
[task 2020-04-01T16:00:38.282Z] 16:00:38 INFO - TEST-UNEXPECTED-PASS | /feature-policy/experimental-features/lazyload/lazyload-disabled-tentative.sub.html | Sanity-check: Contents do not load immediately (no eager-loading) when the lazyload attribute is 'on' and frame is in viewport. - expected FAIL
[task 2020-04-01T16:00:38.282Z] 16:00:38 INFO - TEST-INFO | expected FAIL
[task 2020-04-01T16:00:38.282Z] 16:00:38 INFO - .
[task 2020-04-01T16:00:38.283Z] 16:00:38 INFO - TEST-OK | /feature-policy/experimental-features/lazyload/lazyload-disabled-tentative.sub.html | took 4102ms
[task 2020-04-01T16:00:38.338Z] 16:00:38 INFO - PID 10206 | 1585756838323 Marionette INFO Stopped listening on port 44424
[task 2020-04-01T16:00:38.928Z] 16:00:38 INFO - PID 10206 | [GPU 10300, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp, line 371
[task 2020-04-01T16:00:39.050Z] 16:00:39 INFO - PID 10206 | [2020-04-01T16:00:39Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-04-01T16:00:39.051Z] 16:00:39 INFO - PID 10206 | [2020-04-01T16:00:39Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-04-01T16:00:39.067Z] 16:00:39 INFO - PID 10206 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-01T16:00:39.453Z] 16:00:39 INFO - PID 10206 | [Child 10356, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-04-01T16:00:39.524Z] 16:00:39 INFO - PID 10206 | nsStringStats
[task 2020-04-01T16:00:39.524Z] 16:00:39 INFO - PID 10206 | => mAllocCount: 24393
[task 2020-04-01T16:00:39.525Z] 16:00:39 INFO - PID 10206 | => mReallocCount: 0
[task 2020-04-01T16:00:39.525Z] 16:00:39 INFO - PID 10206 | => mFreeCount: 24393
[task 2020-04-01T16:00:39.526Z] 16:00:39 INFO - PID 10206 | => mShareCount: 11652
[task 2020-04-01T16:00:39.528Z] 16:00:39 INFO - PID 10206 | => mAdoptCount: 463
[task 2020-04-01T16:00:39.530Z] 16:00:39 INFO - PID 10206 | => mAdoptFreeCount: 495
[task 2020-04-01T16:00:39.531Z] 16:00:39 INFO - PID 10206 | => Process ID: 10356, Thread ID: 140654744885120
[task 2020-04-01T16:00:39.531Z] 16:00:39 INFO - PID 10206 | [Child 10903, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-04-01T16:00:39.572Z] 16:00:39 INFO - PID 10206 | nsStringStats
[task 2020-04-01T16:00:39.573Z] 16:00:39 INFO - PID 10206 | => mAllocCount: 11754
[task 2020-04-01T16:00:39.575Z] 16:00:39 INFO - PID 10206 | => mReallocCount: 0
[task 2020-04-01T16:00:39.576Z] 16:00:39 INFO - PID 10206 | => mFreeCount: 11754
[task 2020-04-01T16:00:39.580Z] 16:00:39 INFO - PID 10206 | => mShareCount: 13043
[task 2020-04-01T16:00:39.581Z] 16:00:39 INFO - PID 10206 | => mAdoptCount: 492
[task 2020-04-01T16:00:39.582Z] 16:00:39 INFO - PID 10206 | => mAdoptFreeCount: 510
[task 2020-04-01T16:00:39.583Z] 16:00:39 INFO - PID 10206 | => Process ID: 10903, Thread ID: 140097846302592
[task 2020-04-01T16:00:39.854Z] 16:00:39 INFO - PID 10206 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-01T16:00:39.891Z] 16:00:39 INFO - PID 10206 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-01T16:00:39.983Z] 16:00:39 INFO - PID 10206 | [Parent 10206, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x80070057: file /builds/worker/checkouts/gecko/extensions/permissions/nsPermissionManager.cpp, line 1663
[task 2020-04-01T16:00:40.024Z] 16:00:40 INFO - PID 10206 | nsStringStats

:hsinyi any updates for this?

Comment 14

[Hsin-Yi Tsai (she/her) [:hsinyi]]

Maybe Christoph or Hiro have ideas for this.

Comment 15

[Hiroyuki Ikezoe (:hiro)]

I am afraid I have no idea why this test fails intermittently because the test is for lazy loading iframe.

As I said in bug 1620710 comment 1, if a test for lazy loading iframe is flaky, it means the test itself has issues because we haven't yet implemented lazy loading iframe.

(I wanted to add Ehsan Karamad who is the original author of the test in CC but it doesn't allow me for some reasons).

Comment 16

[Christoph Kerschbaumer [:ckerschb]]

(In reply to Hsin-Yi Tsai [:hsinyi] from comment #14)

Maybe Christoph or Hiro have ideas for this.

302 to baku who did Feature Policy. Baku, can you take a quick look by any chance?

Comment 17

[Andrea Marchesini [:baku]]

We should simply disable the test. We haven't implemented the lazy-loading iframe feature-policy. What's the point of having this test running?

Comment 18

[Intermittent Failures Robot]

39 failures in 5015 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #25 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 38
• try: 1

Platform breakdown:

• linux1804-64-qr: 39

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

Comment 19

[Intermittent Failures Robot]

17 failures in 913 pushes (0.019 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 17

Platform breakdown:

• linux1804-64-qr: 17

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-04-08&endday=2020-04-08&tree=all

Comment 20

[Cristina Coroiu [:ccoroiu]]

Attachment: Bug 1499123 - disable lazyload-disabled-tentative.sub.html on linux-qr fission debug r=baku

Comment 21

[Intermittent Failures Robot]

28 failures in 1016 pushes (0.028 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 28

Platform breakdown:

• linux1804-64-qr: 28

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-04-09&endday=2020-04-09&tree=all

Comment 22

[Andreea Pavel [:apavel]]

Modified the patch to disable the test based on Andrea's comment and also because in some logs, besides the test pass there is also

TEST-UNEXPECTED-FAIL | /feature-policy/experimental-features/lazyload/lazyload-disabled-tentative.sub.html | When 'lazyload' feature is disabled, a frame cannot avoid lazyloading by setting 'lazyload' attribute to 'off' - assert_equals: lazyload='off' not treated as 'auto'. expected (string) "This page is lazyloaded." but got (boolean) false

Comment 23

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/98a5d0b84d04
disable lazyload-disabled-tentative.sub.html on linux-qr fission debug r=jmaher

Comment 24

[Intermittent Failures Robot]

18 failures in 571 pushes (0.032 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 15
• mozilla-central: 3

Platform breakdown:

• linux1804-64-qr: 18

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-04-10&endday=2020-04-10&tree=all

Comment 25

[Cristian Brindusan [:cbrindusan]]

https://hg.mozilla.org/mozilla-central/rev/98a5d0b84d04

Comment 26

[Intermittent Failures Robot]

72 failures in 4823 pushes (0.015 failures/push) were associated with this bug in the last 7 days.

This is the #14 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 69
• mozilla-central: 3

Platform breakdown:

• linux1804-64-qr: 72

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-04-06&endday=2020-04-12&tree=all

Comment 27

[Chris Peterson [:cpeterson]]

This test is expected to intermittently pass with Fission, but always fail without Fission:

https://searchfox.org/mozilla-central/rev/567b68b8ff4b6d607ba34a6f1926873d21a7b4d7/testing/web-platform/meta/feature-policy/experimental-features/lazyload/lazyload-disabled-tentative.sub.html.ini#10

Nika says this is 100% a Fission bug, though the test might also be racy. Fission is probably not blocking a load we're supposed to, which causes the test to mistakenly think the lazyload was lazy.

P3 M6
Block WPT-Fis meta bug
Clearing assignee because a Fission engineer will need to fix this bug.

Comment 28

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1499123&startday=2020-04-13&endday=2020-04-19&tree=all

Comment 29

[Chris Peterson [:cpeterson]]

Tracking WPT Fission bugs for Fission M6b (Q2)

Comment 30

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• linux1804-64-qr: 2

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

Comment 31

[Chris Peterson [:cpeterson]]

This test is still intermittently passes unexpectedly with Fission:

https://searchfox.org/mozilla-central/rev/35b97af64a55d1d30caa4d6e9fabc1a7fbabc509/testing/web-platform/meta/feature-policy/experimental-features/lazyload/lazyload-disabled-tentative.sub.html.ini#5-7

    expected:
      if fission: ["FAIL", "PASS"]
      FAIL

Comment 32

[u608768]

Feature was removed in bug 1638117 (web-platform-tests/wpt#23613) and annotation was removed in bug 1644697 (989aaabe4f66).