Closed Bug 1975789 Opened 10 months ago Closed 9 months ago

Intermittent TV chrome://mochitests/content/browser/toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | changed preference: app.normandy.user_id | single tracking bug

Categories

(Firefox :: Nimbus Desktop Client, defect, P5)

Product:
Firefox
Component:
Nimbus Desktop Client
Type:
defect

Tracking

()

Status:
RESOLVED WORKSFORME
Tracking Flags:
Tracking Status
firefox-esr128 --- unaffected
firefox-esr140 --- unaffected
firefox140 --- unaffected
firefox141 --- unaffected
firefox142 --- affected
firefox143 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=516442867&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TAJC0qtJSqGTwRWEFSmQ6A/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TAJC0qtJSqGTwRWEFSmQ6A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2025-07-04T19:30:23.430+00:00] 19:30:23     INFO - TEST-START | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js
[task 2025-07-04T19:30:24.505+00:00] 19:30:24     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:24.505+00:00] 19:30:24     INFO - GECKO(5133) |   Updating recipes with trigger "enabled"
[task 2025-07-04T19:30:25.189+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.192+00:00] 19:30:25     INFO - GECKO(5133) |   Got 0 recipes from nimbus-desktop-experiments
[task 2025-07-04T19:30:25.300+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.300+00:00] 19:30:25     INFO - GECKO(5133) |   Got 0 recipes from nimbus-secure-experiments
[task 2025-07-04T19:30:25.301+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.302+00:00] 19:30:25     INFO - GECKO(5133) |   0 recipes matched.
[task 2025-07-04T19:30:25.350+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.351+00:00] 19:30:25     INFO - GECKO(5133) |   Updating recipes with trigger "mochitest"
[task 2025-07-04T19:30:25.489+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.490+00:00] 19:30:25     INFO - GECKO(5133) |   Got 1 recipes from nimbus-desktop-experiments
[task 2025-07-04T19:30:25.524+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.525+00:00] 19:30:25     INFO - GECKO(5133) |   Got 0 recipes from nimbus-secure-experiments
[task 2025-07-04T19:30:25.545+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.546+00:00] 19:30:25     INFO - GECKO(5133) |   Testing targeting expression:
[task 2025-07-04T19:30:25.546+00:00] 19:30:25     INFO - GECKO(5133) |   true
[task 2025-07-04T19:30:25.550+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.551+00:00] 19:30:25     INFO - GECKO(5133) |   [experiment] foo0.6892480164630118 matched targeting
[task 2025-07-04T19:30:25.588+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: NimbusEnrollments:
[task 2025-07-04T19:30:25.589+00:00] 19:30:25     INFO - GECKO(5133) |   Queued update for enrollment foo0.6892480164630118
[task 2025-07-04T19:30:25.604+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: ExperimentManager:
[task 2025-07-04T19:30:25.604+00:00] 19:30:25     INFO - GECKO(5133) |   New experiment started: foo0.6892480164630118, treatment
[task 2025-07-04T19:30:25.605+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.606+00:00] 19:30:25     INFO - GECKO(5133) |   1 recipes matched.
[task 2025-07-04T19:30:25.611+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: NimbusEnrollments:
[task 2025-07-04T19:30:25.611+00:00] 19:30:25     INFO - GECKO(5133) |   Queued update for enrollment foo0.6892480164630118
[task 2025-07-04T19:30:25.627+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: ExperimentManager:
[task 2025-07-04T19:30:25.628+00:00] 19:30:25     INFO - GECKO(5133) |   Recipe unenrolled: foo0.6892480164630118
[task 2025-07-04T19:30:25.654+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: NimbusEnrollments:
[task 2025-07-04T19:30:25.654+00:00] 19:30:25     INFO - GECKO(5133) |   Flushing 1 changes to database
[task 2025-07-04T19:30:25.691+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: NimbusEnrollments:
[task 2025-07-04T19:30:25.692+00:00] 19:30:25     INFO - GECKO(5133) |   Deleted expired enrollment foo0.6892480164630118
[task 2025-07-04T19:30:25.855+00:00] 19:30:25     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:25.855+00:00] 19:30:25     INFO - GECKO(5133) |   Updating recipes with trigger "enabled"
[task 2025-07-04T19:30:26.044+00:00] 19:30:26     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:26.044+00:00] 19:30:26     INFO - GECKO(5133) |   Got 0 recipes from nimbus-desktop-experiments
[task 2025-07-04T19:30:26.159+00:00] 19:30:26     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:26.160+00:00] 19:30:26     INFO - GECKO(5133) |   Got 0 recipes from nimbus-secure-experiments
[task 2025-07-04T19:30:26.160+00:00] 19:30:26     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:26.161+00:00] 19:30:26     INFO - GECKO(5133) |   0 recipes matched.
[task 2025-07-04T19:30:26.176+00:00] 19:30:26     INFO - GECKO(5133) | console.debug: RSLoader:
[task 2025-07-04T19:30:26.177+00:00] 19:30:26     INFO - GECKO(5133) |   Updating recipes with trigger "enabled"
[task 2025-07-04T19:30:26.473+00:00] 19:30:26     INFO - Buffered messages logged at 19:30:24
[task 2025-07-04T19:30:26.474+00:00] 19:30:26     INFO - Entering setup bound 
[task 2025-07-04T19:30:26.475+00:00] 19:30:26     INFO - Leaving setup bound 
[task 2025-07-04T19:30:26.475+00:00] 19:30:26     INFO - Entering setup bound 
[task 2025-07-04T19:30:26.476+00:00] 19:30:26     INFO - Buffered messages logged at 19:30:25
[task 2025-07-04T19:30:26.477+00:00] 19:30:26     INFO - Leaving setup bound 
[task 2025-07-04T19:30:26.477+00:00] 19:30:26     INFO - Entering test bound test_experimentEnrollment
[task 2025-07-04T19:30:26.477+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | Enrollment active - "foo0.6892480164630118" == "foo0.6892480164630118" - 
[task 2025-07-04T19:30:26.478+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | Experiment is no longer active - true == true - 
[task 2025-07-04T19:30:26.478+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | Store should have no active enrollments - [] deepEqual [] - 
[task 2025-07-04T19:30:26.479+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | Store should have no inactive enrollments - [] deepEqual [] - 
[task 2025-07-04T19:30:26.479+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | No active slugs in NimbusEnrollments for aaf9c0f1-b74d-4f1c-849c-b5423d597c49 - [] deepEqual [] - 
[task 2025-07-04T19:30:26.480+00:00] 19:30:26     INFO - Leaving test bound test_experimentEnrollment
[task 2025-07-04T19:30:26.480+00:00] 19:30:26     INFO - Entering test bound test_experimentEnrollment_startup
[task 2025-07-04T19:30:26.481+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | Should be disabled - true == true - 
[task 2025-07-04T19:30:26.481+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | Should be enabled - true == true - 
[task 2025-07-04T19:30:26.482+00:00] 19:30:26     INFO - Leaving test bound test_experimentEnrollment_startup
[task 2025-07-04T19:30:26.482+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | Store should have no active enrollments - [] deepEqual [] - 
[task 2025-07-04T19:30:26.483+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | Store should have no inactive enrollments - [] deepEqual [] - 
[task 2025-07-04T19:30:26.483+00:00] 19:30:26     INFO - TEST-PASS | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | No active slugs in NimbusEnrollments for aaf9c0f1-b74d-4f1c-849c-b5423d597c49 - [] deepEqual [] - 
[task 2025-07-04T19:30:26.484+00:00] 19:30:26     INFO - Buffered messages finished
[task 2025-07-04T19:30:26.484+00:00] 19:30:26    ERROR - TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | changed preference: app.normandy.user_id
[task 2025-07-04T19:30:26.485+00:00] 19:30:26    ERROR - TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | changed preference: datareporting.dau.cachedUsageProfileGroupID
[task 2025-07-04T19:30:26.485+00:00] 19:30:26    ERROR - TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | changed preference: datareporting.dau.cachedUsageProfileID
[task 2025-07-04T19:30:26.486+00:00] 19:30:26    ERROR - TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | changed preference: app.update.lastUpdateTime.rs-experiment-loader-timer
[task 2025-07-04T19:30:26.486+00:00] 19:30:26     INFO - GECKO(5133) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2025-07-04T19:30:26.487+00:00] 19:30:26     INFO - GECKO(5133) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2025-07-04T19:30:26.487+00:00] 19:30:26     INFO - GECKO(5133) | MEMORY STAT | vsize 120598974MB | residentFast 1686MB
[task 2025-07-04T19:30:26.488+00:00] 19:30:26     INFO - TEST-OK | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js | took 3049ms
[task 2025-07-04T19:30:26.584+00:00] 19:30:26     INFO - checking window state
[task 2025-07-04T19:30:26.752+00:00] 19:30:26     INFO - TEST-START | toolkit/components/nimbus/test/browser/browser_remotesettings_experiment_enroll.js
Keywords: regression
Regressed by: 1972876
Regressed by: 1972848
No longer regressed by: 1972876

:beth, since you are the author of the regressor, bug 1972848, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(brennie)

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

status-firefox140: --- → unaffected
status-firefox141: --- → unaffected
status-firefox142: --- → affected
status-firefox-esr128: --- → unaffected
status-firefox-esr140: --- → unaffected

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

status-firefox143: --- → affected

In bug 1978278 I made TV jobs no longer fail for preference changes, so the failure in this bug will no longer be reported.

Status: NEW → RESOLVED
Closed: 9 months ago
Depends on: 1978278
Resolution: --- → WORKSFORME
Flags: needinfo?(brennie)
You need to log in before you can comment on or make changes to this bug.