1623098 - Perma TEST-UNEXPECTED-ERROR | js/xpconnect/tests/marionette/test_preloader_telemetry.py TestScriptPreloader.test_preloader_requests_histogram | JavascriptException: TypeError: can't access property "v when version number gets increased to 77 on 2020-04-06

Status: VERIFIED FIXED

(Core :: XPConnect, defect, P1)

Description

[Cristian Brindusan [:cbrindusan]]

Central as Version Increase simulation:
https://treeherder.mozilla.org/#/jobs?repo=try&selectedJob=293513319&resultStatus=testfailed%2Cbusted%2Cexception&revision=b6c5aecb707dafe97258c42aff0dc001b6eeee4a

Failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=293513319&repo=try&lineNumber=49740

[task 2020-03-17T15:20:50.304Z] 15:20:50 INFO - TEST-START | js/xpconnect/tests/marionette/test_preloader_telemetry.py TestScriptPreloader.test_preloader_requests_histogram
[task 2020-03-17T15:20:50.309Z] 15:20:50 INFO - 1584458450304 Marionette DEBUG Accepted connection 4 from 127.0.0.1:53908
[task 2020-03-17T15:20:50.309Z] 15:20:50 INFO - 1584458450305 Marionette DEBUG 4 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-03-17T15:20:50.309Z] 15:20:50 INFO - 1584458450307 Marionette TRACE [20] Frame script loaded
[task 2020-03-17T15:20:50.309Z] 15:20:50 INFO - 1584458450308 Marionette TRACE [20] Frame script registered
[task 2020-03-17T15:20:50.314Z] 15:20:50 INFO - 1584458450309 Marionette DEBUG 4 <- [1,1,null,{"sessionId":"bc2c47ae-e174-4368-98f8-4ccefd980d74","capabilities":{"browserName":"firefox","browserVersion":"77.0a ... p\tmpurwsdh.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-03-17T15:20:50.314Z] 15:20:50 INFO - 1584458450311 Marionette DEBUG 4 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-03-17T15:20:50.314Z] 15:20:50 INFO - 1584458450312 Marionette DEBUG 4 <- [1,2,null,{"value":null}]
[task 2020-03-17T15:20:50.316Z] 15:20:50 INFO - 1584458450313 Marionette DEBUG 4 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-03-17T15:20:50.316Z] 15:20:50 INFO - 1584458450314 Marionette DEBUG 4 <- [1,3,null,{"value":null}]
[task 2020-03-17T15:20:50.317Z] 15:20:50 INFO - 1584458450315 Marionette DEBUG 4 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-03-17T15:20:50.317Z] 15:20:50 INFO - 1584458450315 Marionette DEBUG 4 <- [1,4,null,{"value":null}]
[task 2020-03-17T15:20:50.317Z] 15:20:50 INFO - 1584458450316 Marionette DEBUG 4 -> [0,5,"Marionette:GetContext",{}]
[task 2020-03-17T15:20:50.319Z] 15:20:50 INFO - 1584458450317 Marionette DEBUG 4 <- [1,5,null,{"value":"content"}]
[task 2020-03-17T15:20:50.319Z] 15:20:50 INFO - 1584458450318 Marionette DEBUG 4 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-03-17T15:20:50.319Z] 15:20:50 INFO - 1584458450318 Marionette DEBUG 4 <- [1,6,null,{"value":null}]
[task 2020-03-17T15:20:50.321Z] 15:20:50 INFO - 1584458450320 Marionette DEBUG 4 -> [0,7,"WebDriver:ExecuteScript",{"script":"Services.obs.notifyObservers(null, 'startupcache-invalidate');","newSandbox":true," ... ild\tests\marionette\tests\js\xpconnect\tests\marionette\test_preloader_telemetry.py","sandbox":"default","line":80}]
[task 2020-03-17T15:20:50.323Z] 15:20:50 INFO - 1584458450322 Marionette DEBUG 4 <- [1,7,null,{"value":null}]
[task 2020-03-17T15:20:50.325Z] 15:20:50 INFO - 1584458450323 Marionette DEBUG 4 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2020-03-17T15:20:50.325Z] 15:20:50 INFO - 1584458450324 Marionette DEBUG 4 <- [1,8,null,{"value":null}]
[task 2020-03-17T15:20:50.327Z] 15:20:50 INFO - 1584458450325 Marionette DEBUG 4 -> [0,9,"Marionette:GetContext",{}]
[task 2020-03-17T15:20:50.327Z] 15:20:50 INFO - 1584458450325 Marionette DEBUG 4 <- [1,9,null,{"value":"content"}]
[task 2020-03-17T15:20:50.327Z] 15:20:50 INFO - 1584458450326 Marionette DEBUG 4 -> [0,10,"Marionette:AcceptConnections",{"value":false}]
[task 2020-03-17T15:20:50.327Z] 15:20:50 INFO - 1584458450326 Marionette INFO Stopped listening on port 2828
[task 2020-03-17T15:20:50.329Z] 15:20:50 INFO - 1584458450327 Marionette DEBUG 4 <- [1,10,null,{"value":null}]
[task 2020-03-17T15:20:50.329Z] 15:20:50 INFO - 1584458450328 Marionette DEBUG 4 -> [0,11,"Marionette:GetContext",{}]
[task 2020-03-17T15:20:50.329Z] 15:20:50 INFO - 1584458450328 Marionette DEBUG 4 <- [1,11,null,{"value":"content"}]
[task 2020-03-17T15:20:50.331Z] 15:20:50 INFO - 1584458450329 Marionette DEBUG 4 -> [0,12,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-03-17T15:20:50.331Z] 15:20:50 INFO - 1584458450329 Marionette DEBUG 4 <- [1,12,null,{"value":null}]
[task 2020-03-17T15:20:50.333Z] 15:20:50 INFO - 1584458450331 Marionette DEBUG 4 -> [0,13,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n ... me":"Z:\task_1584454648\build\venv\lib\site-packages\marionette_driver\marionette.py","sandbox":"default","line":900}]
[task 2020-03-17T15:20:50.335Z] 15:20:50 INFO - 1584458450334 Marionette DEBUG 4 <- [1,13,null,{"value":false}]
[task 2020-03-17T15:20:50.337Z] 15:20:50 INFO - 1584458450335 Marionette DEBUG 4 -> [0,14,"Marionette:SetContext",{"value":"content"}]
[task 2020-03-17T15:20:50.337Z] 15:20:50 INFO - 1584458450335 Marionette DEBUG 4 <- [1,14,null,{"value":null}]
[task 2020-03-17T15:20:50.337Z] 15:20:50 INFO - 1584458450336 Marionette DEBUG 4 -> [0,15,"Marionette:Quit",{"flags":["eForceQuit","eRestart"]}]
[task 2020-03-17T15:20:50.388Z] 15:20:50 INFO - 1584458450383 Marionette TRACE Received observer notification quit-application
[task 2020-03-17T15:20:50.393Z] 15:20:50 INFO - 1584458450389 Marionette DEBUG 4 <- [1,15,null,{"cause":"restart"}]
[task 2020-03-17T15:20:50.455Z] 15:20:50 INFO - 1584458450453 Marionette DEBUG Closed connection 4
[task 2020-03-17T15:20:50.607Z] 15:20:50 INFO - JavaScript error: resource:///modules/Discovery.jsm, line 104: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsICookieManager.remove]
[task 2020-03-17T15:20:50.729Z] 15:20:50 INFO - 1584458450716 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2020-03-17T15:20:50.729Z] 15:20:50 INFO - 1584458450716 Marionette DEBUG Marionette stopped listening
[task 2020-03-17T15:20:52.935Z] 15:20:52 ERROR - TEST-UNEXPECTED-ERROR | js/xpconnect/tests/marionette/test_preloader_telemetry.py TestScriptPreloader.test_preloader_requests_histogram | JavascriptException: TypeError: can't access property "values", snapshot.parent.SCRIPT_PRELOADER_REQUESTS is undefined

Comment 1

[Chris Peterson [:cpeterson]]

P1 for perma test error that will block Nightly moving to version 77.

Comment 2

[Alex Thayer [:alexical] (she/her)]

Attachment: Data Review Request

Comment 3

[Alex Thayer [:alexical] (she/her)]

Attachment: Bug 1623098 - Bump startup cache telemetry to 81 r?mconley!,data-review=mmccorquodale

Comment 4

[Megan McCorquodale]

Comment on attachment 9136369 [details]
Data Review Request

1. Is the provided Data Collection Review complete, correct, and data-review+ by a Data Steward?
Yes.

2.  Is the data collection covered by the existing Firefox Privacy Notice?
Yes.
----
data-review +

Comment 5

[Alexandru Michis [:malexandru]]

Patch works in today's central as version increase simulation:
https://treeherder.mozilla.org/#/jobs?repo=try&selectedJob=295587328&revision=977e1b0ec819e44708c47989529e8fd44e846771&searchStr=%28mn

Comment 6

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/bc512313dd43
Bump startup cache telemetry to 81 r=mconley,data-review=mmccorquodale

Comment 7

[Arthur Iakab [arthur_iakab]]

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

Comment 9

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

Verified fixed with yesterday's version increase simulation: https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel%2Crunnable&revision=e7dd8a50a2aaa3827f8294598c9935ed1f24c1d4