Closed Bug 1719987 Opened 4 years ago Closed 4 years ago

Perma browser/base/content/test/performance/browser_preferences_usage.js | Should have accessed all known problematic prefs. Remaining: toolkit.telemetry.cachedClientID - Got 1, expected +0 when Gecko 91 merges to Beta on 2021-07-12

Categories

(Firefox :: General, defect)

Product:
Firefox
Component:
General
Version:
Firefox 91
Type:
defect

Tracking

()

Status:
VERIFIED FIXED
Milestone:
91 Branch
Tracking Flags:
Tracking Status
firefox-esr78 --- unaffected
firefox89 --- unaffected
firefox90 --- unaffected
firefox91 + verified

People

(Reporter: malexandru, Assigned: andreio)

Details

Attachments

(1 file)

Bug 1719987 - Add browser_preferences_usage.js pref exception only for Nightly builds
48 bytes, text/x-phabricator-request
Details | Review

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

[task 2021-07-10T15:48:35.201Z] 15:48:35     INFO - TEST-START | browser/base/content/test/performance/browser_preferences_usage.js
[task 2021-07-10T15:48:35.220Z] 15:48:35     INFO - GECKO(2441) | [Child 2602: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7ff120112400 == 1 [pid = 2602] [id = 0]
[task 2021-07-10T15:48:35.221Z] 15:48:35     INFO - GECKO(2441) | [Child 2602: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7ff13aa643c0) [pid = 2602] [serial = 1] [outer = 0]
[task 2021-07-10T15:48:35.225Z] 15:48:35     INFO - GECKO(2441) | [Child 2602: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7ff120115c00) [pid = 2602] [serial = 2] [outer = 7ff13aa643c0]
[task 2021-07-10T15:48:36.974Z] 15:48:36     INFO - GECKO(2441) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpmw5u69pa.mozrunner/runtests_leaks_tab_pid2625.log
[task 2021-07-10T15:48:36.975Z] 15:48:36     INFO - GECKO(2441) | [2625, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:248
[task 2021-07-10T15:48:37.009Z] 15:48:37     INFO - GECKO(2441) | Waiting for browser load
[task 2021-07-10T15:48:37.014Z] 15:48:37     INFO - GECKO(2441) | Waiting for browser state change
[task 2021-07-10T15:48:37.020Z] 15:48:37     INFO - GECKO(2441) | [Child 2625, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-10T15:48:37.213Z] 15:48:37     INFO - GECKO(2441) | [Child 2625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fec0d00a800 == 1 [pid = 2625] [id = 0]
[task 2021-07-10T15:48:37.214Z] 15:48:37     INFO - GECKO(2441) | [Child 2625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fec278643c0) [pid = 2625] [serial = 1] [outer = 0]
[task 2021-07-10T15:48:37.224Z] 15:48:37     INFO - GECKO(2441) | [Child 2625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fec0d00f000) [pid = 2625] [serial = 2] [outer = 7fec278643c0]
[task 2021-07-10T15:48:37.387Z] 15:48:37     INFO - GECKO(2441) | Saw state f0001 and status 0
[task 2021-07-10T15:48:37.477Z] 15:48:37     INFO - GECKO(2441) | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2021-07-10T15:48:37.479Z] 15:48:37     INFO - GECKO(2441) | [Child 2567, Main Thread] WARNING: '!mLocalStore', file /builds/worker/checkouts/gecko/dom/xul/XULPersist.cpp:171
[task 2021-07-10T15:48:37.493Z] 15:48:37     INFO - GECKO(2441) | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2021-07-10T15:48:37.495Z] 15:48:37     INFO - GECKO(2441) | [Child 2567, Main Thread] WARNING: '!mLocalStore', file /builds/worker/checkouts/gecko/dom/xul/XULPersist.cpp:171
[task 2021-07-10T15:48:37.497Z] 15:48:37     INFO - GECKO(2441) | [Child 2625, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-10T15:48:37.504Z] 15:48:37     INFO - GECKO(2441) | [Child 2625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7fec0d016c00) [pid = 2625] [serial = 3] [outer = 7fec278643c0]
[task 2021-07-10T15:48:37.744Z] 15:48:37     INFO - GECKO(2441) | Saw state c0010 and status 0
[task 2021-07-10T15:48:37.746Z] 15:48:37     INFO - GECKO(2441) | Browser got expected state change http://example.com/
[task 2021-07-10T15:48:37.760Z] 15:48:37     INFO - GECKO(2441) | [Child 2496: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fcc40c3f000 == 2 [pid = 2496] [id = 1]
[task 2021-07-10T15:48:37.762Z] 15:48:37     INFO - GECKO(2441) | [Child 2496: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (7fcc5be643c0) [pid = 2496] [serial = 5] [outer = 0]
[task 2021-07-10T15:48:37.763Z] 15:48:37     INFO - GECKO(2441) | [Child 2496: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (7fcc40c40000) [pid = 2496] [serial = 6] [outer = 7fcc5be643c0]
[task 2021-07-10T15:48:37.827Z] 15:48:37     INFO - GECKO(2441) | Waiting for browser load
[task 2021-07-10T15:48:37.828Z] 15:48:37     INFO - GECKO(2441) | Waiting for browser state change
[task 2021-07-10T15:48:37.878Z] 15:48:37     INFO - GECKO(2441) | Saw state f0001 and status 0
[task 2021-07-10T15:48:38.249Z] 15:48:38     INFO - GECKO(2441) | [Child 2496, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-10T15:48:38.255Z] 15:48:38     INFO - GECKO(2441) | [Child 2496: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (7fcc40c42c00) [pid = 2496] [serial = 7] [outer = 7fcc5be643c0]
[task 2021-07-10T15:48:38.367Z] 15:48:38     INFO - GECKO(2441) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpmw5u69pa.mozrunner/runtests_leaks_tab_pid2652.log
[task 2021-07-10T15:48:38.369Z] 15:48:38     INFO - GECKO(2441) | [2652, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:248
[task 2021-07-10T15:48:38.395Z] 15:48:38     INFO - GECKO(2441) | [Child 2652, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-07-10T15:48:38.541Z] 15:48:38     INFO - GECKO(2441) | Saw state c0010 and status 0
[task 2021-07-10T15:48:38.541Z] 15:48:38     INFO - GECKO(2441) | Browser got expected state change http://example.com/
[task 2021-07-10T15:48:38.610Z] 15:48:38     INFO - GECKO(2441) | [Child 2652: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f4bd310c000 == 1 [pid = 2652] [id = 0]
[task 2021-07-10T15:48:38.612Z] 15:48:38     INFO - GECKO(2441) | [Child 2652: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f4bed9643c0) [pid = 2652] [serial = 1] [outer = 0]
[task 2021-07-10T15:48:38.614Z] 15:48:38     INFO - GECKO(2441) | [Child 2652: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f4bd3110000) [pid = 2652] [serial = 2] [outer = 7f4bed9643c0]
[task 2021-07-10T15:48:38.615Z] 15:48:38     INFO - GECKO(2441) | Waiting for browser load
[task 2021-07-10T15:48:38.616Z] 15:48:38     INFO - GECKO(2441) | Waiting for browser state change
[task 2021-07-10T15:48:38.722Z] 15:48:38     INFO - GECKO(2441) | Saw state f0001 and status 0
[task 2021-07-10T15:48:38.859Z] 15:48:38     INFO - GECKO(2441) | [Child 2652, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-07-10T15:48:38.871Z] 15:48:38     INFO - GECKO(2441) | [Child 2652: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f4bd3118400) [pid = 2652] [serial = 3] [outer = 7f4bed9643c0]
[task 2021-07-10T15:48:39.113Z] 15:48:39     INFO - GECKO(2441) | Saw state c0010 and status 0
[task 2021-07-10T15:48:39.115Z] 15:48:39     INFO - GECKO(2441) | Browser got expected state change http://example.com/
[task 2021-07-10T15:48:39.177Z] 15:48:39     INFO - GECKO(2441) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpmw5u69pa.mozrunner/runtests_leaks_tab_pid2676.log
[task 2021-07-10T15:48:39.179Z] 15:48:39     INFO - GECKO(2441) | [2676, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:248
[task 2021-07-10T15:48:39.182Z] 15:48:39     INFO - GECKO(2441) | Waiting for browser load
<...>
[task 2021-07-10T15:49:04.778Z] 15:49:04     INFO - TEST-PASS | browser/base/content/test/performance/browser_preferences_usage.js | layout.throttled_frame_rate should not be accessed more than 40 times. - 1 <= 40 - 
[task 2021-07-10T15:49:04.779Z] 15:49:04     INFO - TEST-PASS | browser/base/content/test/performance/browser_preferences_usage.js | services.settings.security.onecrl.collection should not be accessed more than 40 times. - 1 <= 40 - 
[task 2021-07-10T15:49:04.780Z] 15:49:04     INFO - Buffered messages finished
[task 2021-07-10T15:49:04.789Z] 15:49:04     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_preferences_usage.js | Should have accessed all known problematic prefs. Remaining: toolkit.telemetry.cachedClientID - Got 1, expected +0
[task 2021-07-10T15:49:04.789Z] 15:49:04     INFO - Stack trace:
[task 2021-07-10T15:49:04.790Z] 15:49:04     INFO - chrome://mochikit/content/browser-test.js:test_is:1354
[task 2021-07-10T15:49:04.790Z] 15:49:04     INFO - chrome://mochitests/content/browser/browser/base/content/test/performance/browser_preferences_usage.js:checkPrefGetters:81
[task 2021-07-10T15:49:04.791Z] 15:49:04     INFO - chrome://mochitests/content/browser/browser/base/content/test/performance/browser_preferences_usage.js:navigate_around:290
[task 2021-07-10T15:49:04.791Z] 15:49:04     INFO - Leaving test bound navigate_around
[task 2021-07-10T15:49:04.792Z] 15:49:04     INFO - GECKO(2441) | [Parent 2441, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2904
[task 2021-07-10T15:49:04.801Z] 15:49:04     INFO - GECKO(2441) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-07-10T15:49:04.802Z] 15:49:04     INFO - GECKO(2441) | MEMORY STAT | vsize 2977MB | residentFast 387MB | heapAllocated 146MB
[task 2021-07-10T15:49:04.803Z] 15:49:04     INFO - TEST-OK | browser/base/content/test/performance/browser_preferences_usage.js | took 29082ms
[task 2021-07-10T15:49:04.804Z] 15:49:04     INFO - GECKO(2441) | [Child 2625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fec0bb62000 == 1 [pid = 2625] [id = 2]
[task 2021-07-10T15:49:04.805Z] 15:49:04     INFO - GECKO(2441) | [Child 2625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fec278643c0) [pid = 2625] [serial = 7] [outer = 0]
[task 2021-07-10T15:49:04.806Z] 15:49:04     INFO - GECKO(2441) | [Child 2625: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fec0bb63400) [pid = 2625] [serial = 8] [outer = 7fec278643c0]
[task 2021-07-10T15:49:04.808Z] 15:49:04     INFO - checking window state
[task 2021-07-10T15:49:04.812Z] 15:49:04     INFO - TEST-START | browser/base/content/test/performance/browser_startup.js

Andrei, could this be caused by the changes in Bug 1704130?

Flags: needinfo?(andrei.br92)
Assignee: nobody → andrei.br92
Flags: needinfo?(andrei.br92)

Oh wow this is kinda annoying. So it looks like we only send telemetry/access the clientID pref on Nightly builds (pref is here).

This hasn't landed yet.

Status: RESOLVED → REOPENED
status-firefox91: verified → affected
Resolution: FIXED → ---
Pushed by gijskruitbosch@gmail.com: https://hg.mozilla.org/integration/autoland/rev/ee0e87247127 Add browser_preferences_usage.js pref exception only for Nightly builds r=Gijs

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

Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
status-firefox91: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 91 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: