Closed Bug 1672050 Opened 4 years ago Closed 3 years ago

Intermittent dom/base/test/test_bug1639328.html | display-none-https[display-none-http] - got true, expected false

Categories

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

defect
Not set
normal

Tracking

()

RESOLVED FIXED
88 Branch
Tracking Status
firefox88 --- fixed

People

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

References

Details

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

Attachments

(1 file)

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


[task 2020-10-19T20:06:26.344Z] 20:06:26     INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-http 
[task 2020-10-19T20:06:26.345Z] 20:06:26     INFO - Switching display of display-none-http
[task 2020-10-19T20:06:26.346Z] 20:06:26     INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2020-10-19T20:06:26.349Z] 20:06:26     INFO - got {"throttledFrameRequests":true}
[task 2020-10-19T20:06:26.350Z] 20:06:26     INFO - Buffered messages finished
[task 2020-10-19T20:06:26.350Z] 20:06:26     INFO - TEST-UNEXPECTED-FAIL | dom/base/test/test_bug1639328.html | display-none-http - got true, expected false
[task 2020-10-19T20:06:26.352Z] 20:06:26     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2020-10-19T20:06:26.352Z] 20:06:26     INFO -     checkFrame@dom/base/test/test_bug1639328.html:42:5
[task 2020-10-19T20:06:26.353Z] 20:06:26     INFO - And switching display back for display-none-http
[task 2020-10-19T20:06:26.353Z] 20:06:26     INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2020-10-19T20:06:26.354Z] 20:06:26     INFO - got {"throttledFrameRequests":true}```
Summary: Intermittent dom/base/test/test_bug1639328.html | display-none-http - got true, expected false → Intermittent dom/base/test/test_bug1639328.html | display-none-https display-none-http - got true, expected false
Summary: Intermittent dom/base/test/test_bug1639328.html | display-none-https display-none-http - got true, expected false → Intermittent dom/base/test/test_bug1639328.html | display-none-https[display-none-http] - got true, expected false

There are 33 total failures in the last 7 days on linux1804-64-qr debug
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=332259209&repo=mozilla-central&lineNumber=3220

[task 2021-03-06T10:12:27.691Z] 10:12:27 INFO - TEST-START | dom/base/test/test_bug1639328.html
[task 2021-03-06T10:12:29.050Z] 10:12:29 INFO - GECKO(1533) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpJsdfH_.mozrunner/runtests_leaks_tab_pid3101.log
[task 2021-03-06T10:12:29.050Z] 10:12:29 INFO - GECKO(1533) | [3101, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-03-06T10:12:29.173Z] 10:12:29 INFO - GECKO(1533) | [Child 3101, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-03-06T10:12:29.438Z] 10:12:29 INFO - TEST-INFO | started process screentopng
[task 2021-03-06T10:12:29.879Z] 10:12:29 INFO - TEST-INFO | screentopng: exit 0
[task 2021-03-06T10:12:29.880Z] 10:12:29 INFO - Buffered messages logged at 10:12:28
[task 2021-03-06T10:12:29.881Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | Should not be throttling main page
[task 2021-03-06T10:12:29.881Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.882Z] 10:12:29 INFO - got {"throttledFrameRequests":false}
[task 2021-03-06T10:12:29.882Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | http
[task 2021-03-06T10:12:29.883Z] 10:12:29 INFO - Switching display of http
[task 2021-03-06T10:12:29.884Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.884Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.885Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | http
[task 2021-03-06T10:12:29.885Z] 10:12:29 INFO - And switching display back for http
[task 2021-03-06T10:12:29.886Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.886Z] 10:12:29 INFO - got {"throttledFrameRequests":false}
[task 2021-03-06T10:12:29.887Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | http
[task 2021-03-06T10:12:29.887Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.888Z] 10:12:29 INFO - got {"throttledFrameRequests":false}
[task 2021-03-06T10:12:29.892Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | https
[task 2021-03-06T10:12:29.892Z] 10:12:29 INFO - Switching display of https
[task 2021-03-06T10:12:29.896Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.898Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.898Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | https
[task 2021-03-06T10:12:29.898Z] 10:12:29 INFO - And switching display back for https
[task 2021-03-06T10:12:29.899Z] 10:12:29 INFO - Buffered messages logged at 10:12:29
[task 2021-03-06T10:12:29.899Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.899Z] 10:12:29 INFO - got {"throttledFrameRequests":false}
[task 2021-03-06T10:12:29.901Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | https
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - querying http://mochi.test:8888/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - got {"throttledFrameRequests":false}
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | same-origin
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - Switching display of same-origin
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - querying http://mochi.test:8888/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | same-origin
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - And switching display back for same-origin
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - querying http://mochi.test:8888/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.904Z] 10:12:29 INFO - got {"throttledFrameRequests":false}
[task 2021-03-06T10:12:29.905Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | same-origin
[task 2021-03-06T10:12:29.905Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.909Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.909Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-http
[task 2021-03-06T10:12:29.909Z] 10:12:29 INFO - Switching display of display-none-http
[task 2021-03-06T10:12:29.909Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.912Z] 10:12:29 INFO - got {"throttledFrameRequests":false}
[task 2021-03-06T10:12:29.915Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-http
[task 2021-03-06T10:12:29.915Z] 10:12:29 INFO - And switching display back for display-none-http
[task 2021-03-06T10:12:29.918Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.919Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.919Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-http
[task 2021-03-06T10:12:29.920Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.920Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.921Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-https
[task 2021-03-06T10:12:29.921Z] 10:12:29 INFO - Switching display of display-none-https
[task 2021-03-06T10:12:29.922Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.922Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.925Z] 10:12:29 INFO - Buffered messages finished
[task 2021-03-06T10:12:29.926Z] 10:12:29 INFO - TEST-UNEXPECTED-FAIL | dom/base/test/test_bug1639328.html | display-none-https - got true, expected false
[task 2021-03-06T10:12:29.929Z] 10:12:29 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2021-03-06T10:12:29.929Z] 10:12:29 INFO - checkFrame@dom/base/test/test_bug1639328.html:42:5
[task 2021-03-06T10:12:29.930Z] 10:12:29 INFO - And switching display back for display-none-https
[task 2021-03-06T10:12:29.930Z] 10:12:29 INFO - querying https://example.com/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.931Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.931Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-https
[task 2021-03-06T10:12:29.932Z] 10:12:29 INFO - querying http://mochi.test:8888/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.932Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.933Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-same-origin
[task 2021-03-06T10:12:29.934Z] 10:12:29 INFO - Switching display of display-none-same-origin
[task 2021-03-06T10:12:29.935Z] 10:12:29 INFO - querying http://mochi.test:8888/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.935Z] 10:12:29 INFO - got {"throttledFrameRequests":false}
[task 2021-03-06T10:12:29.936Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-same-origin
[task 2021-03-06T10:12:29.936Z] 10:12:29 INFO - And switching display back for display-none-same-origin
[task 2021-03-06T10:12:29.937Z] 10:12:29 INFO - querying http://mochi.test:8888/tests/dom/base/test/file_bug1639328.html
[task 2021-03-06T10:12:29.937Z] 10:12:29 INFO - got {"throttledFrameRequests":true}
[task 2021-03-06T10:12:29.938Z] 10:12:29 INFO - TEST-PASS | dom/base/test/test_bug1639328.html | display-none-same-origin
[task 2021-03-06T10:12:29.938Z] 10:12:29 INFO - GECKO(1533) | MEMORY STAT | vsize 2783MB | residentFast 230MB | heapAllocated 24MB
[task 2021-03-06T10:12:29.939Z] 10:12:29 INFO - TEST-OK | dom/base/test/test_bug1639328.html | took 2161ms

Jens please take a look.

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

Hi Emilio, any idea what is happening here?

Flags: needinfo?(jstutte) → needinfo?(emilio)
Flags: needinfo?(emilio)

2 request animation ticks should be enough to guarantee that a paint has
happened, and thus that the frame has received the new visibility
updates and such.

That being said, I guess that since on fission the IPC communication
goes through the parent, it might not be guaranteed after all. This is
mostly a tentative patch, if waiting longer doesn't fix it and the
intermittents become timeouts, that means that there's something else to
fix in Gecko.

Assignee: nobody → emilio
Status: NEW → ASSIGNED
Pushed by ealvarez@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/faf68902d4cd
Try to make test_bug1639328.html more reliable. r=smaug
Status: ASSIGNED → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 88 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: