1716810 - Intermittent Fission [Tier2] dom/events/test/test_deltaMode_lines_always_enabled.html | Test timed out. -

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

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

---

[task 2021-06-16T14:46:45.013Z] 14:46:45     INFO - TEST-START | dom/events/test/test_deltaMode_lines_always_enabled.html
[task 2021-06-16T14:46:45.026Z] 14:46:45     INFO - GECKO(1495) | [Child 1503, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-06-16T14:46:45.028Z] 14:46:45     INFO - GECKO(1495) | [Child 1649, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-06-16T14:46:45.033Z] 14:46:45     INFO - GECKO(1495) | [Child 1503, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-06-16T14:46:45.039Z] 14:46:45     INFO - GECKO(1495) | [Child 1649, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-06-16T14:46:45.040Z] 14:46:45     INFO - GECKO(1495) | [Child 1503, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-06-16T14:46:45.041Z] 14:46:45     INFO - GECKO(1495) | [Parent 1495, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2931
[task 2021-06-16T14:46:45.131Z] 14:46:45     INFO - GECKO(1495) | [Child 1503, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-06-16T14:46:45.910Z] 14:46:45     INFO - GECKO(1495) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/ly/p9f8v1392v3f7m_0t2jx899c000014/T/tmpci3hbqra.mozrunner/runtests_leaks_tab_pid1656.log
[task 2021-06-16T14:46:45.910Z] 14:46:45     INFO - GECKO(1495) | [1656, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:254
[task 2021-06-16T14:46:45.951Z] 14:46:45     INFO - GECKO(1495) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/ly/p9f8v1392v3f7m_0t2jx899c000014/T/tmpci3hbqra.mozrunner/runtests_leaks_tab_pid1657.log
[task 2021-06-16T14:46:45.951Z] 14:46:45     INFO - GECKO(1495) | [1657, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:254
[task 2021-06-16T14:51:46.589Z] 14:51:46     INFO - TEST-INFO | started process screencapture
[task 2021-06-16T14:51:46.707Z] 14:51:46     INFO - TEST-INFO | screencapture: exit 0
[task 2021-06-16T14:51:46.707Z] 14:51:46     INFO - Buffered messages logged at 14:46:45
[task 2021-06-16T14:51:46.707Z] 14:51:46     INFO - must wait for load
[task 2021-06-16T14:51:46.707Z] 14:51:46     INFO - Buffered messages finished
[task 2021-06-16T14:51:46.708Z] 14:51:46     INFO - TEST-UNEXPECTED-FAIL | dom/events/test/test_deltaMode_lines_always_enabled.html | Test timed out. - 
[task 2021-06-16T14:51:47.747Z] 14:51:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T14:51:47.747Z] 14:51:47     INFO - TEST-UNEXPECTED-FAIL | dom/events/test/test_deltaMode_lines_always_enabled.html | [SimpleTest.finish()] No checks actually run. (You need to call ok(), is(), or similar functions at least once.  Make sure you use SimpleTest.waitForExplicitFinish() if you need it.) 
[task 2021-06-16T14:51:47.748Z] 14:51:47     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-06-16T14:51:47.748Z] 14:51:47     INFO -     afterCleanup@SimpleTest/SimpleTest.js:1423:18
[task 2021-06-16T14:51:47.748Z] 14:51:47     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1488:7
[task 2021-06-16T14:51:47.748Z] 14:51:47     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1508:3
[task 2021-06-16T14:51:47.748Z] 14:51:47     INFO -     @SimpleTest/SimpleTest.js:2148:16
[task 2021-06-16T14:51:47.760Z] 14:51:47     INFO - GECKO(1495) | MEMORY STAT | vsize 6635MB | residentFast 88MB | heapAllocated 7MB
[task 2021-06-16T14:51:47.774Z] 14:51:47     INFO - TEST-OK | dom/events/test/test_deltaMode_lines_always_enabled.html | took 302761ms

Comment 1

[Chris Peterson [:cpeterson]]

I suspect these failures are all Fission. Note that this test is now skipped for opt builds in fission+xorigin mode, so we'll see fewer failure reports until this bug is fixed.

[test_deltaMode_lines_always_enabled.html]
skip-if =
  fission && xorigin && !debug  # Bug 1716405 - New fission platform triage

https://searchfox.org/mozilla-central/rev/5e955a47c4af398e2a859b34056017764e7a2252/dom/events/test/mochitest.ini#269-271

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-central: 2

Platform and build breakdown:

• macosx1015-64-qr: 4
  • debug: 4
• windows10-64-qr: 3
  • debug: 3

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-central: 1

Platform and build breakdown:

• macosx1015-64-qr: 2
  • debug: 2
• windows10-64-qr: 4
  • debug: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1716810&startday=2021-06-21&endday=2021-06-27&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-central: 1

Platform and build breakdown:

• windows10-64-qr: 3
  • debug: 3
• macosx1015-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1716810&startday=2021-06-28&endday=2021-07-04&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 5
• autoland: 5

Platform and build breakdown:

• macosx1015-64-qr: 6
  • debug: 6
• windows10-64-qr: 4
  • debug: 4

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

Comment 6

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)(sick)]

Attachment: Bug 1716810 - Make `test_deltaMode_lines_always_enabled.html` wait to first user input via the parent process r=smaug!

synthesizeWheel dispatches a wheel event via APZ.

• https://searchfox.org/mozilla-central/rev/b79212b4fc017f27ac2435a658d4e9b9798efa86/dom/base/nsDOMWindowUtils.cpp#755,789
• https://searchfox.org/mozilla-central/rev/b79212b4fc017f27ac2435a658d4e9b9798efa86/widget/PuppetWidget.cpp#383,386,396-397

So, it's important that the <iframe>'s layout information is registered in
the parent process. Otherwise, the wheel event will be dispatched on the
<iframe> in the parent document instead.

Therefore, the test needs to wait a synthesized event via the parent process
before using synthesizeWheel.

Depends on D119596

Comment 7

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/1fd95898de33
Make `test_deltaMode_lines_always_enabled.html` wait to first user input via the parent process r=smaug

Comment 8

[Dorel Luca [:dluca]]

https://hg.mozilla.org/mozilla-central/rev/1fd95898de33

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1
• macosx1015-64-qr: 1
  • debug: 1

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