1773806 - Intermittent dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | Focusing failed to complete before mouseup

Status: RESOLVED FIXED

(Core :: DOM: UI Events & Focus Handling, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-06-11T03:25:40.816Z] 03:25:40     INFO - TEST-START | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html
[task 2022-06-11T03:25:41.011Z] 03:25:41     INFO - GECKO(1612) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-06-11T03:25:41.028Z] 03:25:41     INFO - GECKO(1612) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-06-11T03:25:41.031Z] 03:25:41     INFO - GECKO(1612) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-06-11T03:25:41.032Z] 03:25:41     INFO - GECKO(1612) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-06-11T03:25:41.035Z] 03:25:41     INFO - GECKO(1612) | WaitUntilApzStable: flushed APZ repaints in parent proc, waiting for callback...
[task 2022-06-11T03:25:41.036Z] 03:25:41     INFO - GECKO(1612) | WaitUntilApzStable: APZ flush done in parent proc
[task 2022-06-11T03:25:41.036Z] 03:25:41     INFO - GECKO(1612) | WaitUntilApzStable: got apz-flush-done in child proc
[task 2022-06-11T03:25:41.036Z] 03:25:41     INFO - GECKO(1612) | WaitUntilApzStable: done promiseFocus
[task 2022-06-11T03:25:41.037Z] 03:25:41     INFO - GECKO(1612) | WaitUntilApzStable: done promiseAllPaintsDone
[task 2022-06-11T03:25:41.038Z] 03:25:41     INFO - GECKO(1612) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-06-11T03:25:41.038Z] 03:25:41     INFO - GECKO(1612) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-06-11T03:25:41.039Z] 03:25:41     INFO - GECKO(1612) | WaitUntilApzStable: all done
[task 2022-06-11T03:25:41.051Z] 03:25:41     INFO - TEST-INFO | started process screenshot
[task 2022-06-11T03:25:41.122Z] 03:25:41     INFO - TEST-INFO | screenshot: exit 0
[task 2022-06-11T03:25:41.131Z] 03:25:41     INFO - Buffered messages logged at 03:25:40
[task 2022-06-11T03:25:41.131Z] 03:25:41     INFO - add_task | Entering test 
[task 2022-06-11T03:25:41.132Z] 03:25:41     INFO - receive middleready
[task 2022-06-11T03:25:41.132Z] 03:25:41     INFO - Buffered messages logged at 03:25:41
[task 2022-06-11T03:25:41.132Z] 03:25:41     INFO - receive innerready
[task 2022-06-11T03:25:41.133Z] 03:25:41     INFO - receive innerclick
[task 2022-06-11T03:25:41.133Z] 03:25:41     INFO - Buffered messages finished
[task 2022-06-11T03:25:41.133Z] 03:25:41     INFO - TEST-UNEXPECTED-FAIL | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | Focusing failed to complete before mouseup 
[task 2022-06-11T03:25:41.134Z] 03:25:41     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-06-11T03:25:41.134Z] 03:25:41     INFO -     listener@dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html:66:13
[task 2022-06-11T03:25:41.134Z] 03:25:41     INFO - receive innerfocus
[task 2022-06-11T03:25:41.135Z] 03:25:41     INFO - TEST-PASS | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | The second event should be 'innerfocus' 
[task 2022-06-11T03:25:41.135Z] 03:25:41     INFO - TEST-PASS | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | A valid string reason is expected 
[task 2022-06-11T03:25:41.135Z] 03:25:41     INFO - TEST-PASS | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | Reason cannot be empty 
[task 2022-06-11T03:25:41.136Z] 03:25:41     INFO - receive middlefocus
[task 2022-06-11T03:25:41.136Z] 03:25:41     INFO - TEST-PASS | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | The third event should be 'middlefocus'. 
[task 2022-06-11T03:25:41.137Z] 03:25:41     INFO - receive middleclick
[task 2022-06-11T03:25:41.137Z] 03:25:41     INFO - receive innerblur
[task 2022-06-11T03:25:41.137Z] 03:25:41     INFO - TEST-PASS | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | The fifth event should be 'innerblur'. 
[task 2022-06-11T03:25:41.138Z] 03:25:41     INFO - TEST-FAIL | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | The author of the test has indicated that flaky timeouts are expected.  Reason: Waiting for unwanted events that don't exist on success. 
[task 2022-06-11T03:25:41.280Z] 03:25:41     INFO - add_task | Leaving test 
[task 2022-06-11T03:25:41.287Z] 03:25:41     INFO - GECKO(1612) | MEMORY STAT | vsize 2103694MB | vsizeMaxContiguous 64969614MB | residentFast 97MB | heapAllocated 13MB
[task 2022-06-11T03:25:41.290Z] 03:25:41     INFO - TEST-OK | dom/events/test/test_focus_blur_on_click_in_deep_cross_origin_iframe.html | took 475ms
[task 2022-06-11T03:25:41.303Z] 03:25:41     INFO - TEST-START | dom/events/test/test_hover_mouseleave.html

Comment 1

[Marian-Vasile Laza]

Backfill range and retriggers - backfill-task doesn't start lower than this: https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=windows%2C10%2Cx64%2C2004%2Cwebrender%2Copt%2Cmochitests%2Cwith%2Ca%2Cconditioned%2Cprofile%2Cand%2Cfission%2Cenabled%2Ctest-windows10-64-2004-qr%2Fopt-mochitest-plain-condprof-fis-e10s%2C3&group_state=expanded&tochange=a419f8030e7556aa789dc5dfaa43a2ff9f6ad883&fromchange=e52a65341944b5ca1079f6f56d6f8bff617deee3&selectedTaskRun=OzJx9V5ZSrusluHVmGoC4Q.0

Comment 2

[Intermittent Failures Robot]

10 failures in 3336 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 10

Platform and build breakdown:

• windows10-64-2004-qr: 6
  • opt: 6
• windows10-64-2004-shippable-qr: 3
  • opt: 3
• linux1804-64-qr: 1
  • opt: 1

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

Comment 3

[Intermittent Failures Robot]

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

This is the #29 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: 30
• mozilla-central: 4

Platform and build breakdown:

• windows10-64-2004-qr: 17
  • opt: 17
• windows10-64-2004-shippable-qr: 13
  • opt: 13
• linux1804-64-qr: 3
  • opt: 3
• linux1804-64-shippable-qr: 1
  • opt: 1

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

Comment 4

[Joel Maher ( :jmaher ) (UTC -8)]

Attachment: Bug 1773806 - skip test_focus_blur_on_click_in_deep_cross_origin_iframe.html on windows with conditioned profile. r=aryx!

Comment 5

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/e82072c95be6
skip test_focus_blur_on_click_in_deep_cross_origin_iframe.html on windows with conditioned profile. r=aryx

Comment 6

[Norisz Fay [:noriszfay]]

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

Comment 7

[Cristian Tuns]

This is still happening on Linux 18.04 x64 WebRender Shippable : https://treeherder.mozilla.org/logviewer?job_id=382485829&repo=mozilla-central

Comment 8

[Intermittent Failures Robot]

11 failures in 3075 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 4

Platform and build breakdown:

• windows10-64-2004-qr: 4
  • opt: 4
• windows10-64-2004-shippable-qr: 4
  • opt: 4
• linux1804-64-shippable-qr: 3
  • opt: 3

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