1693437 - Intermittent toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimentloader_init.js | false == true - JS frame :: chrome://mochitests/content/browser/toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimen

Status: RESOLVED FIXED

(Firefox :: Messaging System, defect, P1)

Description

[Treeherder Bug Filer]

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=330302869&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/XqDecFV_R7mEjFQLIfZYyw/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/XqDecFV_R7mEjFQLIfZYyw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2021-02-17T22:04:42.037Z] 22:04:42     INFO - TEST-START | toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimentloader_init.js
[task 2021-02-17T22:04:42.164Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.165Z] 22:04:42     INFO - GECKO(3434) |   Registered update timer
[task 2021-02-17T22:04:42.165Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.166Z] 22:04:42     INFO - GECKO(3434) |   Updating recipes
[task 2021-02-17T22:04:42.308Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.308Z] 22:04:42     INFO - GECKO(3434) |   Updating recipes
[task 2021-02-17T22:04:42.324Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.324Z] 22:04:42     INFO - GECKO(3434) |   Got 2 recipes from Remote Settings
[task 2021-02-17T22:04:42.324Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.327Z] 22:04:42     INFO - GECKO(3434) |   No targeting for recipe, so it matches automatically
[task 2021-02-17T22:04:42.327Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.328Z] 22:04:42     INFO - GECKO(3434) |   d44adfa3-b42e-49ea-8500-8291497b38fc matched
[task 2021-02-17T22:04:42.352Z] 22:04:42     INFO - GECKO(3434) | console.debug: ExperimentManager:
[task 2021-02-17T22:04:42.353Z] 22:04:42     INFO - GECKO(3434) |   New experiment started: foo1613599482268, treatment
[task 2021-02-17T22:04:42.354Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.354Z] 22:04:42     INFO - GECKO(3434) |   No targeting for recipe, so it matches automatically
[task 2021-02-17T22:04:42.354Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.355Z] 22:04:42     INFO - GECKO(3434) |   f6637396-a452-442c-b9c8-467354067396 matched
[task 2021-02-17T22:04:42.362Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.363Z] 22:04:42     INFO - GECKO(3434) |   Got 2 recipes from Remote Settings
[task 2021-02-17T22:04:42.364Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.364Z] 22:04:42     INFO - GECKO(3434) |   No targeting for recipe, so it matches automatically
[task 2021-02-17T22:04:42.366Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.366Z] 22:04:42     INFO - GECKO(3434) |   d44adfa3-b42e-49ea-8500-8291497b38fc matched
[task 2021-02-17T22:04:42.366Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.367Z] 22:04:42     INFO - GECKO(3434) |   No targeting for recipe, so it matches automatically
[task 2021-02-17T22:04:42.367Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.367Z] 22:04:42     INFO - GECKO(3434) |   f6637396-a452-442c-b9c8-467354067396 matched
[task 2021-02-17T22:04:42.375Z] 22:04:42     INFO - GECKO(3434) | console.debug: ExperimentManager:
[task 2021-02-17T22:04:42.375Z] 22:04:42     INFO - GECKO(3434) |   Skipping enrollment for "foo1613599482269" because there is an existing experiment for its feature.
[task 2021-02-17T22:04:42.375Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:[task 2021-02-17T22:04:42.375Z] 22:04:42     INFO - GECKO(3434) |   2 recipes matched. Finalizing ExperimentManager.
[task 2021-02-17T22:04:42.383Z] 22:04:42     INFO - GECKO(3434) | console.debug: ExperimentManager:
[task 2021-02-17T22:04:42.384Z] 22:04:42     INFO - GECKO(3434) |   Skipping enrollment for "foo1613599482269" because there is an existing experiment for its feature.
[task 2021-02-17T22:04:42.384Z] 22:04:42     INFO - GECKO(3434) | console.debug: RSLoader:
[task 2021-02-17T22:04:42.385Z] 22:04:42     INFO - GECKO(3434) |   2 recipes matched. Finalizing ExperimentManager.
[task 2021-02-17T22:04:42.386Z] 22:04:42     INFO - GECKO(3434) | console.debug: ExperimentManager:
[task 2021-02-17T22:04:42.386Z] 22:04:42     INFO - GECKO(3434) |   Stopping study for recipe foo1613599482268
[task 2021-02-17T22:04:42.386Z] 22:04:42     INFO - GECKO(3434) | console.debug: ExperimentManager:
[task 2021-02-17T22:04:42.393Z] 22:04:42     INFO - GECKO(3434) |   Experiment unenrolled: foo1613599482268
[task 2021-02-17T22:04:42.395Z] 22:04:42     INFO - TEST-INFO | started process screentopng
[task 2021-02-17T22:04:42.552Z] 22:04:42     INFO - TEST-INFO | screentopng: exit 0
[task 2021-02-17T22:04:42.553Z] 22:04:42     INFO - Buffered messages logged at 22:04:42
[task 2021-02-17T22:04:42.553Z] 22:04:42     INFO - TEST-PASS | toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimentloader_init.js | Sanity check the temporary file doesn't exist. - true == true - 
[task 2021-02-17T22:04:42.554Z] 22:04:42     INFO - Entering test bound setup
[task 2021-02-17T22:04:42.554Z] 22:04:42     INFO - Leaving test bound setup
[task 2021-02-17T22:04:42.554Z] 22:04:42     INFO - Entering test bound test_double_feature_enrollment
[task 2021-02-17T22:04:42.555Z] 22:04:42     INFO - TEST-PASS | toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimentloader_init.js | true == true - 
[task 2021-02-17T22:04:42.555Z] 22:04:42     INFO - Console message: [JavaScript Error: "Unknown Collection "main/nimbus-desktop-experiments"" {file: "resource://services-settings/RemoteSettingsClient.jsm" line: 160}]
[task 2021-02-17T22:04:42.555Z] 22:04:42     INFO - UnknownCollectionError@resource://services-settings/RemoteSettingsClient.jsm:160:5
[task 2021-02-17T22:04:42.556Z] 22:04:42     INFO - sync@resource://services-settings/RemoteSettingsClient.jsm:470:13
[task 2021-02-17T22:04:42.556Z] 22:04:42     INFO - 
[task 2021-02-17T22:04:42.557Z] 22:04:42     INFO - Console message: 1613599482347	Toolkit.Telemetry	WARN	TelemetryEnvironment::setExperimentActive - the experiment type was truncated.
[task 2021-02-17T22:04:42.558Z] 22:04:42     INFO - Buffered messages finished
[task 2021-02-17T22:04:42.558Z] 22:04:42     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimentloader_init.js | false == true - JS frame :: chrome://mochitests/content/browser/toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimentloader_init.js :: test_double_feature_enrollment :: line 90```

Comment 1

[Andrei Oprea [:andreio]]

Attachment: Bug 1693437 - Fix Intermittent toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimentloader_init.js

Comment 2

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/24937d7a350a
Fix Intermittent toolkit/components/messaging-system/test/browser/browser_remotesettingsexperimentloader_init.js r=k88hudson

Comment 4

[Dorel Luca [:dluca]]

https://hg.mozilla.org/mozilla-central/rev/24937d7a350a

Comment 5

[Intermittent Failures Robot]

5 failures in 3737 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64: 2
  • debug: 1
  • opt: 1
• macosx1014-64-qr: 1
  • opt: 1
• linux1804-64-tsan: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1693437&startday=2021-02-15&endday=2021-02-21&tree=all

Comment 6

[Andrei Oprea [:andreio]]

I'll call this fixed by recent changes we landed to component and tests and reopen if it happens again.