Closed Bug 1460573 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/get_element_tag_name/user_prompts.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

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

Tracking

(firefox62 disabled)

Status:
RESOLVED WORKSFORME
Tracking Flags:
Tracking Status
firefox62 --- disabled

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure, Whiteboard: [webrender])

Filed by: ncsoregi [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=177826587&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/ZcICyqi4TCiR7tBUDBL_-g/runs/0/artifacts/public/logs/live_backing.log [task 2018-05-10T09:52:23.374Z] 09:52:23 INFO - PID 4936 | 1525945943354 geckodriver::marionette TRACE -> 55:[0,21,"WebDriver:SwitchToWindow",{"name":"2147483649"}] [task 2018-05-10T09:52:23.375Z] 09:52:23 INFO - PID 4936 | 1525945943358 Marionette TRACE 0 -> [0,21,"WebDriver:SwitchToWindow",{"name":"2147483649"}] [task 2018-05-10T09:52:23.375Z] 09:52:23 INFO - PID 4936 | 1525945943359 Marionette TRACE 0 <- [1,21,null,{}] [task 2018-05-10T09:52:23.376Z] 09:52:23 INFO - PID 4936 | 1525945943360 geckodriver::marionette TRACE <- [1,21,null,{}] [task 2018-05-10T09:52:23.377Z] 09:52:23 INFO - PID 4936 | 1525945943360 webdriver::server DEBUG <- 200 OK {"value": {}} [task 2018-05-10T09:52:23.378Z] 09:52:23 INFO - PID 4936 | 1525945943362 webdriver::server DEBUG -> GET /session/970d4823-6ed0-425a-b259-f0b7effa023e/window [task 2018-05-10T09:52:23.380Z] 09:52:23 INFO - PID 4936 | 1525945943362 geckodriver::marionette TRACE -> 37:[0,22,"WebDriver:GetWindowHandle",{}] [task 2018-05-10T09:52:23.381Z] 09:52:23 INFO - PID 4936 | 1525945943363 Marionette TRACE 0 -> [0,22,"WebDriver:GetWindowHandle",{}] [task 2018-05-10T09:52:23.381Z] 09:52:23 INFO - PID 4936 | 1525945943364 Marionette TRACE 0 <- [1,22,null,{"value":"2147483649"}] [task 2018-05-10T09:52:23.383Z] 09:52:23 INFO - PID 4936 | 1525945943366 geckodriver::marionette TRACE <- [1,22,null,{"value":"2147483649"}] [task 2018-05-10T09:52:23.384Z] 09:52:23 INFO - PID 4936 | 1525945943366 webdriver::server DEBUG <- 200 OK {"value":"2147483649"} [task 2018-05-10T09:52:23.386Z] 09:52:23 INFO - PID 4936 | 1525945943369 webdriver::server DEBUG -> GET /session/970d4823-6ed0-425a-b259-f0b7effa023e/window/handles [task 2018-05-10T09:52:23.387Z] 09:52:23 INFO - PID 4936 | 1525945943369 geckodriver::marionette TRACE -> 38:[0,23,"WebDriver:GetWindowHandles",{}] [task 2018-05-10T09:52:23.395Z] 09:52:23 INFO - PID 4936 | 1525945943380 Marionette TRACE 0 -> [0,23,"WebDriver:GetWindowHandles",{}] [task 2018-05-10T09:52:23.395Z] 09:52:23 INFO - PID 4936 | 1525945943381 Marionette TRACE 0 <- [1,23,null,["2147483649"]] [task 2018-05-10T09:52:23.396Z] 09:52:23 INFO - PID 4936 | 1525945943382 geckodriver::marionette TRACE <- [1,23,null,["2147483649"]] [task 2018-05-10T09:52:23.396Z] 09:52:23 INFO - PID 4936 | 1525945943382 webdriver::server DEBUG <- 200 OK {"value":["2147483649"]} [task 2018-05-10T09:52:23.397Z] 09:52:23 INFO - PID 4936 | 1525945943385 webdriver::server DEBUG -> POST /session/970d4823-6ed0-425a-b259-f0b7effa023e/window {"handle": "2147483649"} [task 2018-05-10T09:52:23.398Z] 09:52:23 INFO - PID 4936 | 1525945943385 geckodriver::marionette TRACE -> 55:[0,24,"WebDriver:SwitchToWindow",{"name":"2147483649"}] [task 2018-05-10T09:52:23.398Z] 09:52:23 INFO - PID 4936 | 1525945943388 Marionette TRACE 0 -> [0,24,"WebDriver:SwitchToWindow",{"name":"2147483649"}] [task 2018-05-10T09:52:23.399Z] 09:52:23 INFO - PID 4936 | 1525945943389 Marionette TRACE 0 <- [1,24,null,{}] [task 2018-05-10T09:52:23.399Z] 09:52:23 INFO - PID 4936 | 1525945943389 geckodriver::marionette TRACE <- [1,24,null,{}] [task 2018-05-10T09:52:23.400Z] 09:52:23 INFO - PID 4936 | 1525945943390 webdriver::server DEBUG <- 200 OK {"value": {}} [task 2018-05-10T09:52:23.402Z] 09:52:23 INFO - PID 4936 | 1525945943393 webdriver::server DEBUG -> POST /session/970d4823-6ed0-425a-b259-f0b7effa023e/window/rect {"width": 800, "height": 600} [task 2018-05-10T09:52:23.403Z] 09:52:23 INFO - PID 4936 | 1525945943393 geckodriver::marionette TRACE -> 77:[0,25,"WebDriver:SetWindowRect",{"height":600,"width":800,"x":null,"y":null}] [task 2018-05-10T09:52:23.404Z] 09:52:23 INFO - PID 4936 | 1525945943395 Marionette TRACE 0 -> [0,25,"WebDriver:SetWindowRect",{"height":600,"width":800,"x":null,"y":null}] [task 2018-05-10T09:52:23.650Z] 09:52:23 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/get_element_tag_name/user_prompts.py | expected OK [task 2018-05-10T09:52:23.650Z] 09:52:23 INFO - TEST-INFO took 30004ms
The test would pass with a longer timeout as suggested on bug 1460239. The underlying problem is caused by bug 1460193 as there is a 5s delay after the `sessionstore-windows-restored` notification for each restart. > [task 2018-05-10T09:52:16.480Z] 09:52:16 INFO - PID 4936 | 1525945936470 Marionette DEBUG Received observer notification sessionstore-windows-restored > [task 2018-05-10T09:52:22.374Z] 09:52:22 INFO - PID 4936 | 1525945942370 Marionette DEBUG Setting recommended pref toolkit.cosmeticAnimations.enabled to false
Depends on: 1460239, 1460193
status-firefox62: --- → disabled
Keywords: test-disabled
Whiteboard: [webrender]
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 7 years ago → 7 years ago
Resolution: --- → INCOMPLETE

At some point this test file got unskipped. It's working fine right now.

Keywords: test-disabled
Resolution: INCOMPLETE → WORKSFORME
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.