1779608 - Intermittent browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | single tracking bug

Status: RESOLVED FIXED

(Firefox :: New Tab Page, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-07-14T16:48:37.504Z] 16:48:37     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | 2 Stub was called - 
[task 2022-07-14T16:48:37.504Z] 16:48:37     INFO - Call #0: AWPage:TELEMETRY_EVENT {"event":"CLICK_BUTTON","event_context":{"source":"secondary_button_top","page":"about:welcome"},"message_id":"MY-MOCHITEST-EXPERIMENT_0_AW_STEP1"}
[task 2022-07-14T16:48:37.506Z] 16:48:37     INFO - Call #1: AWPage:SPECIAL_ACTION {"type":"SHOW_FIREFOX_ACCOUNTS","data":{"entrypoint":"test","extraParams":{"utm_source":"activity-stream","utm_campaign":"firstrun","utm_medium":"referral","utm_term":"aboutwelcome-aboutwelcome-my-mochitest-experiment-experiment-0.21051187695518048-control-screen"}}}
[task 2022-07-14T16:48:37.507Z] 16:48:37     INFO - Buffered messages finished
[task 2022-07-14T16:48:37.508Z] 16:48:37     INFO - TEST-UNEXPECTED-FAIL | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | Telemetry includes MR message id - false == true - JS frame :: chrome://mochitests/content/browser/browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js :: test_aboutwelcome_mr_template_telemetry :: line 49
[task 2022-07-14T16:48:37.508Z] 16:48:37     INFO - Stack trace:
[task 2022-07-14T16:48:37.508Z] 16:48:37     INFO - chrome://mochitests/content/browser/browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js:test_aboutwelcome_mr_template_telemetry:49
[task 2022-07-14T16:48:37.510Z] 16:48:37     INFO - Leaving test bound test_aboutwelcome_mr_template_telemetry
[task 2022-07-14T16:48:37.512Z] 16:48:37     INFO - Entering test bound test_aboutwelcome_mr_template_content
[task 2022-07-14T16:48:37.513Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeChild:
[task 2022-07-14T16:48:37.514Z] 16:48:37     INFO - GECKO(10056) |   Received page event DOMDocElementInserted
[task 2022-07-14T16:48:37.518Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.519Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:GET_ATTRIBUTION_DATA
[task 2022-07-14T16:48:37.521Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeChild:
[task 2022-07-14T16:48:37.523Z] 16:48:37     INFO - GECKO(10056) |   Loading about:welcome with no experiment
[task 2022-07-14T16:48:37.524Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.527Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:NEED_DEFAULT
[task 2022-07-14T16:48:37.528Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.529Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:DOES_APP_NEED_PIN
[task 2022-07-14T16:48:37.530Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.530Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:FXA_METRICS_FLOW_URI
[task 2022-07-14T16:48:37.531Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.533Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-14T16:48:37.533Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.534Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:SET_WELCOME_MESSAGE_SEEN
[task 2022-07-14T16:48:37.534Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.537Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-14T16:48:37.538Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.539Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:GET_REGION
[task 2022-07-14T16:48:37.540Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.541Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:GET_SELECTED_THEME
[task 2022-07-14T16:48:37.541Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.542Z] 16:48:37     INFO - GECKO(10056) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-14T16:48:37.600Z] 16:48:37     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | Navigated to a welcome screen - "about:welcome" == "about:welcome" - 
[task 2022-07-14T16:48:37.602Z] 16:48:37     INFO - Leaving test bound test_aboutwelcome_mr_template_content
[task 2022-07-14T16:48:37.655Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.656Z] 16:48:37     INFO - GECKO(10056) |   Terminate reason is welcome-tab-closed
[task 2022-07-14T16:48:37.665Z] 16:48:37     INFO - GECKO(10056) | console.debug: AboutWelcomeParent:
[task 2022-07-14T16:48:37.665Z] 16:48:37     INFO - GECKO(10056) |   Terminate reason is welcome-tab-closed
[task 2022-07-14T16:48:37.671Z] 16:48:37     INFO - GECKO(10056) | MEMORY STAT | vsize 11361MB | residentFast 403MB | heapAllocated 221MB
[task 2022-07-14T16:48:37.672Z] 16:48:37     INFO - TEST-OK | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | took 1009ms
[task 2022-07-14T16:48:37.742Z] 16:48:37     INFO - checking window state
[task 2022-07-14T16:48:37.804Z] 16:48:37     INFO - TEST-START | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_primary.js

Comment 1

[Intermittent Failures Robot]

19 failures in 3341 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 14
• mozilla-central: 4
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 10
  • opt: 5
  • debug: 5
• linux1804-64-shippable-qr: 7
  • opt: 7
• linux1804-64-asan-qr: 2
  • opt: 2

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

Comment 2

[Meg Viar [:mviar]]

Thanks for flagging, this looks like an intermittent - I'll work on a fix this week.

Comment 3

[Intermittent Failures Robot]

15 failures in 783 pushes (0.019 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 14
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 7
  • opt: 4
  • debug: 3
• linux1804-64-asan-qr: 6
  • opt: 6
• linux1804-64-shippable-qr: 2
  • opt: 2

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

Comment 4

[Intermittent Failures Robot]

17 failures in 664 pushes (0.026 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 16
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 12
  • opt: 4
  • debug: 8
• linux1804-64-asan-qr: 5
  • opt: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1779608&startday=2022-07-21&endday=2022-07-21&tree=all

Comment 5

[Andreea Pavel [:apavel]]

There are 58 total failures in the last 7 days on

• linux1804-64-asan-qr opt
• linux1804-64-qr debug and opt
• linux1804-64-shippable-qr opt
  Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=385250936&repo=autoland&lineNumber=3968

[task 2022-07-24T15:57:13.830Z] 15:57:13     INFO - TEST-START | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js
[task 2022-07-24T15:57:13.853Z] 15:57:13     INFO - GECKO(1622) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fca819db400 == 6 [pid = 1778] [id = 36]
[task 2022-07-24T15:57:13.854Z] 15:57:13     INFO - GECKO(1622) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (7fca81bdbbc0) [pid = 1778] [serial = 110] [outer = 0]
[task 2022-07-24T15:57:13.858Z] 15:57:13     INFO - GECKO(1622) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (7fca819dcc00) [pid = 1778] [serial = 111] [outer = 7fca81bdbbc0]
[task 2022-07-24T15:57:13.905Z] 15:57:13     INFO - GECKO(1622) | [Child 1743, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-24T15:57:13.967Z] 15:57:13     INFO - GECKO(1622) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (7fca819df400) [pid = 1778] [serial = 112] [outer = 7fca81bdbbc0]
[task 2022-07-24T15:57:13.998Z] 15:57:13     INFO - GECKO(1622) | console.debug: AboutWelcomeChild:
[task 2022-07-24T15:57:14.003Z] 15:57:14     INFO - GECKO(1622) |   Received page event DOMDocElementInserted
[task 2022-07-24T15:57:14.107Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.107Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:GET_ATTRIBUTION_DATA
[task 2022-07-24T15:57:14.120Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeChild:
[task 2022-07-24T15:57:14.123Z] 15:57:14     INFO - GECKO(1622) |   Loading about:welcome with aboutwelcome-my-mochitest-experiment-experiment-0.3309975444665354 experiment
[task 2022-07-24T15:57:14.128Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.129Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:NEED_DEFAULT
[task 2022-07-24T15:57:14.131Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.133Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:DOES_APP_NEED_PIN
[task 2022-07-24T15:57:14.215Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.215Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:FXA_METRICS_FLOW_URI
[task 2022-07-24T15:57:14.219Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.219Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-24T15:57:14.222Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.223Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:SET_WELCOME_MESSAGE_SEEN
[task 2022-07-24T15:57:14.234Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.234Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-24T15:57:14.244Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.245Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:GET_REGION
[task 2022-07-24T15:57:14.246Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.248Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:GET_SELECTED_THEME
[task 2022-07-24T15:57:14.252Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.253Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-24T15:57:14.339Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.341Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-24T15:57:14.344Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.346Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:SPECIAL_ACTION
[task 2022-07-24T15:57:14.356Z] 15:57:14     INFO - TEST-INFO | started process screentopng
[task 2022-07-24T15:57:14.747Z] 15:57:14     INFO - TEST-INFO | screentopng: exit 0
[task 2022-07-24T15:57:14.750Z] 15:57:14     INFO - Buffered messages logged at 15:57:13
[task 2022-07-24T15:57:14.752Z] 15:57:14     INFO - Entering test bound test_aboutwelcome_mr_template_telemetry
[task 2022-07-24T15:57:14.760Z] 15:57:14     INFO - Buffered messages logged at 15:57:14
[task 2022-07-24T15:57:14.761Z] 15:57:14     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | 9 Stub was called - 
[task 2022-07-24T15:57:14.765Z] 15:57:14     INFO - Call #0: AWPage:FXA_METRICS_FLOW_URI undefined
[task 2022-07-24T15:57:14.765Z] 15:57:14     INFO - Call #1: AWPage:TELEMETRY_EVENT {"event":"IMPRESSION","event_context":{"domComplete":192,"domInteractive":181,"mountStart":181,"domState":"complete","source":"aboutwelcome-my-mochitest-experiment-experiment-0.3309975444665354-control","page":"about:welcome"},"message_id":"MY-MOCHITEST-EXPERIMENT"}
[task 2022-07-24T15:57:14.765Z] 15:57:14     INFO - Call #2: AWPage:SET_WELCOME_MESSAGE_SEEN "MY-MOCHITEST-EXPERIMENT"
[task 2022-07-24T15:57:14.766Z] 15:57:14     INFO - Call #3: AWPage:TELEMETRY_EVENT {"event":"IMPRESSION","event_context":{"page":"about:welcome"},"message_id":"MY-MOCHITEST-EXPERIMENT_0_AW_STEP1"}
[task 2022-07-24T15:57:14.768Z] 15:57:14     INFO - Call #4: AWPage:GET_REGION undefined
[task 2022-07-24T15:57:14.769Z] 15:57:14     INFO - Call #5: AWPage:GET_SELECTED_THEME undefined
[task 2022-07-24T15:57:14.769Z] 15:57:14     INFO - Call #6: AWPage:TELEMETRY_EVENT {"event":"IMPRESSION","event_context":{"display":"static","importable":0,"page":"about:welcome"},"message_id":"MY-MOCHITEST-EXPERIMENT_SITES"}
[task 2022-07-24T15:57:14.769Z] 15:57:14     INFO - Call #7: AWPage:TELEMETRY_EVENT {"event":"CLICK_BUTTON","event_context":{"source":"secondary_button_top","page":"about:welcome"},"message_id":"MY-MOCHITEST-EXPERIMENT_0_AW_STEP1"}
[task 2022-07-24T15:57:14.774Z] 15:57:14     INFO - Call #8: AWPage:SPECIAL_ACTION {"type":"SHOW_FIREFOX_ACCOUNTS","data":{"entrypoint":"test","extraParams":{"utm_source":"activity-stream","utm_campaign":"firstrun","utm_medium":"referral","utm_term":"aboutwelcome-aboutwelcome-my-mochitest-experiment-experiment-0.3309975444665354-control-screen"}}}
[task 2022-07-24T15:57:14.775Z] 15:57:14     INFO - Buffered messages finished
[task 2022-07-24T15:57:14.775Z] 15:57:14     INFO - TEST-UNEXPECTED-FAIL | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | Telemetry includes MR message id - false == true - JS frame :: chrome://mochitests/content/browser/browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js :: test_aboutwelcome_mr_template_telemetry :: line 49
[task 2022-07-24T15:57:14.775Z] 15:57:14     INFO - Stack trace:
[task 2022-07-24T15:57:14.776Z] 15:57:14     INFO - chrome://mochitests/content/browser/browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js:test_aboutwelcome_mr_template_telemetry:49
[task 2022-07-24T15:57:14.776Z] 15:57:14     INFO - Leaving test bound test_aboutwelcome_mr_template_telemetry
task 2022-07-24T15:57:14.777Z] 15:57:14     INFO - Entering test bound test_aboutwelcome_mr_template_content
[task 2022-07-24T15:57:14.777Z] 15:57:14     INFO - GECKO(1622) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fca819e3800 == 7 [pid = 1778] [id = 37]
[task 2022-07-24T15:57:14.778Z] 15:57:14     INFO - GECKO(1622) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (7fca81bdc870) [pid = 1778] [serial = 113] [outer = 0]
[task 2022-07-24T15:57:14.778Z] 15:57:14     INFO - GECKO(1622) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (7fca827be000) [pid = 1778] [serial = 114] [outer = 7fca81bdc870]
[task 2022-07-24T15:57:14.778Z] 15:57:14     INFO - GECKO(1622) | [Child 1778, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-24T15:57:14.779Z] 15:57:14     INFO - GECKO(1622) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (7fca827bfc00) [pid = 1778] [serial = 115] [outer = 7fca81bdc870]
[task 2022-07-24T15:57:14.779Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeChild:
[task 2022-07-24T15:57:14.779Z] 15:57:14     INFO - GECKO(1622) |   Received page event DOMDocElementInserted
[task 2022-07-24T15:57:14.780Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.780Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:GET_ATTRIBUTION_DATA
[task 2022-07-24T15:57:14.782Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeChild:
[task 2022-07-24T15:57:14.782Z] 15:57:14     INFO - GECKO(1622) |   Loading about:welcome with no experiment
[task 2022-07-24T15:57:14.782Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.783Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:NEED_DEFAULT
[task 2022-07-24T15:57:14.828Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.829Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:DOES_APP_NEED_PIN
[task 2022-07-24T15:57:14.858Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.859Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:FXA_METRICS_FLOW_URI
[task 2022-07-24T15:57:14.863Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.864Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-24T15:57:14.865Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.865Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:SET_WELCOME_MESSAGE_SEEN
[task 2022-07-24T15:57:14.875Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.877Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-24T15:57:14.878Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.883Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:GET_REGION
[task 2022-07-24T15:57:14.891Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.891Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:GET_SELECTED_THEME
[task 2022-07-24T15:57:14.910Z] 15:57:14     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:14.911Z] 15:57:14     INFO - GECKO(1622) |   Received content event: AWPage:TELEMETRY_EVENT
[task 2022-07-24T15:57:14.978Z] 15:57:14     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | Navigated to a welcome screen - "about:welcome" == "about:welcome" - 
[task 2022-07-24T15:57:14.981Z] 15:57:14     INFO - Leaving test bound test_aboutwelcome_mr_template_content
[task 2022-07-24T15:57:15.004Z] 15:57:15     INFO - GECKO(1622) | [Parent 1622, Main Thread] WARNING: 'aOwner->IsDiscarded()', file /builds/worker/workspace/obj-build/dist/include/mozilla/dom/SyncedContextInlines.h:95
[task 2022-07-24T15:57:15.040Z] 15:57:15     INFO - GECKO(1622) | [Child 1778, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-24T15:57:15.084Z] 15:57:15     INFO - GECKO(1622) | [Parent 1622, Main Thread] WARNING: 'aOwner->IsDiscarded()', file /builds/worker/workspace/obj-build/dist/include/mozilla/dom/SyncedContextInlines.h:95
[task 2022-07-24T15:57:15.095Z] 15:57:15     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:15.096Z] 15:57:15     INFO - GECKO(1622) |   Terminate reason is welcome-tab-closed
[task 2022-07-24T15:57:15.135Z] 15:57:15     INFO - GECKO(1622) | console.debug: AboutWelcomeParent:
[task 2022-07-24T15:57:15.137Z] 15:57:15     INFO - GECKO(1622) |   Terminate reason is welcome-tab-closed
[task 2022-07-24T15:57:15.158Z] 15:57:15     INFO - GECKO(1622) | MEMORY STAT | vsize 11392MB | residentFast 497MB | heapAllocated 235MB
[task 2022-07-24T15:57:15.161Z] 15:57:15     INFO - TEST-OK | browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | took 1330ms
[task 2022-07-24T15:57:15.180Z] 15:57:15     INFO - GECKO(1622) | [Child 1809: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fc1bc34a800 == 2 [pid = 1809] [id = 3]
[task 2022-07-24T15:57:15.182Z] 15:57:15     INFO - GECKO(1622) | [Child 1809: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7fc1d7592130) [pid = 1809] [serial = 7] [outer = 0]
[task 2022-07-24T15:57:15.185Z] 15:57:15     INFO - GECKO(1622) | [Child 1809: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7fc1bc34ac00) [pid = 1809] [serial = 8] [outer = 7fc1d7592130]
[task 2022-07-24T15:57:15.207Z] 15:57:15     INFO - GECKO(1622) | [Child 1743, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-24T15:57:15.225Z] 15:57:15     INFO - checking window state

Meg, are there any updates here?

Comment 6

[Intermittent Failures Robot]

58 failures in 3497 pushes (0.017 failures/push) were associated with this bug in the last 7 days.

This is the #15 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 51
• try: 3
• mozilla-central: 4

Platform and build breakdown:

• linux1804-64-qr: 36
  • debug: 23
  • opt: 13
• linux1804-64-asan-qr: 17
  • opt: 17
• linux1804-64-shippable-qr: 5
  • opt: 5

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

Comment 7

[Meg Viar [:mviar]]

Attachment: Bug 1779608 - Intermittent browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | single tracking bug

Comment 8

[Meg Viar [:mviar]]

Hi Andreea, thanks for the nudge. Looking at the failure log, it appears the test is sometimes resulting in a click on the wrong button. It's calling the secondary_button_top to sign in rather than the secondary call to action button. I've added a patch updating the test with a more specific selector.

Comment 9

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/e9e62a618af6
Intermittent browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js | single tracking bug r=emcminn

Comment 10

[Atila Butkovits]

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

Comment 11

[Cristian Tuns]

This is still happening on autoland: https://treeherder.mozilla.org/logviewer?job_id=385585880&repo=autoland
Can you please take a look?

Comment 12

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

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

Comment 13

[Meg Viar [:mviar]]

We're making an update in this patch that I believe will address the issue. I'll update this bug once that patch lands.

Comment 14

[Intermittent Failures Robot]

34 failures in 3245 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #35 most frequent failure this week.

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

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

Repository breakdown:

• mozilla-central: 9
• autoland: 23
• mozilla-beta: 1
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 22
  • debug: 15
  • opt: 7
• linux1804-64-asan-qr: 5
  • opt: 5
• windows7-32-shippable-qr: 1
  • opt: 1
• linux1804-64-shippable-qr: 4
  • opt: 4
• windows7-32-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1779608&startday=2022-07-25&endday=2022-07-31&tree=all

Comment 15

[Meg Viar [:mviar]]

Bug 1776863 landed today and includes updates to browser/components/newtab/test/browser/browser_aboutwelcome_multistage_mr.js that should address the intermittent failures. I can monitor for the rest of the week and mark resolved if we don't see further failures.

Comment 16

[Intermittent Failures Robot]

7 failures in 3057 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5
• try: 1
• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-qr: 6
  • debug: 5
  • opt: 1
• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1779608&startday=2022-08-01&endday=2022-08-07&tree=all

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1779608&startday=2022-08-08&endday=2022-08-14&tree=all

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 2

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1
• linux1804-64-devedition-qr: 1
  • opt: 1

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