1667286 - Intermittent [fission] browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 2 - JS frame :: /browser/browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js :: check_events ::line 130

Reporter

Description

•

4 years ago

treeherder

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

[task 2020-09-25T00:17:14.882Z] 00:17:14     INFO - TEST-START | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js
[task 2020-09-25T00:17:15.504Z] 00:17:15     INFO - GECKO(5816) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 113: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2020-09-25T00:17:15.613Z] 00:17:15     INFO - GECKO(5816) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 83: Error: TelemetryStopwatch: finishing nonexisting stopwatch. Histogram: "FULLSCREEN_CHANGE_MS", key: ""
[task 2020-09-25T00:17:15.716Z] 00:17:15     INFO - TEST-INFO | started process screenshot
[task 2020-09-25T00:17:15.840Z] 00:17:15     INFO - TEST-INFO | screenshot: exit 0
[task 2020-09-25T00:17:15.840Z] 00:17:15     INFO - Buffered messages logged at 00:17:14
[task 2020-09-25T00:17:15.840Z] 00:17:15     INFO - Entering test bound test_fullscreen_api_cross_origin_tree
[task 2020-09-25T00:17:15.841Z] 00:17:15     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/browser/base/content/test/fullscreen/fullscreen.html" line: 0}]
[task 2020-09-25T00:17:15.841Z] 00:17:15     INFO - Buffered messages logged at 00:17:15
[task 2020-09-25T00:17:15.841Z] 00:17:15     INFO - Console message: [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "http://example.com/browser/browser/base/content/test/fullscreen/fullscreen.html?different-uri=1" line: 0}]
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - Console message: [JavaScript Error: "Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized" {file: "resource:///actors/DOMFullscreenParent.jsm" line: 113}]
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - handleEvent@resource:///actors/DOMFullscreenParent.jsm:113:28
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - receiveMessage@resource:///actors/DOMFullscreenParent.jsm:54:28
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - 
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - Console message: [JavaScript Error: "Error: TelemetryStopwatch: finishing nonexisting stopwatch. Histogram: "FULLSCREEN_CHANGE_MS", key: """ {file: "resource:///actors/DOMFullscreenParent.jsm" line: 83}]
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - receiveMessage@resource:///actors/DOMFullscreenParent.jsm:83:28
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - 
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 1 - 
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 1 - 
[task 2020-09-25T00:17:15.842Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.843Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 0 == 0 - 
[task 2020-09-25T00:17:15.843Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 1 - 
[task 2020-09-25T00:17:15.843Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.843Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 1 - 
[task 2020-09-25T00:17:15.843Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.843Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 0 == 0 - 
[task 2020-09-25T00:17:15.843Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "child_iframe" == "child_iframe" - 
[task 2020-09-25T00:17:15.843Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "child_iframe" == "child_iframe" - 
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "child_iframe" == "child_iframe" - 
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "body" == "body" - 
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - Buffered messages finished
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 2 - JS frame :: chrome://mochitests/content/browser/browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js :: check_events :: line 130
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - Stack trace:
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - chrome://mochitests/content/browser/browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js:check_events:130
[task 2020-09-25T00:17:15.844Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 2 == 2 - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 0 == 0 - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 2 == 2 - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 2 == 2 - 
[task 2020-09-25T00:17:15.845Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 0 == 0 - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.846Z] 00:17:15     INFO - Console message: [JavaScript Warning: "Key event not available on some keyboard layouts: key=“i” modifiers=“accel,alt,shift” id=“key_browserToolbox”" {file: "chrome://mochikit/content/tests/SimpleTest/EventUtils.js" line: 1192}]
[task 2020-09-25T00:17:15.873Z] 00:17:15     INFO - GECKO(5816) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 119: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2020-09-25T00:17:15.892Z] 00:17:15     INFO - GECKO(5816) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 83: Error: TelemetryStopwatch: finishing nonexisting stopwatch. Histogram: "FULLSCREEN_CHANGE_MS", key: ""
[task 2020-09-25T00:17:15.894Z] 00:17:15     INFO - Console message: [JavaScript Error: "Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized" {file: "resource:///actors/DOMFullscreenParent.jsm" line: 119}]
[task 2020-09-25T00:17:15.894Z] 00:17:15     INFO - handleEvent@resource:///actors/DOMFullscreenParent.jsm:119:28
[task 2020-09-25T00:17:15.894Z] 00:17:15     INFO - 
[task 2020-09-25T00:17:15.911Z] 00:17:15     INFO - Console message: [JavaScript Error: "Error: TelemetryStopwatch: finishing nonexisting stopwatch. Histogram: "FULLSCREEN_CHANGE_MS", key: """ {file: "resource:///actors/DOMFullscreenParent.jsm" line: 83}]
[task 2020-09-25T00:17:15.911Z] 00:17:15     INFO - receiveMessage@resource:///actors/DOMFullscreenParent.jsm:83:28
[task 2020-09-25T00:17:15.911Z] 00:17:15     INFO - 
[task 2020-09-25T00:17:15.954Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 2 == 2 - 
[task 2020-09-25T00:17:15.954Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.955Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.955Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 2 == 2 - 
[task 2020-09-25T00:17:15.955Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.955Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.955Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 0 == 0 - 
[task 2020-09-25T00:17:15.957Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 2 == 2 - 
[task 2020-09-25T00:17:15.957Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.957Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.958Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 2 == 2 - 
[task 2020-09-25T00:17:15.960Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.960Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Event type matches - true == true - 
[task 2020-09-25T00:17:15.960Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 0 == 0 - 
[task 2020-09-25T00:17:15.960Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.966Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.966Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.966Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.968Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.968Z] 00:17:15     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | The fullScreenElement matches - "null" == "null" - 
[task 2020-09-25T00:17:15.988Z] 00:17:15     INFO - Leaving test bound test_fullscreen_api_cross_origin_tree
[task 2020-09-25T00:17:16.089Z] 00:17:16     INFO - GECKO(5816) | MEMORY STAT | vsize 2104189MB | vsizeMaxContiguous 66180367MB | residentFast 249MB | heapAllocated 98MB
[task 2020-09-25T00:17:16.089Z] 00:17:16     INFO - TEST-OK | browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | took 1204ms
[task 2020-09-25T00:17:16.099Z] 00:17:16     INFO - checking window state```

BugBot [:suhaib / :marco/ :calixte]

Comment 1

•

4 years ago

Set release status flags based on info from the regressing bug 1665941

status-firefox81: --- → unaffected

status-firefox82: --- → unaffected

status-firefox83: --- → affected

status-firefox-esr78: --- → unaffected

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Updated

•

4 years ago

Flags: needinfo?(smacleod)

Comment hidden (Intermittent Failures Robot)

Steven MacLeod [:smacleod]

Assignee

Comment 3

•

4 years ago

I've started taking a look at fixing this.

Assignee: nobody → smacleod

Status: NEW → ASSIGNED

Flags: needinfo?(smacleod)

Neha Kochar [:neha]

Updated

•

4 years ago

Fission Milestone: --- → M6c

Priority: P5 → P3

Comment hidden (Intermittent Failures Robot)

Pascal Chevrel:pascalc (PTO until April 26)

Updated

•

4 years ago

status-firefox83: affected → fix-optional

Neha Kochar [:neha]

Comment 6

•

4 years ago

This isn't a perma failure as seen in comment 5 so renaming the bug title.

Summary: Perma [tier2][fission] browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 2 - JS frame :: /browser/browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js :: check_events :: line 130 → Intermittent [tier2][fission] browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 2 - JS frame :: /browser/browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js :: check_events ::line 130

Bogdan Tara[:bogdan_tara | bogdant]

Comment 7

•

4 years ago

Seeing this on Tier 1: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=318814274&repo=autoland&lineNumber=2489

Summary: Intermittent [tier2][fission] browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 2 - JS frame :: /browser/browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js :: check_events ::line 130 → Intermittent [fission] browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js | Number of events equal - 1 == 2 - JS frame :: /browser/browser/base/content/test/fullscreen/browser_fullscreen_api_fission.js :: check_events ::line 130

Comment hidden (Intermittent Failures Robot)

Steven MacLeod [:smacleod]

Assignee

Comment 9

•

4 years ago

Attached file Bug 1667286 - wait for requestFullscreen promise in browser_fullscreen_api_fission.js r=mconley! — Details

Before this change we were only waiting for the fullscreenchange
event in the frame that requested fullscreen. This even is fired
before the the promise returned by requestFullscreen is resolved.
We now wait on the returned promise to resolve in addition to the
event, which should delay when we check the logged events from
all the frames.

Pulsebot

Comment 10

•

4 years ago

Pushed by smacleod@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/bc4a177b07e4
wait for requestFullscreen promise in browser_fullscreen_api_fission.js r=mconley

Sandor Molnar[:smolnar]

Comment 11

•

4 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/bc4a177b07e4

Status: ASSIGNED → RESOLVED

Closed: 4 years ago

status-firefox84: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → 84 Branch

Sandor Molnar[:smolnar]

Comment 12

•

4 years ago

Recent failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=319119669&repo=mozilla-central&lineNumber=2494

Status: RESOLVED → REOPENED

status-firefox84: fixed → ---

Flags: needinfo?(smacleod)

Resolution: FIXED → ---

Target Milestone: 84 Branch → ---

Comment hidden (Intermittent Failures Robot)

Steven MacLeod [:smacleod]

Assignee

Comment 14

•

4 years ago

Attached file Bug 1667286 - wait for expected number of events in browser_fullscreen_api_fission.js r=mconley! — Details

Neha Kochar [:neha]

Updated

•

4 years ago

Priority: P3 → P1

Comment hidden (Intermittent Failures Robot)

Pulsebot

Comment 16

•

4 years ago

Pushed by smacleod@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/c4986e7dacec
wait for expected number of events in browser_fullscreen_api_fission.js r=mconley

Steven MacLeod [:smacleod]

Assignee

Comment 17

•

4 years ago

New patch should hopefully fix things.

Flags: needinfo?(smacleod)

Dorel Luca [:dluca]

Comment 18

•

4 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/c4986e7dacec

Status: REOPENED → RESOLVED

Closed: 4 years ago → 4 years ago

status-firefox84: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → 84 Branch

Ryan VanderMeulen [:RyanVM]

Updated

•

4 years ago

status-firefox83: fix-optional → wontfix

Comment hidden (Intermittent Failures Robot)

BMO Automation

Updated

•

4 years ago

Has Regression Range: --- → yes

Bug 1667286 - wait for requestFullscreen promise in browser_fullscreen_api_fission.js r=mconley! 4 years ago Steven MacLeod [:smacleod] 47 bytes, text/x-phabricator-request		Details \| Review
Bug 1667286 - wait for expected number of events in browser_fullscreen_api_fission.js r=mconley! 4 years ago Steven MacLeod [:smacleod] 47 bytes, text/x-phabricator-request		Details \| Review