Closed Bug 1640080 Opened 4 years ago Closed 4 years ago

Intermittent browser/modules/test/browser/browser_Telemetry_numberOfSiteOriginsPerDocument.js | Test timed out -

Categories

(Toolkit :: Telemetry, defect, P5)

defect

Tracking

()

RESOLVED FIXED
mozilla79
Tracking Status
firefox-esr68 --- unaffected
firefox76 --- unaffected
firefox77 --- unaffected
firefox78 --- fixed
firefox79 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [retriggered][stockwell needswork:owner])

Attachments

(1 file)

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


[task 2020-05-22T03:00:39.370Z] 03:00:39     INFO - TEST-START | browser/modules/test/browser/browser_Telemetry_numberOfSiteOriginsPerDocument.js
[task 2020-05-22T03:00:39.390Z] 03:00:39     INFO - GECKO(8019) | Waiting for browser load
[task 2020-05-22T03:00:39.411Z] 03:00:39     INFO - GECKO(8019) | Saw state f0001 and status 0
[task 2020-05-22T03:00:39.492Z] 03:00:39     INFO - GECKO(8019) | Saw state c0010 and status 0
[task 2020-05-22T03:00:39.493Z] 03:00:39     INFO - GECKO(8019) | Browser loaded http://mochi.test:8888/browser/browser/modules/test/browser/contain_iframe.html
[task 2020-05-22T03:00:39.717Z] 03:00:39     INFO - GECKO(8019) | Waiting for browser load
[task 2020-05-22T03:00:39.733Z] 03:00:39     INFO - GECKO(8019) | Saw state c0010 and status 0
[task 2020-05-22T03:00:39.733Z] 03:00:39     INFO - GECKO(8019) | Browser loaded about:blank
[task 2020-05-22T03:01:24.383Z] 03:01:24     INFO - TEST-INFO | started process screentopng
[task 2020-05-22T03:01:24.653Z] 03:01:24     INFO - TEST-INFO | screentopng: exit 0
[task 2020-05-22T03:01:24.654Z] 03:01:24     INFO - Buffered messages logged at 03:00:39
[task 2020-05-22T03:01:24.654Z] 03:01:24     INFO - Entering test bound test_numberOfSiteOriginsAfterTabClose
[task 2020-05-22T03:01:24.654Z] 03:01:24     INFO - TEST-PASS | browser/modules/test/browser/browser_Telemetry_numberOfSiteOriginsPerDocument.js | unexpected counts should be zero for FX_NUMBER_OF_UNIQUE_SITE_ORIGINS_PER_DOCUMENT at index 1 - 0 == 0 - 
[task 2020-05-22T03:01:24.654Z] 03:01:24     INFO - TEST-PASS | browser/modules/test/browser/browser_Telemetry_numberOfSiteOriginsPerDocument.js | expected counts should match for FX_NUMBER_OF_UNIQUE_SITE_ORIGINS_PER_DOCUMENT at index 2 - 1 == 1 - 
[task 2020-05-22T03:01:24.655Z] 03:01:24     INFO - TEST-PASS | browser/modules/test/browser/browser_Telemetry_numberOfSiteOriginsPerDocument.js | unexpected counts should be zero for FX_NUMBER_OF_UNIQUE_SITE_ORIGINS_PER_DOCUMENT at index 3 - 0 == 0 - 
[task 2020-05-22T03:01:24.655Z] 03:01:24     INFO - TEST-PASS | browser/modules/test/browser/browser_Telemetry_numberOfSiteOriginsPerDocument.js | Should have found an entry for FX_NUMBER_OF_UNIQUE_SITE_ORIGINS_PER_DOCUMENT at index 2 - true == true - 
[task 2020-05-22T03:01:24.656Z] 03:01:24     INFO - Leaving test bound test_numberOfSiteOriginsAfterTabClose
[task 2020-05-22T03:01:24.657Z] 03:01:24     INFO - Entering test bound test_numberOfSiteOriginsAboutBlank
[task 2020-05-22T03:01:24.657Z] 03:01:24     INFO - Buffered messages finished
[task 2020-05-22T03:01:24.657Z] 03:01:24     INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_Telemetry_numberOfSiteOriginsPerDocument.js | Test timed out - 
[task 2020-05-22T03:01:24.658Z] 03:01:24     INFO - GECKO(8019) | MEMORY STAT | vsize 3779MB | residentFast 337MB | heapAllocated 102MB
[task 2020-05-22T03:01:24.658Z] 03:01:24     INFO - TEST-OK | browser/modules/test/browser/browser_Telemetry_numberOfSiteOriginsPerDocument.js | took 45028ms

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

Barret,
This failure looks to be of high frequency, could you please take a look?

Flags: needinfo?(brennie)
Whiteboard: [retriggered]

It turns out that we sometimes do not get a OnLocationChange event from the
BrowserChild when navigating to an about:blank document from an initial
about:blank. We then would not have the innerWindowID set on the
<xul:browser>. Now we retrieve the innerWindowId from the <browser>'s
current window global.

Additionally, we were running into some timeouts due to intermittently not
receiving a OnStateChange event for STATE_STOP. Because we waiting for this
event, the test would time out sometimes.

Assignee: nobody → brennie
Status: NEW → ASSIGNED

I just triggered a landing for the patch in comment 8. I have a try run that suggests that the intermittent is fixed.

Flags: needinfo?(brennie)
Pushed by brennie@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/2ca480e00722
Correctly retrieve inner window ID in numberOfSiteOriginsPerDocument telemetry tests r=nika
Status: ASSIGNED → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla79
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: