1578604 - Intermittent accessible/tests/mochitest/actions/test_general.xul | Unique type focus event was handled.

Status: RESOLVED FIXED

(Core :: Disability Access APIs, defect, P2)

Description

[Treeherder Bug Filer]

Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=264849442&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/P3cq2DFnTwiw0QqTkkod1w/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://queue.taskcluster.net/v1/task/P3cq2DFnTwiw0QqTkkod1w/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2019-09-04T00:20:28.928Z] 00:20:28 INFO - TEST-START | accessible/tests/mochitest/actions/test_general.xul
[task 2019-09-04T00:20:28.947Z] 00:20:28 INFO - GECKO(2072) | ++DOMWINDOW == 40 (2625CC00) [pid = 1220] [serial = 40] [outer = 26B50040]
[task 2019-09-04T00:20:29.948Z] 00:20:29 INFO - GECKO(2072) | ++DOMWINDOW == 41 (26251400) [pid = 1220] [serial = 41] [outer = 26B50040]
[task 2019-09-04T00:20:29.963Z] 00:20:29 INFO - GECKO(2072) | Chrome file doesn't exist: Z:\task_1567552811\build\tests\mochitest\a11y\accessible\tests\mochitest\nsIAccessible_name.css
[task 2019-09-04T00:20:30.038Z] 00:20:30 INFO - GECKO(2072) | [1220, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/dom/base/nsContentUtils.cpp, line 3683
[task 2019-09-04T00:20:30.465Z] 00:20:30 INFO - GECKO(2072) | [1220, Main Thread] WARNING: Must complete empty transaction when compositing!: file z:/build/build/src/layout/base/PresShell.cpp, line 6030
[task 2019-09-04T00:20:30.520Z] 00:20:30 INFO - GECKO(2072) | [1220, Main Thread] WARNING: GetElementById had some kind of spasm.: file z:/build/build/src/dom/xul/nsXULPopupListener.cpp, line 335
[task 2019-09-04T00:20:30.527Z] 00:20:30 INFO - TEST-INFO | started process screenshot
[task 2019-09-04T00:20:30.594Z] 00:20:30 INFO - TEST-INFO | screenshot: exit 0
[task 2019-09-04T00:20:30.594Z] 00:20:30 INFO - Buffered messages logged at 00:20:30
[task 2019-09-04T00:20:30.595Z] 00:20:30 INFO - must wait for load
[task 2019-09-04T00:20:30.595Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | label text should have 1 action
[task 2019-09-04T00:20:30.596Z] 00:20:30 INFO - Invoke the 'invoke an action click at index undefined on 'menu' ' test { scenario #0: expected 'mousedown' event; expected 'mouseup' event; expected 'click' event; expected 'focus' event; }
[task 2019-09-04T00:20:30.596Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'menu'
[task 2019-09-04T00:20:30.596Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'menu'
[task 2019-09-04T00:20:30.596Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mousedown event handling' succeed. Event 'mousedown' was handled.
[task 2019-09-04T00:20:30.597Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mouseup event handling' succeed. Event 'mouseup' was handled.
[task 2019-09-04T00:20:30.597Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'click event handling' succeed. Event 'click' was handled.
[task 2019-09-04T00:20:30.597Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'invoke an action click at index undefined on 'menu' ' succeed. Event 'focus' was handled.
[task 2019-09-04T00:20:30.598Z] 00:20:30 INFO - Invoke the 'invoke an action click at index undefined on 'submenu' ' test { scenario #0: expected 'mousedown' event; expected 'mouseup' event; expected 'click' event; }
[task 2019-09-04T00:20:30.598Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'submenu'
[task 2019-09-04T00:20:30.599Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'submenu'
[task 2019-09-04T00:20:30.599Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mousedown event handling' succeed. Event 'mousedown' was handled.
[task 2019-09-04T00:20:30.599Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mouseup event handling' succeed. Event 'mouseup' was handled.
[task 2019-09-04T00:20:30.600Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'click event handling' succeed. Event 'click' was handled.
[task 2019-09-04T00:20:30.600Z] 00:20:30 INFO - Invoke the 'invoke an action click at index undefined on 'menuitem' ' test { scenario #0: expected 'mousedown' event; expected 'mouseup' event; expected 'click' event; expected 'command' event; }
[task 2019-09-04T00:20:30.600Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'menuitem'
[task 2019-09-04T00:20:30.601Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'menuitem'
[task 2019-09-04T00:20:30.601Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mousedown event handling' succeed. Event 'mousedown' was handled.
[task 2019-09-04T00:20:30.602Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mouseup event handling' succeed. Event 'mouseup' was handled.
[task 2019-09-04T00:20:30.602Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'click event handling' succeed. Event 'click' was handled.
[task 2019-09-04T00:20:30.602Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'command event handling' succeed. Event 'command' was handled.
[task 2019-09-04T00:20:30.602Z] 00:20:30 INFO - Invoke the 'invoke an action press at index undefined on 'button' ' test { scenario #0: expected 'mousedown' event; expected 'mouseup' event; expected 'click' event; expected 'command' event; }
[task 2019-09-04T00:20:30.603Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'button'
[task 2019-09-04T00:20:30.603Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'button'
[task 2019-09-04T00:20:30.604Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mousedown event handling' succeed. Event 'mousedown' was handled.
[task 2019-09-04T00:20:30.604Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mouseup event handling' succeed. Event 'mouseup' was handled.
[task 2019-09-04T00:20:30.605Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'click event handling' succeed. Event 'click' was handled.
[task 2019-09-04T00:20:30.605Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'command event handling' succeed. Event 'command' was handled.
[task 2019-09-04T00:20:30.605Z] 00:20:30 INFO - Invoke the 'invoke an action press at index undefined on 'buttonmenu' ' test { scenario #0: expected 'mousedown' event; expected 'mouseup' event; expected 'click' event; }
[task 2019-09-04T00:20:30.605Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'buttonmenu'
[task 2019-09-04T00:20:30.606Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'buttonmenu'
[task 2019-09-04T00:20:30.606Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mousedown event handling' succeed. Event 'mousedown' was handled.
[task 2019-09-04T00:20:30.606Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mouseup event handling' succeed. Event 'mouseup' was handled.
[task 2019-09-04T00:20:30.607Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'click event handling' succeed. Event 'click' was handled.
[task 2019-09-04T00:20:30.607Z] 00:20:30 INFO - Invoke the 'invoke an action click at index undefined on 'name_entry_label' ' test { scenario #0: expected 'mousedown' event; expected 'mouseup' event; expected 'click' event; }
[task 2019-09-04T00:20:30.607Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'name_entry_label'
[task 2019-09-04T00:20:30.608Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'name_entry_label'
[task 2019-09-04T00:20:30.608Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mousedown event handling' succeed. Event 'mousedown' was handled.
[task 2019-09-04T00:20:30.608Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mouseup event handling' succeed. Event 'mouseup' was handled.
[task 2019-09-04T00:20:30.609Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'click event handling' succeed. Event 'click' was handled.
[task 2019-09-04T00:20:30.609Z] 00:20:30 INFO - Invoke the 'invoke an action click at index undefined on 'labelWithPopup' ' test { scenario #0: expected 'mousedown' event; expected 'mouseup' event; expected 'click' event; }
[task 2019-09-04T00:20:30.609Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'labelWithPopup'
[task 2019-09-04T00:20:30.609Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'labelWithPopup'
[task 2019-09-04T00:20:30.610Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mousedown event handling' succeed. Event 'mousedown' was handled.
[task 2019-09-04T00:20:30.610Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mouseup event handling' succeed. Event 'mouseup' was handled.
[task 2019-09-04T00:20:30.610Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'click event handling' succeed. Event 'click' was handled.
[task 2019-09-04T00:20:30.611Z] 00:20:30 INFO - Invoke the 'invoke an action click at index undefined on 'toolbarbutton_label' ' test { scenario #0: expected 'mousedown' event; expected 'mouseup' event; expected 'click' event; expected 'command' event; }
[task 2019-09-04T00:20:30.611Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'toolbarbutton_label'
[task 2019-09-04T00:20:30.612Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'toolbarbutton_label'
[task 2019-09-04T00:20:30.612Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mousedown event handling' succeed. Event 'mousedown' was handled.
[task 2019-09-04T00:20:30.612Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'mouseup event handling' succeed. Event 'mouseup' was handled.
[task 2019-09-04T00:20:30.613Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'click event handling' succeed. Event 'click' was handled.
[task 2019-09-04T00:20:30.613Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'command event handling' succeed. Event 'command' was handled.
[task 2019-09-04T00:20:30.613Z] 00:20:30 INFO - Invoke the 'invoke an action click at index undefined on 'menulist_label' ' test { scenario #0: expected 'focus' event; }
[task 2019-09-04T00:20:30.613Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | No actions on the accessible for 'menulist_label'
[task 2019-09-04T00:20:30.613Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Wrong action name of the accessible for 'menulist_label'
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - Buffered messages finished
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - TEST-UNEXPECTED-FAIL | accessible/tests/mochitest/actions/test_general.xul | Unique type focus event was handled.
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - SimpleTest.ok@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:277:18
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - eventQueue_handleEvent@chrome://mochitests/content/a11y/accessible/tests/mochitest/events.js:581:15
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - observe@chrome://mochitests/content/a11y/accessible/tests/mochitest/events.js:2372:29
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | wrong state bits for ['menulist@id="menulist" node', address: [object XULMenuElement], role: combobox, name: 'menulist', address: 0x26d73a60]!
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Focussed ['menulist@id="menulist" node', address: [object XULMenuElement], role: combobox, name: 'menulist', address: 0x26d73a60] must be focusable!
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Collapsed or expanded ['menulist@id="menulist" node', address: [object XULMenuElement], role: combobox, name: 'menulist', address: 0x26d73a60] must be expandable!
[task 2019-09-04T00:20:30.614Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Collapsed ['menulist@id="menulist" node', address: [object XULMenuElement], role: combobox, name: 'menulist', address: 0x26d73a60] cannot be expanded!
[task 2019-09-04T00:20:30.615Z] 00:20:30 INFO - TEST-PASS | accessible/tests/mochitest/actions/test_general.xul | Test with ID = 'invoke an action click at index undefined on 'menulist_label' ' succeed. Event 'focus' was handled.
[task 2019-09-04T00:20:30.615Z] 00:20:30 INFO - GECKO(2072) | MEMORY STAT | vsize 823MB | vsizeMaxContiguous 730MB | residentFast 296MB | heapAllocated 146MB
[task 2019-09-04T00:20:30.615Z] 00:20:30 INFO - TEST-OK | accessible/tests/mochitest/actions/test_general.xul | took 1653ms
[task 2019-09-04T00:20:30.615Z] 00:20:30 INFO - TEST-START | Shutdown

Comment 1

[Noemi Erli[:noemi_erli]]

This is a regression from Bug1578140

Comment 2

[James Teh [:Jamie]]

Attachment: Bug 1578604: accessible/tests/mochitest/actions/test_general.xul: Don't require the focus event on menulist to be unique.

focusChecker expects a unique focus event.
However, there might still be pending focus events not caught by previous tests.
Therefore, we specify our own checker so we can disable the uniqueness requirement.
This is a little ugly, but it's rather difficult to work around this within this declarative framework without potentially breaking other tests.

Comment 3

[Intermittent Failures Robot]

35 failures in 874 pushes (0.04 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 18
• mozilla-central: 11
• mozilla-inbound: 4
• try: 2

Platform breakdown:

• linux64-shippable: 5
• macosx1014-64: 1
• linux64: 3
• macosx1014-64-shippable: 3
• windows7-32: 3
• windows7-32-shippable: 3
• windows10-64-shippable: 2
• windows10-aarch64: 6
• linux64-shippable-qr: 3
• windows7-32-mingwclang: 4
• windows10-64-mingwclang: 1
• linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1578604&startday=2019-09-04&endday=2019-09-04&tree=all

Comment 4

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/3bfc0e27d038
accessible/tests/mochitest/actions/test_general.xul: Don't require the focus event on menulist to be unique. r=eeejay

Comment 5

[Andreea Pavel [:apavel]]

https://hg.mozilla.org/mozilla-central/rev/3bfc0e27d038

Comment 6

[Intermittent Failures Robot]

26 failures in 1022 pushes (0.025 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 17
• mozilla-inbound: 3
• mozilla-central: 2
• try: 4

Platform breakdown:

• windows7-32-mingwclang: 1
• windows7-32: 5
• linux64-shippable-qr: 4
• linux64-shippable: 2
• windows7-32-shippable: 5
• linux64-qr: 3
• windows10-aarch64: 4
• linux64: 1
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1578604&startday=2019-09-05&endday=2019-09-05&tree=all

Comment 7

[Intermittent Failures Robot]

71 failures in 4408 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

This is the #19 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: 44
• mozilla-central: 13
• mozilla-inbound: 7
• try: 7

Platform breakdown:

• linux64: 5
• windows7-32-mingwclang: 10
• windows7-32: 9
• linux64-shippable: 8
• windows7-32-shippable: 9
• macosx1014-64: 2
• macosx1014-64-shippable: 3
• windows10-64-shippable: 2
• windows10-aarch64: 10
• linux64-shippable-qr: 7
• windows10-64-mingwclang: 1
• linux64-qr: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1578604&startday=2019-09-02&endday=2019-09-08&tree=all

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1578604&startday=2019-09-16&endday=2019-09-22&tree=all

Comment 9

[Alexandru Michis [:malexandru]]

This appears on beta as well: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267922228&repo=mozilla-beta&lineNumber=1357

Comment 10

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-beta/rev/4dfac5537b49

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 7

Platform breakdown:

• linux64-devedition: 2
• windows7-32-devedition: 2
• windows7-32: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1578604&startday=2019-09-23&endday=2019-09-29&tree=all