1875204 - Intermittent browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_multistage_languageSwitcher.js | single tracking bug

Status: RESOLVED FIXED

(Firefox :: Messaging System, defect, P1)

Description

[Treeherder Bug Filer]

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=443726355&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TtGSXYwIRpGzT_eSuw-hVQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2024-01-18T03:42:49.309Z] 03:42:49     INFO - TEST-START | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_multistage_languageSwitcher.js
[task 2024-01-18T03:42:49.432Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 2bf37322000 == 2 [pid = 9296] [id = 56]
[task 2024-01-18T03:42:49.433Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (2bf2d24e6a0) [pid = 9296] [serial = 170] [outer = 0]
[task 2024-01-18T03:42:49.434Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (2bf37324400) [pid = 9296] [serial = 171] [outer = 2bf2d24e6a0]
[task 2024-01-18T03:42:49.473Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (2bf38322c00) [pid = 9296] [serial = 172] [outer = 2bf2d24e6a0]
[task 2024-01-18T03:42:49.600Z] 03:42:49     INFO - GECKO(7444) | [Parent 7128, BgIOThreadPool #1] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1296
[task 2024-01-18T03:42:49.897Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (2bf2d24dd40) [pid = 9296] [serial = 164] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:49.898Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (2bf36582020) [pid = 9296] [serial = 151] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:49.899Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (2bf380c0020) [pid = 9296] [serial = 161] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:49.899Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (2bf365823e0) [pid = 9296] [serial = 158] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:49.900Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (2bf36582200) [pid = 9296] [serial = 154] [outer = 0] [url = about:home]
[task 2024-01-18T03:42:49.900Z] 03:42:49     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (2bf2d24e4c0) [pid = 9296] [serial = 167] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:50.402Z] 03:42:50     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (2bf38325400) [pid = 9296] [serial = 153] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:50.404Z] 03:42:50     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (2bf3832e000) [pid = 9296] [serial = 157] [outer = 0] [url = about:home]
[task 2024-01-18T03:42:50.404Z] 03:42:50     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (2bf39e1c000) [pid = 9296] [serial = 160] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:50.406Z] 03:42:50     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (2bf39e1b400) [pid = 9296] [serial = 163] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:50.406Z] 03:42:50     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (2bf3832e800) [pid = 9296] [serial = 166] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:50.407Z] 03:42:50     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (2bf39e13c00) [pid = 9296] [serial = 169] [outer = 0] [url = about:welcome]
[task 2024-01-18T03:42:50.407Z] 03:42:50     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (2bf3832a400) [pid = 9296] [serial = 168] [outer = 0] [url = about:blank]
[task 2024-01-18T03:42:51.848Z] 03:42:51     INFO - GECKO(7444) | WARN: HLSLCompiler.cpp:257 (compileToBinary):
[task 2024-01-18T03:42:51.849Z] 03:42:51     INFO - GECKO(7444) | C:\fakepath(1767,1-6): warning X4000: use of potentially uninitialized variable (dyn_index_vec4_float4_int)
[task 2024-01-18T03:42:51.849Z] 03:42:51     INFO - GECKO(7444) | WARN: HLSLCompiler.cpp:257 (compileToBinary):
[task 2024-01-18T03:42:51.849Z] 03:42:51     INFO - GECKO(7444) | C:\fakepath(1767,1-6): warning X4000: use of potentially uninitialized variable (dyn_index_vec4_float4_int)
[task 2024-01-18T03:42:51.955Z] 03:42:51     INFO - GECKO(7444) | [Child 9716: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (1e74a74d200) [pid = 9716] [serial = 17] [outer = 0] [url = about:blank]
[task 2024-01-18T03:42:52.917Z] 03:42:52     INFO - GECKO(7444) | [WARN  l10nregistry_ffi::env] L10nRegistry error: Missing resource in locale es-ES: branding/brand.ftl
[task 2024-01-18T03:42:54.089Z] 03:42:54     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 2bf39e14800 == 3 [pid = 9296] [id = 57]
[task 2024-01-18T03:42:54.090Z] 03:42:54     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (2bf2d24e4c0) [pid = 9296] [serial = 173] [outer = 0]
<...>
[task 2024-01-18T03:43:05.455Z] 03:43:05     INFO - TEST-PASS | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_multistage_languageSwitcher.js | undefined assertion name - 
[task 2024-01-18T03:43:05.455Z] 03:43:05     INFO - LangPack install finished.
[task 2024-01-18T03:43:05.456Z] 03:43:05     INFO - Leaving test bound test_aboutwelcome_fallback_locale
[task 2024-01-18T03:43:05.456Z] 03:43:05     INFO - Entering test bound test_aboutwelcome_languageSwitcher_noMatch
[task 2024-01-18T03:43:05.457Z] 03:43:05     INFO - Mocking LangPackMatcher.jsm APIs
[task 2024-01-18T03:43:05.457Z] 03:43:05     INFO - Opening about:welcome
[task 2024-01-18T03:43:05.458Z] 03:43:05     INFO - Requesting which langpacks are available for download
[task 2024-01-18T03:43:05.458Z] 03:43:05     INFO - Clicking the primary button to start installing the langpack.
[task 2024-01-18T03:43:05.459Z] 03:43:05     INFO - Resolving which langpacks are available for download: ["es-MX","es-ES","fr-FR"]
[task 2024-01-18T03:43:05.461Z] 03:43:05     INFO - Console message: [JavaScript Error: "Error: Expected a LangPack to install." {file: "resource://gre/modules/LangPackMatcher.sys.mjs" line: 114}]
[task 2024-01-18T03:43:05.461Z] 03:43:05     INFO - ensureLangPackInstalled@resource://gre/modules/LangPackMatcher.sys.mjs:114:11
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - onContentMessage@resource:///actors/AboutWelcomeParent.sys.mjs:228:37
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - invoke@resource://testing-common/sinon-7.2.7.js:2609:51
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - proxy@resource://testing-common/sinon-7.2.7.js:2407:30
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - receiveMessage@resource:///actors/AboutWelcomeParent.sys.mjs:251:19
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - JSActor query*AWEnsureLangPackInstalled@resource:///actors/AboutWelcomeChild.sys.mjs:265:12
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - ensureLangPackInstalled@chrome://browser/content/aboutwelcome/aboutwelcome.bundle.js:1540:12
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - Bh@resource://activity-stream/vendor/react-dom.js:126:456
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - Dj@resource://activity-stream/vendor/react-dom.js:162:476
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - unstable_runWithPriority@resource://activity-stream/vendor/react.js:25:260
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - Da@resource://activity-stream/vendor/react-dom.js:60:280
[task 2024-01-18T03:43:05.462Z] 03:43:05     INFO - xb@resource://activity-stream/vendor/react-dom.js:162:231
[task 2024-01-18T03:43:05.463Z] 03:43:05     INFO - Bj/<@resource://activity-stream/vendor/react-dom.js:162:138
[task 2024-01-18T03:43:05.463Z] 03:43:05     INFO - U@resource://activity-stream/vendor/react.js:16:399
[task 2024-01-18T03:43:05.463Z] 03:43:05     INFO - B.port1.onmessage@resource://activity-stream/vendor/react.js:24:130
[task 2024-01-18T03:43:05.463Z] 03:43:05     INFO - EventHandlerNonNull*@resource://activity-stream/vendor/react.js:24:65
[task 2024-01-18T03:43:05.463Z] 03:43:05     INFO - @resource://activity-stream/vendor/react.js:9:167
[task 2024-01-18T03:43:05.463Z] 03:43:05     INFO - @resource://activity-stream/vendor/react.js:9:183
[task 2024-01-18T03:43:05.463Z] 03:43:05     INFO - 
[task 2024-01-18T03:43:05.466Z] 03:43:05     INFO - Console message: [JavaScript Error: "Error: Expected a LangPack to install." {file: "resource://gre/modules/LangPackMatcher.sys.mjs" line: 114}]
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - ensureLangPackInstalled@resource://gre/modules/LangPackMatcher.sys.mjs:114:11
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - onContentMessage@resource:///actors/AboutWelcomeParent.sys.mjs:228:37
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - invoke@resource://testing-common/sinon-7.2.7.js:2609:51
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - proxy@resource://testing-common/sinon-7.2.7.js:2407:30
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - receiveMessage@resource:///actors/AboutWelcomeParent.sys.mjs:251:19
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - JSActor query*AWEnsureLangPackInstalled@resource:///actors/AboutWelcomeChild.sys.mjs:265:12
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - ensureLangPackInstalled@chrome://browser/content/aboutwelcome/aboutwelcome.bundle.js:1540:12
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - Bh@resource://activity-stream/vendor/react-dom.js:126:456
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - Dj@resource://activity-stream/vendor/react-dom.js:162:476
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - unstable_runWithPriority@resource://activity-stream/vendor/react.js:25:260
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - Da@resource://activity-stream/vendor/react-dom.js:60:280
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - xb@resource://activity-stream/vendor/react-dom.js:162:231
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - Bj/<@resource://activity-stream/vendor/react-dom.js:162:138
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - U@resource://activity-stream/vendor/react.js:16:399
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - B.port1.onmessage@resource://activity-stream/vendor/react.js:24:130
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - EventHandlerNonNull*@resource://activity-stream/vendor/react.js:24:65
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - @resource://activity-stream/vendor/react.js:9:167
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - @resource://activity-stream/vendor/react.js:9:183
[task 2024-01-18T03:43:05.467Z] 03:43:05     INFO - 
[task 2024-01-18T03:43:05.468Z] 03:43:05     INFO - Buffered messages finished
[task 2024-01-18T03:43:05.469Z] 03:43:05     INFO - TEST-UNEXPECTED-FAIL | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_multistage_languageSwitcher.js | Uncaught exception in test bound test_aboutwelcome_languageSwitcher_noMatch - Should render .screen.AW_IMPORT_SETTINGS_EMBEDDED in Language selection skipped - timed out after 50 tries.
[task 2024-01-18T03:43:05.469Z] 03:43:05     INFO - Leaving test bound test_aboutwelcome_languageSwitcher_noMatch
[task 2024-01-18T03:43:05.470Z] 03:43:05     INFO - Entering test bound test_aboutwelcome_languageSwitcher_bidiNotSupported
[task 2024-01-18T03:43:05.470Z] 03:43:05     INFO - Mocking LangPackMatcher.jsm APIs
[task 2024-01-18T03:43:05.470Z] 03:43:05     INFO - Opening about:welcome
[task 2024-01-18T03:43:05.471Z] 03:43:05     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 2bf39f7e800 == 7 [pid = 9296] [id = 61]
[task 2024-01-18T03:43:05.471Z] 03:43:05     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (2bf36583100) [pid = 9296] [serial = 185] [outer = 0]
[task 2024-01-18T03:43:05.472Z] 03:43:05     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (2bf39f86c00) [pid = 9296] [serial = 186] [outer = 2bf36583100]
[task 2024-01-18T03:43:05.472Z] 03:43:05     INFO - GECKO(7444) | [Child 9296: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (2bf3a40dc00) [pid = 9296] [serial = 187] [outer = 2bf36583100]
[task 2024-01-18T03:43:05.516Z] 03:43:05     INFO - GECKO(7444) | [Parent 7128, BgIOThreadPool #1] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1296
[task 2024-01-18T03:43:05.554Z] 03:43:05     INFO - Requesting which langpacks are available for download
[task 2024-01-18T03:43:05.650Z] 03:43:05     INFO - Clicking the primary button to start installing the langpack.
[task 2024-01-18T03:43:05.803Z] 03:43:05     INFO - GECKO(7444) | [Child 1520: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (1d0c8d3c800) [pid = 1520] [serial = 10] [outer = 0] [url = about:blank]
[task 2024-01-18T03:43:07.002Z] 03:43:07     INFO - TEST-PASS | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_multistage_languageSwitcher.js | Should not render [data-l10n-id*="onboarding-live-language"] in Language selection skipped for bidi - true == true - 
[task 2024-01-18T03:43:07.003Z] 03:43:07     INFO - TEST-PASS | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_multistage_languageSwitcher.js | Should not render [data-l10n-id="onboarding-live-language-header"] in Language selection skipped for bidi - true == true - 

Comment 1

[Natalia Csoregi [:nataliaCs]]

First occurrence so far, in this range of retriggers and backfills: range

Negin, can you please check if Bug 1873019 could be the cause of this failure?
Thank you.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows11-64-2009-qr: 2
  • debug: 2

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

Comment 3

[Negin]

Thanks for flagging! Will keep an eye on this and the cadence of intermittent (seems quite low for the time being and there is plans to refactor some of the LanguageSwitcher logic that may improve intermittents)

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 3
• mozilla-central: 1
• autoland: 2

Platform and build breakdown:

• windows11-64-2009-qr: 4
  • debug: 4
• windows11-64-2009-asan-qr: 1
  • opt: 1
• windows11-32-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1875204&startday=2024-01-22&endday=2024-01-28&tree=all

Comment 5

[Negin]

Hi Greg! Hope you're the right person to NI about this, not sure if this is related to the intermittent but it seems like it might be?

When running the following command in Nightly, it gets stuck on getting language packs (Getting available languages..)

./mach run --temp-profile --setpref “extensions.getAddons.langpacks.url=https://mock-amo-language-tools.glitch.me/?app=firefox&type=language&appversion=%VERSION%” --setpref “intl.multilingual.aboutWelcome.languageMismatchEnabled=true” --setpref “intl.multilingual.aboutWelcome.systemLocaleOverride=es-ES” `#(optional)` --setpref browser.aboutwelcome.templateMR=true -- --new-tab about:welcome

It seems like https://mock-amo-language-tools.glitch.me/?app=firefox&type=language&appversion=124.0a1 may not be working? Routing to it manually shows:
It looks like this project isn't running because the version of Node.js it relies on is too old.

Comment 6

[Intermittent Failures Robot]

1 failures in 3952 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1875204&startday=2024-01-29&endday=2024-02-04&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2
• mozilla-central: 2
• mozilla-beta: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1
• windows11-64-2009-qr: 4
  • debug: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1875204&startday=2024-02-05&endday=2024-02-11&tree=all

Comment 8

[Greg Tatum [:gregtatum]]

Was this... :mstriemer that had access to this? This conversation was 2 years ago. Perhaps it would be worth documenting where this thing lives so we can maintain it a bit better. I only used it when doing live language switching stuff.

Comment 9

[Mark Striemer [:mstriemer]]

I updated the node version on Glitch and it appears to be running now. It still says its node is outdated, but hopefully that resolves soon, the docs say it could take 12 hours to update.

Let me know if you run into anymore issues

Comment 10

[Negin]

Thanks Mark, this working for me locally! I think this is safe to close.

Comment 11

[Intermittent Failures Robot]

1 failures in 3382 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1875204&startday=2024-02-19&endday=2024-02-25&tree=all

Comment 12

[Intermittent Failures Robot]

1 failures in 4547 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-32-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1875204&startday=2024-05-13&endday=2024-05-19&tree=all

Comment 13

[Intermittent Failures Robot]

1 failures in 3995 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1875204&startday=2024-05-20&endday=2024-05-26&tree=all

Comment 14

[Intermittent Failures Robot]

1 failures in 3839 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1875204&startday=2024-07-29&endday=2024-08-04&tree=all