1769374 - Perma TEST-UNEXPECTED-FAIL | js/xpconnect/tests/marionette/test_preloader_telemetry.py TestScriptPreloader.test_preloader_requests_histogram | AssertionError: 144 not greater than 154.5 when Gecko 102 merges to Beta on 2022-05-30

Status: RESOLVED DUPLICATE of bug 1768871

(Core :: XPConnect, defect)

Description

[Sandor Molnar[:smolnar]]

[Tracking Requested - why for this release]:

Central-as-beta simulation
How to run these simulations
Failure log

INFO -  DEBUG: Adding blocker DownloadAutoSaveView: writing data for phase profile-before-change
[task 2022-05-14T11:15:42.648Z] 11:15:42     INFO -  1652526942648	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2022-05-14T11:15:42.649Z] 11:15:42     INFO -  DEBUG: Adding blocker ScriptPreloader: Saving bytecode cache for phase xpcom-will-shutdown
[task 2022-05-14T11:15:42.650Z] 11:15:42     INFO -  1652526942649	RemoteAgent	TRACE	[31] Document already finished loading: about:blank
[task 2022-05-14T11:15:42.652Z] 11:15:42     INFO -  1652526942651	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"88f4aa0f-3bc1-4b30-ad39-1075e712e297","capabilities":{"browserName":"firefox","browserVersion":"102.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-05-14T11:15:42.654Z] 11:15:42     INFO -  1652526942653	Marionette	DEBUG	1 -> [0,2,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-14T11:15:42.654Z] 11:15:42     INFO -  1652526942654	Marionette	DEBUG	1 <- [1,2,null,{"value":null}]
[task 2022-05-14T11:15:42.657Z] 11:15:42     INFO -  Child process with id "9938" has been marked as detached because it is no longer in the managed process group. Keeping reference to the process id "9956" which is the new child process.
[task 2022-05-14T11:15:42.657Z] 11:15:42     INFO -  1652526942656	Marionette	DEBUG	1 -> [0,3,"Marionette:GetContext",{}]
[task 2022-05-14T11:15:42.657Z] 11:15:42     INFO -  1652526942657	Marionette	DEBUG	1 <- [1,3,null,{"value":"content"}]
[task 2022-05-14T11:15:42.659Z] 11:15:42     INFO -  1652526942658	Marionette	DEBUG	1 -> [0,4,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-05-14T11:15:42.659Z] 11:15:42     INFO -  1652526942658	Marionette	DEBUG	1 <- [1,4,null,{"value":null}]
[task 2022-05-14T11:15:42.661Z] 11:15:42     INFO -  1652526942660	Marionette	DEBUG	1 -> [0,5,"WebDriver:ExecuteScript",{"script":"const name = arguments[0];\n                const snapshot = Services.telemetry.get ... newSandbox":true,"sandbox":"default","line":90,"filename":"tests/js/xpconnect/tests/marionette/test_preloader_telemetry.py"}]
[task 2022-05-14T11:15:42.662Z] 11:15:42     INFO -  1652526942662	Marionette	TRACE	[29] MarionetteCommands actor created for window id 4
[task 2022-05-14T11:15:42.665Z] 11:15:42     INFO -  1652526942664	Marionette	DEBUG	1 <- [1,5,null,{"value":{"0":309,"1":12,"2":144,"3":0}}]
[task 2022-05-14T11:15:42.667Z] 11:15:42     INFO -  1652526942666	Marionette	DEBUG	1 -> [0,6,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-14T11:15:42.667Z] 11:15:42     INFO -  1652526942666	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2022-05-14T11:15:42.669Z] 11:15:42     INFO -  1652526942668	Marionette	DEBUG	1 -> [0,7,"Marionette:GetContext",{}]
[task 2022-05-14T11:15:42.669Z] 11:15:42     INFO -  1652526942668	Marionette	DEBUG	1 <- [1,7,null,{"value":"content"}]
[task 2022-05-14T11:15:42.670Z] 11:15:42     INFO -  1652526942669	Marionette	DEBUG	1 -> [0,8,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-05-14T11:15:42.670Z] 11:15:42     INFO -  1652526942669	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2022-05-14T11:15:42.670Z] 11:15:42     INFO -  1652526942670	Marionette	DEBUG	1 -> [0,9,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2022-05-14T11:15:42.717Z] 11:15:42     INFO -  1652526942717	Marionette	DEBUG	1 <- [1,9,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAPNCAYAAADWQsenAAAgAElEQVR4XuzdBbylRf0H4NmghKW7a0kJaRBEEARR2iAMuixCDFCQVlC6U ... QIECBAgAABAgQIECBAgMCVgAB49S7HEiBAgAABAgQIECBAgAABAgQIEGgCAmDzsiZAgAABAgQIECBAgAABAgQIECBwJTAh8eX/DlvkDwAAAABJRU5ErkJggg=="}]
[task 2022-05-14T11:15:42.719Z] 11:15:42     INFO -  1652526942719	Marionette	DEBUG	1 -> [0,10,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-14T11:15:42.719Z] 11:15:42     INFO -  1652526942719	Marionette	DEBUG	1 <- [1,10,null,{"value":null}]
[task 2022-05-14T11:15:42.721Z] 11:15:42     INFO -  1652526942720	Marionette	DEBUG	1 -> [0,11,"Marionette:GetContext",{}]
[task 2022-05-14T11:15:42.721Z] 11:15:42     INFO -  1652526942720	Marionette	DEBUG	1 <- [1,11,null,{"value":"content"}]
[task 2022-05-14T11:15:42.721Z] 11:15:42     INFO -  1652526942721	Marionette	DEBUG	1 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-14T11:15:42.721Z] 11:15:42     INFO -  1652526942721	Marionette	DEBUG	1 <- [1,12,null,{"value":null}]
[task 2022-05-14T11:15:42.722Z] 11:15:42     INFO -  1652526942722	Marionette	DEBUG	1 -> [0,13,"WebDriver:GetPageSource",{}]
[task 2022-05-14T11:15:42.725Z] 11:15:42     INFO -  1652526942724	Marionette	TRACE	[31] MarionetteCommands actor created for window id 4294967297
[task 2022-05-14T11:15:42.726Z] 11:15:42     INFO -  1652526942725	Marionette	DEBUG	1 <- [1,13,null,{"value":"<html><head></head><body></body></html>"}]
[task 2022-05-14T11:15:42.726Z] 11:15:42     INFO -  1652526942726	Marionette	DEBUG	1 -> [0,14,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-14T11:15:42.727Z] 11:15:42     INFO -  1652526942726	Marionette	DEBUG	1 <- [1,14,null,{"value":null}]
[task 2022-05-14T11:15:42.775Z] 11:15:42     INFO - TEST-UNEXPECTED-FAIL | js/xpconnect/tests/marionette/test_preloader_telemetry.py TestScriptPreloader.test_preloader_requests_histogram | AssertionError: 144 not greater than 154.5
[task 2022-05-14T11:15:42.775Z] 11:15:42     INFO - Traceback (most recent call last):
[task 2022-05-14T11:15:42.775Z] 11:15:42     INFO -   File "/opt/worker/tasks/task_165251213843214/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 202, in run
[task 2022-05-14T11:15:42.775Z] 11:15:42     INFO -     testMethod()