1853483 - High frequency browser/components/shopping/tests/browser/browser_shopping_survey.js | single tracking bug

Status: RESOLVED FIXED

(Firefox :: Messaging System, defect, P1)

Description

[Treeherder Bug Filer]

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

---

[task 2023-09-15T23:32:09.636Z] 23:32:09     INFO - TEST-PASS | browser/components/shopping/tests/browser/browser_shopping_survey.js | Survey Message container is shown - true == true - 
[task 2023-09-15T23:32:09.637Z] 23:32:09     INFO - Buffered messages finished
[task 2023-09-15T23:32:09.640Z] 23:32:09     INFO - TEST-UNEXPECTED-FAIL | browser/components/shopping/tests/browser/browser_shopping_survey.js | Survey pref state is updated - false == true - got false, expected true (operator ==)
[task 2023-09-15T23:32:09.641Z] 23:32:09     INFO - Stack trace:
[task 2023-09-15T23:32:09.641Z] 23:32:09     INFO - ok@resource://testing-common/SpecialPowersSandbox.sys.mjs:85:21
[task 2023-09-15T23:32:09.642Z] 23:32:09     INFO - @chrome://mochitests/content/browser/browser/components/shopping/tests/browser/browser_shopping_survey.js:82:13
[task 2023-09-15T23:32:09.643Z] 23:32:09     INFO - async*execute@resource://testing-common/SpecialPowersSandbox.sys.mjs:139:12
[task 2023-09-15T23:32:09.643Z] 23:32:09     INFO - _spawnTask@resource://testing-common/SpecialPowersChild.sys.mjs:1654:15
[task 2023-09-15T23:32:09.644Z] 23:32:09     INFO - receiveMessage@resource://testing-common/SpecialPowersChild.sys.mjs:257:21
[task 2023-09-15T23:32:09.644Z] 23:32:09     INFO - JSActor query*receiveMessage@resource://testing-common/SpecialPowersParent.sys.mjs:1385:14
[task 2023-09-15T23:32:09.645Z] 23:32:09     INFO - JSActor query*spawn@resource://testing-common/SpecialPowersChild.sys.mjs:1583:17
[task 2023-09-15T23:32:09.645Z] 23:32:09     INFO - test_showSurvey_Enabled/<@chrome://mochitests/content/browser/browser/components/shopping/tests/browser/browser_shopping_survey.js:41:27
[task 2023-09-15T23:32:09.646Z] 23:32:09     INFO - withNewTab@resource://testing-common/BrowserTestUtils.sys.mjs:146:22
[task 2023-09-15T23:32:09.647Z] 23:32:09     INFO - async*test_showSurvey_Enabled@chrome://mochitests/content/browser/browser/components/shopping/tests/browser/browser_shopping_survey.js:35:26
[task 2023-09-15T23:32:09.647Z] 23:32:09     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1131:26
[task 2023-09-15T23:32:09.648Z] 23:32:09     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1203:18
[task 2023-09-15T23:32:09.648Z] 23:32:09     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1345:14
[task 2023-09-15T23:32:09.649Z] 23:32:09     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1114:14
[task 2023-09-15T23:32:09.649Z] 23:32:09     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2023-09-15T23:32:09.650Z] 23:32:09     INFO - GECKO(1607) | JavaScript error: , line 0: AbortError: Actor 'AboutWelcomeShopping' destroyed before query 'AWPage:ADD_SCREEN_IMPRESSION' was resolved
[task 2023-09-15T23:32:09.651Z] 23:32:09     INFO - GECKO(1607) | JavaScript error: , line 0: AbortError: Actor 'AboutWelcomeShopping' destroyed before query 'AWPage:TELEMETRY_EVENT' was resolved
[task 2023-09-15T23:32:09.652Z] 23:32:09     INFO - GECKO(1607) | JavaScript error: , line 0: InternalError: Promise rejection value is a non-unwrappable cross-compartment wrapper.
[task 2023-09-15T23:32:09.653Z] 23:32:09     INFO - GECKO(1607) | JavaScript error: , line 0: InternalError: Promise rejection value is a non-unwrappable cross-compartment wrapper.
[task 2023-09-15T23:32:09.653Z] 23:32:09     INFO - GECKO(1607) | console.warn: AboutWelcomeParent: Not handling AWPage:TELEMETRY_EVENT because the browser doesn't exist.
[task 2023-09-15T23:32:09.654Z] 23:32:09     INFO - GECKO(1607) | console.warn: AboutWelcomeParent: Not handling AWPage:ADD_SCREEN_IMPRESSION because the browser doesn't exist.
[task 2023-09-15T23:32:09.655Z] 23:32:09     INFO - Leaving test bound test_showSurvey_Enabled
[task 2023-09-15T23:32:09.656Z] 23:32:09     INFO - Entering test bound test_showSurvey_Disabled
[task 2023-09-15T23:32:09.656Z] 23:32:09     INFO - Console message: [JavaScript Error: "AbortError: Actor 'AboutWelcomeShopping' destroyed before query 'AWPage:ADD_SCREEN_IMPRESSION' was resolved"]
[task 2023-09-15T23:32:09.657Z] 23:32:09     INFO - Console message: [JavaScript Error: "AbortError: Actor 'AboutWelcomeShopping' destroyed before query 'AWPage:TELEMETRY_EVENT' was resolved"]
[task 2023-09-15T23:32:09.658Z] 23:32:09     INFO - Console message: [JavaScript Error: "InternalError: Promise rejection value is a non-unwrappable cross-compartment wrapper."]
[task 2023-09-15T23:32:09.659Z] 23:32:09     INFO - Console message: [JavaScript Error: "InternalError: Promise rejection value is a non-unwrappable cross-compartment wrapper."]
[task 2023-09-15T23:32:09.659Z] 23:32:09     INFO - GECKO(1607) | [Child 1844: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7ff027ca4800 == 4 [pid = 1844] [id = 3]
[task 2023-09-15T23:32:09.660Z] 23:32:09     INFO - GECKO(1607) | [Child 1844: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (7ff0283f8d40) [pid = 1844] [serial = 10] [outer = 0]
[task 2023-09-15T23:32:09.661Z] 23:32:09     INFO - GECKO(1607) | [Child 1844: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (7ff027ca4c00) [pid = 1844] [serial = 11] [outer = 7ff0283f8d40]
[task 2023-09-15T23:32:09.733Z] 23:32:09     INFO - GECKO(1607) | [Child 1844: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (7ff027ca5c00) [pid = 1844] [serial = 12] [outer = 7ff0283f8d40]
[task 2023-09-15T23:32:09.932Z] 23:32:09     INFO - GECKO(1607) | console.error: "Failed to fetch product analysis data" (new TypeError("null has no properties", "resource:///actors/ShoppingSidebarChild.sys.mjs", 243))
[task 2023-09-15T23:32:10.188Z] 23:32:10     INFO - GECKO(1607) | [Child 1844, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-09-15T23:32:10.188Z] 23:32:10     INFO - GECKO(1607) | [Child 1844, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-09-15T23:32:10.497Z] 23:32:10     INFO - TEST-PASS | browser/components/shopping/tests/browser/browser_shopping_survey.js | Survey is disabled - true == true - 

Comment 1

[BugBot [:suhaib / :marco/ :calixte]]

:pdahiya, since you are the author of the regressor, bug 1851635, could you take a look?

For more information, please visit BugBot documentation.

Comment 2

[Sandor Molnar[:smolnar]]

tier 1 failure -> https://treeherder.mozilla.org/logviewer?job_id=429356887&repo=autoland&lineNumber=4911

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5

Platform and build breakdown:

• linux1804-64-qr: 3
  • debug: 3
• linux1804-64-tsan-qr: 1
  • opt: 1
• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1853483&startday=2023-09-11&endday=2023-09-17&tree=all

Comment 5

[Punam Dahiya [:pdahiya]]

Failure is highly intermittent and seen only so far on autoland. Looks like race condition between setting (surveySeeen) and read pref and could be resolved by adding explicit wait for pref to be set before checking pref value in test. I can submit the patch if the intermittent persist. Keeping NI for now. thanks!

Comment 6

[Punam Dahiya [:pdahiya]]

Attachment: Bug 1853483 - Fix of intermittent failure due to survey seen pref state

Comment 7

[Intermittent Failures Robot]

57 failures in 822 pushes (0.069 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 51
• mozilla-central: 5
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 17
  • debug: 13
  • opt: 4
• macosx1015-64-qr: 6
  • debug: 5
  • opt: 1
• windows11-32-2009-qr: 5
  • debug: 5
• linux1804-64-tsan-qr: 5
  • opt: 5
• windows11-64-2009-qr: 22
  • debug: 22
• linux1804-64-asan-qr: 1
  • opt: 1
• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1853483&startday=2023-09-19&endday=2023-09-19&tree=all

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/6fee26d24a58
Fix of intermittent failure due to survey seen pref state r=omc-reviewers,shopping-reviewers,aminomancer,Gijs

Comment 9

[Intermittent Failures Robot]

35 failures in 774 pushes (0.045 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 4
• autoland: 29
• try: 2

Platform and build breakdown:

• linux1804-64-qr: 18
  • debug: 15
  • opt: 3
• macosx1015-64-qr: 2
  • debug: 2
• windows11-32-2009-qr: 4
  • debug: 4
• windows11-64-2009-asan-qr: 1
  • opt: 1
• windows11-64-2009-qr: 8
  • debug: 8
• linux1804-64-tsan-qr: 1
  • opt: 1
• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1853483&startday=2023-09-20&endday=2023-09-20&tree=all

Comment 10

[Sandor Molnar[:smolnar]]

https://hg.mozilla.org/mozilla-central/rev/6fee26d24a58

Comment 11

[Intermittent Failures Robot]

97 failures in 3850 pushes (0.025 failures/push) were associated with this bug in the last 7 days.

This is the #4 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 82
• mozilla-central: 12
• try: 3

Platform and build breakdown:

• linux1804-64-qr: 36
  • debug: 29
  • opt: 7
• linux1804-64-tsan-qr: 7
  • opt: 7
• linux1804-64-asan-qr: 2
  • opt: 2
• macosx1015-64-qr: 8
  • debug: 7
  • opt: 1
• windows11-32-2009-qr: 9
  • debug: 9
• windows11-64-2009-qr: 32
  • debug: 32
• windows11-64-2009-asan-qr: 2
  • opt: 2
• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1853483&startday=2023-09-18&endday=2023-09-24&tree=all

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1853483&startday=2023-09-25&endday=2023-10-01&tree=all

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• elm: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1853483&startday=2023-10-16&endday=2023-10-22&tree=all

Comment 14

[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:

• linux1804-64-tsan-qr: 1
  • opt: 1

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