1882067 - Intermittent browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | single tracking bug

Status: RESOLVED FIXED

(Firefox :: Messaging System, defect, P1)

Description

[Treeherder Bug Filer]

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

---

[task 2024-02-26T11:59:24.491Z] 11:59:24     INFO - TEST-START | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js
[task 2024-02-26T11:59:25.151Z] 11:59:25     INFO - GECKO(8012) | type=http, flags=0
[task 2024-02-26T11:59:30.771Z] 11:59:30     INFO - TEST-INFO | started process screenshot
[task 2024-02-26T11:59:30.994Z] 11:59:30     INFO - TEST-INFO | screenshot: exit 0
[task 2024-02-26T11:59:30.994Z] 11:59:30     INFO - Buffered messages logged at 11:59:24
[task 2024-02-26T11:59:30.995Z] 11:59:30     INFO - Entering test bound test_About_Welcome_Tab_Close
[task 2024-02-26T11:59:30.995Z] 11:59:30     INFO - TEST-PASS | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | Active window is not null - [object Window] == true - 
[task 2024-02-26T11:59:30.996Z] 11:59:30     INFO - TEST-PASS | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | AboutWelcomeObserver is not null - [object Object] == true - 
[task 2024-02-26T11:59:30.997Z] 11:59:30     INFO - TEST-PASS | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | Terminated due to tab closed - "welcome-tab-closed" == "welcome-tab-closed" - 
[task 2024-02-26T11:59:30.997Z] 11:59:30     INFO - Leaving test bound test_About_Welcome_Tab_Close
[task 2024-02-26T11:59:30.998Z] 11:59:30     INFO - Entering test bound test_About_Welcome_Location_Change
[task 2024-02-26T11:59:30.998Z] 11:59:30     INFO - Buffered messages logged at 11:59:25
[task 2024-02-26T11:59:30.999Z] 11:59:30     INFO - TEST-PASS | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | AboutWelcomeObserver is not null - [object Object] == true - 
[task 2024-02-26T11:59:31.000Z] 11:59:31     INFO - TEST-PASS | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | Terminated due to location uri changed - "address-bar-navigated" == "address-bar-navigated" - 
[task 2024-02-26T11:59:31.000Z] 11:59:31     INFO - Leaving test bound test_About_Welcome_Location_Change
[task 2024-02-26T11:59:31.000Z] 11:59:31     INFO - Buffered messages finished
[task 2024-02-26T11:59:31.003Z] 11:59:31     INFO - TEST-UNEXPECTED-FAIL | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | waiting for vsync to be disabled - timed out after 50 tries. - false == true - {"filename":"chrome://mochikit/content/browser-test.js","name":"ensureVsyncDisabled","sourceId":576,"lineNumber":607,"columnNumber":19,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest","sourceId":576,"lineNumber":776,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"testScope/test_finish/<","sourceId":576,"lineNumber":1806,"columnNumber":25,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"run","sourceId":576,"lineNumber":1726,"columnNumber":9,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"run@chrome://mochikit/content/browser-test.js:1726:9\n","nativeSavedFrame":{}},"formattedStack":"async*testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1806:25\nrun@chrome://mochikit/content/browser-test.js:1726:9\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"async*nextTest@chrome://mochikit/content/browser-test.js:776:18\nasync*testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1806:25\nrun@chrome://mochikit/content/browser-test.js:1726:9\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"ensureVsyncDisabled@chrome://mochikit/content/browser-test.js:607:19\nasync*nextTest@chrome://mochikit/content/browser-test.js:776:18\nasync*testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1806:25\nrun@chrome://mochikit/content/browser-test.js:1726:9\n","nativeSavedFrame":{}}
[task 2024-02-26T11:59:31.004Z] 11:59:31     INFO - Stack trace:
[task 2024-02-26T11:59:31.004Z] 11:59:31     INFO - chrome://mochikit/content/browser-test.js:ensureVsyncDisabled:607
[task 2024-02-26T11:59:31.004Z] 11:59:31     INFO - chrome://mochikit/content/browser-test.js:nextTest:776
[task 2024-02-26T11:59:31.004Z] 11:59:31     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1806
[task 2024-02-26T11:59:31.004Z] 11:59:31     INFO - chrome://mochikit/content/browser-test.js:run:1726
[task 2024-02-26T11:59:31.005Z] 11:59:31     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-02-26T11:59:31.008Z] 11:59:31     INFO - TEST-UNEXPECTED-FAIL | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | vsync remained enabled at the end of the test. Is there an animation still running? Consider talking to the performance team for tips to solve this. - false == true - {"filename":"chrome://mochikit/content/browser-test.js","name":"ensureVsyncDisabled","sourceId":576,"lineNumber":608,"columnNumber":19,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest","sourceId":576,"lineNumber":776,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"testScope/test_finish/<","sourceId":576,"lineNumber":1806,"columnNumber":25,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"run","sourceId":576,"lineNumber":1726,"columnNumber":9,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"run@chrome://mochikit/content/browser-test.js:1726:9\n","nativeSavedFrame":{}},"formattedStack":"async*testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1806:25\nrun@chrome://mochikit/content/browser-test.js:1726:9\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"async*nextTest@chrome://mochikit/content/browser-test.js:776:18\nasync*testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1806:25\nrun@chrome://mochikit/content/browser-test.js:1726:9\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"ensureVsyncDisabled@chrome://mochikit/content/browser-test.js:608:19\nasync*nextTest@chrome://mochikit/content/browser-test.js:776:18\nasync*testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1806:25\nrun@chrome://mochikit/content/browser-test.js:1726:9\n","nativeSavedFrame":{}}
[task 2024-02-26T11:59:31.008Z] 11:59:31     INFO - Stack trace:
[task 2024-02-26T11:59:31.008Z] 11:59:31     INFO - chrome://mochikit/content/browser-test.js:ensureVsyncDisabled:608
[task 2024-02-26T11:59:31.008Z] 11:59:31     INFO - chrome://mochikit/content/browser-test.js:nextTest:776
[task 2024-02-26T11:59:31.008Z] 11:59:31     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1806
[task 2024-02-26T11:59:31.008Z] 11:59:31     INFO - chrome://mochikit/content/browser-test.js:run:1726
[task 2024-02-26T11:59:31.009Z] 11:59:31     INFO - GECKO(8012) | MEMORY STAT | vsize 2112493MB | vsizeMaxContiguous 67469646MB | residentFast 313MB | heapAllocated 133MB
[task 2024-02-26T11:59:31.009Z] 11:59:31     INFO - TEST-OK | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_observer.js | took 6294ms
[task 2024-02-26T11:59:31.010Z] 11:59:31     INFO - checking window state
[task 2024-02-26T11:59:31.010Z] 11:59:31     INFO - TEST-START | browser/components/aboutwelcome/tests/browser/browser_aboutwelcome_rtamo.js

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2
• try: 1

Platform and build breakdown:

• windows11-64-2009-shippable-qr: 3
  • opt: 3

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

Comment 2

[Punam Dahiya [:pdahiya]]

Attachment: Bug 1882067 - Fix intermittent test failure due to enabled vsync

Comment 4

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/5e1f89a51b11
Fix intermittent test failure due to enabled vsync r=negin,omc-reviewers

Comment 5

[Cristina Horotan [:chorotan]]

https://hg.mozilla.org/mozilla-central/rev/5e1f89a51b11

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows11-64-2009-devedition-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1882067&startday=2024-03-04&endday=2024-03-10&tree=all