Closed Bug 1499123 Opened 6 years ago Closed 4 years ago

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.

Categories

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

defect

Tracking

()

RESOLVED FIXED
Fission Milestone M6b

People

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

References

(Blocks 1 open bug)

Details

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

Attachments

(1 file)

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
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
Component: DOM → DOM: Core & HTML
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Priority: -- → P3

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?

Flags: needinfo?(htsai)

Maybe Christoph or Hiro have ideas for this.

Flags: needinfo?(htsai)
Flags: needinfo?(hikezoe.birchill)
Flags: needinfo?(ckerschb)

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).

Flags: needinfo?(hikezoe.birchill)

(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?

Flags: needinfo?(ckerschb) → needinfo?(amarchesini)

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?

Flags: needinfo?(amarchesini)
Assignee: nobody → ccoroiu
Attachment #9139665 - Attachment description: Bug 1499123 - update expectation for lazyload-disabled-tentative.sub.html on linux debug r=baku → Bug 1499123 - disable lazyload-disabled-tentative.sub.html on linux-qr fission debug r=baku

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

Whiteboard: [stockwell needswork:owner] → [stockwell disabled(fission)]
Pushed by apavel@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/98a5d0b84d04
disable lazyload-disabled-tentative.sub.html on linux-qr fission debug r=jmaher
Keywords: leave-open
Whiteboard: [stockwell disabled(fission)] → [stockwell disabled(fission)]
Whiteboard: [stockwell needswork:owner] → [stockwell disabled(fission)]
Whiteboard: [stockwell needswork:owner] → [stockwell disabled(fission)]

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.

Assignee: ccoroiu → nobody
Blocks: WPT-Fis
Fission Milestone: --- → M6
Summary: 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. → 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.

Tracking WPT Fission bugs for Fission M6b (Q2)

Fission Milestone: M6 → M6b
Assignee: nobody → kmadan
Status: REOPENED → ASSIGNED

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

Assignee: kmadan → nobody
Status: ASSIGNED → RESOLVED
Closed: 5 years ago4 years ago
Resolution: --- → FIXED
Assignee: nobody → ccoroiu
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: