Open Bug 1621855 Opened 4 years ago Updated 2 years ago

Intermittent /pointerevents/pointerlock/pointerevent_movementxy.html | mouse pointerevent attributes - assert_equals: movementX should be the delta between current event's and last event's screenX expected 20 but got 0

Categories

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

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, leave-open, regression, Whiteboard: [retriggered][stockwell unknown])

Attachments

(1 file)

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


[task 2020-03-12T02:26:41.054Z] 02:26:41 INFO - PID 2552 | 1583980001048 Marionette WARN TimedPromise timed out after 16 ms: stacktrace:
[task 2020-03-12T02:26:41.054Z] 02:26:41 INFO - PID 2552 | TimedPromise/<@chrome://marionette/content/sync.js:244:13
[task 2020-03-12T02:26:41.054Z] 02:26:41 INFO - PID 2552 | TimedPromise@chrome://marionette/content/sync.js:229:10
[task 2020-03-12T02:26:41.054Z] 02:26:41 INFO - PID 2552 | Sleep@chrome://marionette/content/sync.js:282:10
[task 2020-03-12T02:26:41.055Z] 02:26:41 INFO - PID 2552 | dispatchPause@chrome://marionette/content/action.js:1482:10
[task 2020-03-12T02:26:41.055Z] 02:26:41 INFO - PID 2552 | toEvents/<@chrome://marionette/content/action.js:1156:16
[task 2020-03-12T02:26:41.055Z] 02:26:41 INFO - PID 2552 | action.dispatchTickActions@chrome://marionette/content/action.js:1060:35
[task 2020-03-12T02:26:41.055Z] 02:26:41 INFO - PID 2552 | action.dispatch/chainEvents<@chrome://marionette/content/action.js:1028:20
[task 2020-03-12T02:26:41.055Z] 02:26:41 INFO - PID 2552 | asyncaction.dispatch@chrome://marionette/content/action.js:1034:5
[task 2020-03-12T02:26:41.055Z] 02:26:41 INFO - PID 2552 | performActions@chrome://marionette/content/listener.js:850:16
[task 2020-03-12T02:26:41.056Z] 02:26:41 INFO - PID 2552 | dispatch/</req<@chrome://marionette/content/listener.js:527:14
[task 2020-03-12T02:26:41.056Z] 02:26:41 INFO - PID 2552 | dispatch/<@chrome://marionette/content/listener.js:520:15
[task 2020-03-12T02:26:41.056Z] 02:26:41 INFO - PID 2552 | MessageListener.receiveMessage
startListeners@chrome://marionette/content/listener.js:600:21
[task 2020-03-12T02:26:41.056Z] 02:26:41 INFO - PID 2552 | registerSelf@chrome://marionette/content/listener.js:501:5
[task 2020-03-12T02:26:41.057Z] 02:26:41 INFO - PID 2552 | @chrome://marionette/content/listener.js:1852:1
[task 2020-03-12T02:26:41.072Z] 02:26:41 INFO - PID 2552 | 1583980001067 Marionette WARN TimedPromise timed out after 16 ms: stacktrace:
[task 2020-03-12T02:26:41.072Z] 02:26:41 INFO - PID 2552 | TimedPromise/<@chrome://marionette/content/sync.js:244:13
[task 2020-03-12T02:26:41.072Z] 02:26:41 INFO - PID 2552 | TimedPromise@chrome://marionette/content/sync.js:229:10
[task 2020-03-12T02:26:41.073Z] 02:26:41 INFO - PID 2552 | Sleep@chrome://marionette/content/sync.js:282:10
[task 2020-03-12T02:26:41.073Z] 02:26:41 INFO - PID 2552 | dispatchPause@chrome://marionette/content/action.js:1482:10
[task 2020-03-12T02:26:41.073Z] 02:26:41 INFO - PID 2552 | toEvents/<@chrome://marionette/content/action.js:1156:16
[task 2020-03-12T02:26:41.073Z] 02:26:41 INFO - PID 2552 | action.dispatchTickActions@chrome://marionette/content/action.js:1060:35
[task 2020-03-12T02:26:41.073Z] 02:26:41 INFO - PID 2552 | action.dispatch/chainEvents<@chrome://marionette/content/action.js:1028:20
[task 2020-03-12T02:26:41.074Z] 02:26:41 INFO - PID 2552 | asyncaction.dispatch@chrome://marionette/content/action.js:1034:5
[task 2020-03-12T02:26:41.074Z] 02:26:41 INFO - PID 2552 | performActions@chrome://marionette/content/listener.js:850:16
[task 2020-03-12T02:26:41.074Z] 02:26:41 INFO - PID 2552 | dispatch/</req<@chrome://marionette/content/listener.js:527:14
[task 2020-03-12T02:26:41.074Z] 02:26:41 INFO - PID 2552 | dispatch/<@chrome://marionette/content/listener.js:520:15
[task 2020-03-12T02:26:41.075Z] 02:26:41 INFO - PID 2552 | MessageListener.receiveMessage
startListeners@chrome://marionette/content/listener.js:600:21
[task 2020-03-12T02:26:41.075Z] 02:26:41 INFO - PID 2552 | registerSelf@chrome://marionette/content/listener.js:501:5
[task 2020-03-12T02:26:41.075Z] 02:26:41 INFO - PID 2552 | @chrome://marionette/content/listener.js:1852:1
[task 2020-03-12T02:26:41.090Z] 02:26:41 INFO - PID 2552 | 1583980001087 Marionette WARN TimedPromise timed out after 16 ms: stacktrace:
[task 2020-03-12T02:26:41.091Z] 02:26:41 INFO - PID 2552 | TimedPromise/<@chrome://marionette/content/sync.js:244:13
[task 2020-03-12T02:26:41.091Z] 02:26:41 INFO - PID 2552 | TimedPromise@chrome://marionette/content/sync.js:229:10
[task 2020-03-12T02:26:41.092Z] 02:26:41 INFO - PID 2552 | Sleep@chrome://marionette/content/sync.js:282:10
[task 2020-03-12T02:26:41.092Z] 02:26:41 INFO - PID 2552 | dispatchPause@chrome://marionette/content/action.js:1482:10
[task 2020-03-12T02:26:41.092Z] 02:26:41 INFO - PID 2552 | toEvents/<@chrome://marionette/content/action.js:1156:16
[task 2020-03-12T02:26:41.093Z] 02:26:41 INFO - PID 2552 | action.dispatchTickActions@chrome://marionette/content/action.js:1060:35
[task 2020-03-12T02:26:41.093Z] 02:26:41 INFO - PID 2552 | action.dispatch/chainEvents<@chrome://marionette/content/action.js:1028:20
[task 2020-03-12T02:26:41.094Z] 02:26:41 INFO - PID 2552 | asyncaction.dispatch@chrome://marionette/content/action.js:1034:5
[task 2020-03-12T02:26:41.095Z] 02:26:41 INFO - PID 2552 | performActions@chrome://marionette/content/listener.js:850:16
[task 2020-03-12T02:26:41.095Z] 02:26:41 INFO - PID 2552 | dispatch/</req<@chrome://marionette/content/listener.js:527:14
[task 2020-03-12T02:26:41.095Z] 02:26:41 INFO - PID 2552 | dispatch/<@chrome://marionette/content/listener.js:520:15
[task 2020-03-12T02:26:41.096Z] 02:26:41 INFO - PID 2552 | MessageListener.receiveMessage
startListeners@chrome://marionette/content/listener.js:600:21
[task 2020-03-12T02:26:41.096Z] 02:26:41 INFO - PID 2552 | registerSelf@chrome://marionette/content/listener.js:501:5
[task 2020-03-12T02:26:41.097Z] 02:26:41 INFO - PID 2552 | @chrome://marionette/content/listener.js:1852:1
[task 2020-03-12T02:26:41.151Z] 02:26:41 INFO -
[task 2020-03-12T02:26:41.152Z] 02:26:41 INFO - TEST-UNEXPECTED-FAIL | /pointerevents/pointerlock/pointerevent_movementxy.html | mouse pointerevent attributes - assert_equals: movementX should be the delta between current event's and last event's screenX expected 20 but got 0
[task 2020-03-12T02:26:41.152Z] 02:26:41 INFO - run/</</<@http://web-platform.test:8000/pointerevents/pointerlock/pointerevent_movementxy.html:97:38
[task 2020-03-12T02:26:41.152Z] 02:26:41 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:1956:25
[task 2020-03-12T02:26:41.152Z] 02:26:41 INFO - MultiPointerTypeTest.prototype.step@http://web-platform.test:8000/pointerevents/pointerevent_support.js:232:22
[task 2020-03-12T02:26:41.152Z] 02:26:41 INFO - run/</<@http://web-platform.test:8000/pointerevents/pointerlock/pointerevent_movementxy.html:95:39
[task 2020-03-12T02:26:41.153Z] 02:26:41 INFO - TEST-KNOWN-INTERMITTENT-OK | /pointerevents/pointerlock/pointerevent_movementxy.html | took 573ms
[task 2020-03-12T02:26:41.170Z] 02:26:41 INFO - PID 2552 | 1583980001160 Marionette INFO Stopped listening on port 54541
[task 2020-03-12T02:26:41.919Z] 02:26:41 INFO - Browser exited with return code 0
[task 2020-03-12T02:26:41.920Z] 02:26:41 INFO - PROCESS LEAKS None
[task 2020-03-12T02:26:41.922Z] 02:26:41 INFO - Closing logging queue
[task 2020-03-12T02:26:41.922Z] 02:26:41 INFO - queue closed
[task 2020-03-12T02:26:41.998Z] 02:26:41 INFO - Application command: Z:\task_1583978482\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile c:\users\task_1583978482\appdata\local\temp\tmpkp6anv
[task 2020-03-12T02:26:42.005Z] 02:26:42 INFO - PID 1256 | 1583979998687 addons.webextension.doh-rollout@mozilla.org WARN Loading extension 'doh-rollout@mozilla.org': Reading manifest: Invalid extension permission: networkStatus
[task 2020-03-12T02:26:42.005Z] 02:26:42 INFO - PID 1256 | 1583980000011 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2020-03-12T02:26:42.006Z] 02:26:42 INFO - PID 1256 | 1583980000011 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
[task 2020-03-12T02:26:42.006Z] 02:26:42 INFO - PID 1256 | 1583980000012 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2020-03-12T02:26:42.007Z] 02:26:42 INFO - PID 1256 | 1583980000012 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
[task 2020-03-12T02:26:42.007Z] 02:26:42 INFO - Starting runner
[task 2020-03-12T02:26:42.094Z] 02:26:42 INFO - PID 1256 | 1583980002088 Marionette INFO Listening on port 54573
[task 2020-03-12T02:26:43.140Z] 02:26:43 INFO - TEST-START | /pointerevents/pointerlock/pointerevent_movementxy_with_pointerlock.html

Has Regression Range: --- → yes

Hi Edgar, this triggers quite often, can you take a look if it's related to things you are working on?

Flags: needinfo?(echen)

Please re-needinfo me if we decide to just update metadata here and you need some help with that part.

Flags: needinfo?(james)

We actually could not run this test successfully due to lack of touch support in gecko webdriver, see bug 1543337.
I think we could just disable this test.

We could see the following errors on all tasks, but somehow not being reported.

[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - Action action_sequence failed
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - Traceback (most recent call last):
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 790, in process_action
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - result = action_handler(payload)
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/actions.py", line 47, in call
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - self.protocol.action_sequence.send_actions({"actions": actions})
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 459, in send_actions
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - self.marionette._send_message("WebDriver:PerformActions", actions)
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - return func(*args, **kwargs)
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 602, in _send_message
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - self._handle_error(err)
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 622, in _handle_error
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - stacktrace:
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - InvalidArgumentError@chrome://marionette/content/error.js:304:5
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - assert.that/<@chrome://marionette/content/assert.js:476:13
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - assert.in@chrome://marionette/content/assert.js:429:47
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - action.PointerType.get@chrome://marionette/content/action.js:399:10
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - Pointer@chrome://marionette/content/action.js:589:39
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - fromJSON@chrome://marionette/content/action.js:477:14
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - fromJSON@chrome://marionette/content/action.js:843:39
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - fromJSON@chrome://marionette/content/action.js:806:48
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - performActions@chrome://marionette/content/listener.js:848:28
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - dispatch/</req<@chrome://marionette/content/listener.js:522:14
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING - dispatch/<@chrome://marionette/content/listener.js:515:15
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING -
[task 2020-09-07T23:39:20.780Z] 23:39:20 WARNING -
[task 2020-09-07T23:39:20.800Z] 23:39:20 INFO - PID 12578 | JavaScript error: , line 0: uncaught exception: error: undefined
[task 2020-09-07T23:39:21.051Z] 23:39:21 WARNING - Traceback (most recent call last):
[task 2020-09-07T23:39:21.052Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 729, in run_func
[task 2020-09-07T23:39:21.052Z] 23:39:21 WARNING - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-09-07T23:39:21.052Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 846, in do_testharness
[task 2020-09-07T23:39:21.052Z] 23:39:21 WARNING - done, rv = handler(result)
[task 2020-09-07T23:39:21.052Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 776, in call
[task 2020-09-07T23:39:21.052Z] 23:39:21 WARNING - return callback(url, payload)
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 790, in process_action
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - result = action_handler(payload)
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/actions.py", line 47, in call
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - self.protocol.action_sequence.send_actions({"actions": actions})
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 459, in send_actions
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - self.marionette._send_message("WebDriver:PerformActions", actions)
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - return func(*args, **kwargs)
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 602, in _send_message
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - self._handle_error(err)
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 622, in _handle_error
[task 2020-09-07T23:39:21.053Z] 23:39:21 WARNING - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-09-07T23:39:21.054Z] 23:39:21 WARNING - InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2020-09-07T23:39:21.054Z] 23:39:21 WARNING - stacktrace:
[task 2020-09-07T23:39:21.054Z] 23:39:21 WARNING - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2020-09-07T23:39:21.054Z] 23:39:21 WARNING - InvalidArgumentError@chrome://marionette/content/error.js:304:5
[task 2020-09-07T23:39:21.055Z] 23:39:21 WARNING - assert.that/<@chrome://marionette/content/assert.js:476:13
[task 2020-09-07T23:39:21.056Z] 23:39:21 WARNING - assert.in@chrome://marionette/content/assert.js:429:47
[task 2020-09-07T23:39:21.057Z] 23:39:21 WARNING - action.PointerType.get@chrome://marionette/content/action.js:399:10
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING - Pointer@chrome://marionette/content/action.js:589:39
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING - fromJSON@chrome://marionette/content/action.js:477:14
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING - fromJSON@chrome://marionette/content/action.js:843:39
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING - fromJSON@chrome://marionette/content/action.js:806:48
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING - performActions@chrome://marionette/content/listener.js:848:28
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING - dispatch/</req<@chrome://marionette/content/listener.js:522:14
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING - dispatch/<@chrome://marionette/content/listener.js:515:15
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING -
[task 2020-09-07T23:39:21.059Z] 23:39:21 WARNING -
[task 2020-09-07T23:39:21.098Z] 23:39:21 INFO - TEST-ERROR | /pointerevents/pointerlock/pointerevent_movementxy.html | took 2082ms

Hi James, this seems like a bug that TEST-ERROR doesn't be reported somehow?

Flags: needinfo?(echen) → needinfo?(james)
Depends on: 1543337
Component: DOM: Events → DOM: UI Events & Focus Handling
Keywords: leave-open
Assignee: nobody → echen
Status: NEW → ASSIGNED
Assignee: echen → nobody
Status: ASSIGNED → NEW
Assignee: nobody → echen
Status: NEW → ASSIGNED
Pushed by echen@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/5a1ee7a9c7ac
Disable wpt pointerevents/pointerlock/pointerevent_movementxy.html; r=jgraham

The reason we sometimes don't get an error is that an earlier test intermittently fails: mouse pointerevent attributes - assert_equals: movementX should be the delta between current event's and last event's screenX expected 30 but got 0. That suggests disabling here is going to lose some test coverage; the right thing to do would be to refactor the test so that the different input devices form different subtests.

Flags: needinfo?(james)
Assignee: echen → nobody
Status: ASSIGNED → NEW
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: