Closed Bug 1411845 Opened 7 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/actions/modifier_click.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Attachments

(1 file)

trace-log-excerpt
5.61 KB, text/plain
Details 
In the last week there has been 37 failures.
All the failures occur on Linux X64 pgo

Here is a recent example of the log:
https://treeherder.mozilla.org/logviewer.html#?repo=autoland&job_id=140823213&lineNumber=1385

and a relevant part of the log where the test timed out

[task 2017-10-30T19:00:53.959Z] 19:00:53     INFO - TEST-START | /webdriver/tests/actions/modifier_click.py
1374
[task 2017-10-30T19:00:53.980Z] 19:00:53     INFO - STDOUT: ============================= test session starts ==============================
1375
[task 2017-10-30T19:00:53.982Z] 19:00:53     INFO - STDOUT: platform linux2 -- Python 2.7.12, pytest-2.9.1, py-1.4.31, pluggy-0.3.1 -- /builds/worker/workspace/build/venv/bin/python
1376
[task 2017-10-30T19:00:53.983Z] 19:00:53     INFO - STDOUT: rootdir: /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/actions, inifile: 
1377
[task 2017-10-30T19:00:53.984Z] 19:00:53     INFO - STDOUT: collecting ... 
1378
[task 2017-10-30T19:00:53.986Z] 19:00:53     INFO - STDOUT: collected 9 items
1379
[task 2017-10-30T19:00:53.988Z] 19:00:53     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/actions/modifier_click.py::test_modifier_click[modifier0-ctrlKey] 
1380
[task 2017-10-30T19:00:53.996Z] 19:00:53     INFO - PID 852 | 1509390053990	Marionette	DEBUG	Received DOM event "beforeunload" for "http://web-platform.test:8000/webdriver/tests/actions/support/test_actions_wdspec.html"
1381
[task 2017-10-30T19:00:54.013Z] 19:00:54     INFO - PID 852 | 1509390054001	Marionette	DEBUG	Received DOM event "pagehide" for "http://web-platform.test:8000/webdriver/tests/actions/support/test_actions_wdspec.html"
1382
[task 2017-10-30T19:00:54.013Z] 19:00:54     INFO - PID 852 | 1509390054002	Marionette	DEBUG	Received DOM event "unload" for "http://web-platform.test:8000/webdriver/tests/actions/support/test_actions_wdspec.html"
1383
[task 2017-10-30T19:00:54.013Z] 19:00:54     INFO - PID 852 | 1509390054009	Marionette	DEBUG	Received DOM event "DOMContentLoaded" for "http://web-platform.test:8000/webdriver/tests/actions/support/test_actions_wdspec.html"
1384
[task 2017-10-30T19:00:54.016Z] 19:00:54     INFO - PID 852 | 1509390054012	Marionette	DEBUG	Received DOM event "pageshow" for "http://web-platform.test:8000/webdriver/tests/actions/support/test_actions_wdspec.html"
1385
[task 2017-10-30T19:02:58.960Z] 19:02:58     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/actions/modifier_click.py | expected OK

:gbrown, can you please take a look?
Flags: needinfo?(gbrown)
Whiteboard: [stockwell needswork]
This test was just "created" in bug 1397187. Maja is probably interested in the failures here.
Flags: needinfo?(gbrown) → needinfo?(mjzffr)
Judging by the log pasted above, the ctrl+click test hangs after the page is loaded. The pageshow event is logged, so  don't think it's a problem with wptserve. 

I haven't been able to reproduce this behaviour on a One-Click loaner with --repeat-until-unexpected.

I'll run it repeatedly in a try push that produces webdriver trace logs. We'll see if that reveals anything new: https://treeherder.mozilla.org/#/jobs?repo=try&revision=d545d2c66879a61a6d6fc1008604772e257375d8


Leaving the need-info on so I remember to circle back in a couple of days.
Attached file trace-log-excerpt — 
I had to make another try push where modifier_click.py isn't the first test being run in order to distinguish from Bug 1391545. 

https://treeherder.mozilla.org/#/jobs?repo=try&revision=c65b52e5ad4b37c3bdf4409177a869c52733521b&selectedJob=141683093

The attached trace log shows that we hang after sending the performActions command, which indicates that there's something wrong in Marionette actions code.
I'm not sure when I'll be able to investigate this more deeply. Perhaps next week.
Flags: needinfo?(mjzffr)
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: