Closed Bug 1770612 Opened 3 years ago Closed 3 years ago

Intermittent [Tier 2] TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/execute_script/cyclic.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1760291

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

INFO - PID 6814 | 1653171052141	webdriver::server	DEBUG	-> GET /session/2ac41215-963c-422a-b734-e5d3254b6fef/window
[task 2022-05-21T22:10:52.146Z] 22:10:52     INFO - PID 6814 | 1653171052142	Marionette	DEBUG	0 -> [0,65,"WebDriver:GetWindowHandle",{}]
[task 2022-05-21T22:10:52.146Z] 22:10:52     INFO - PID 6814 | 1653171052142	Marionette	DEBUG	0 <- [1,65,null,{"value":"70cdcc57-467e-423b-b3ad-132010cac7a6"}]
[task 2022-05-21T22:10:52.147Z] 22:10:52     INFO - PID 6814 | 1653171052142	webdriver::server	DEBUG	<- 200 OK {"value":"70cdcc57-467e-423b-b3ad-132010cac7a6"}
[task 2022-05-21T22:10:52.147Z] 22:10:52     INFO - PID 6814 | 1653171052143	webdriver::server	DEBUG	-> GET /session/2ac41215-963c-422a-b734-e5d3254b6fef/window/handles
[task 2022-05-21T22:10:52.147Z] 22:10:52     INFO - PID 6814 | 1653171052143	Marionette	DEBUG	0 -> [0,66,"WebDriver:GetWindowHandles",{}]
[task 2022-05-21T22:10:52.148Z] 22:10:52     INFO - PID 6814 | 1653171052144	Marionette	DEBUG	0 <- [1,66,null,["70cdcc57-467e-423b-b3ad-132010cac7a6"]]
[task 2022-05-21T22:10:52.148Z] 22:10:52     INFO - PID 6814 | 1653171052144	webdriver::server	DEBUG	<- 200 OK {"value":["70cdcc57-467e-423b-b3ad-132010cac7a6"]}
[task 2022-05-21T22:10:52.149Z] 22:10:52     INFO - PID 6814 | 1653171052144	webdriver::server	DEBUG	-> POST /session/2ac41215-963c-422a-b734-e5d3254b6fef/window {"handle": "70cdcc57-467e-423b-b3ad-132010cac7a6"}
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052145	Marionette	DEBUG	0 -> [0,67,"WebDriver:SwitchToWindow",{"handle":"70cdcc57-467e-423b-b3ad-132010cac7a6"}]
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052146	Marionette	DEBUG	0 <- [1,67,null,{"value":null}]
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052146	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052147	webdriver::server	DEBUG	-> POST /session/2ac41215-963c-422a-b734-e5d3254b6fef/window/rect {"width": 800, "height": 600}
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052147	Marionette	DEBUG	0 -> [0,68,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052149	Marionette	DEBUG	0 <- [1,68,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052149	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052150	webdriver::server	DEBUG	-> POST /session/2ac41215-963c-422a-b734-e5d3254b6fef/frame {"id": null}
[task 2022-05-21T22:10:52.152Z] 22:10:52     INFO - PID 6814 | 1653171052150	Marionette	DEBUG	0 -> [0,69,"WebDriver:SwitchToFrame",{"id":null}]
[task 2022-05-21T22:10:52.153Z] 22:10:52     INFO - PID 6814 | 1653171052152	Marionette	DEBUG	0 <- [1,69,null,{"value":null}]
[task 2022-05-21T22:10:52.154Z] 22:10:52     INFO - PID 6814 | 1653171052153	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2022-05-21T22:10:52.156Z] 22:10:52     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/execute_script/cyclic.py::test_element_in_collection 
[task 2022-05-21T22:10:52.156Z] 22:10:52     INFO - PID 6814 | 1653171052155	webdriver::server	DEBUG	-> POST /session/2ac41215-963c-422a-b734-e5d3254b6fef/window/rect {"width": 800, "height": 600}
[task 2022-05-21T22:10:52.157Z] 22:10:52     INFO - PID 6814 | 1653171052155	Marionette	DEBUG	0 -> [0,70,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2022-05-21T22:10:52.158Z] 22:10:52     INFO - PID 6814 | 1653171052157	Marionette	DEBUG	0 <- [1,70,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2022-05-21T22:10:52.158Z] 22:10:52     INFO - PID 6814 | 1653171052157	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2022-05-21T22:10:52.159Z] 22:10:52     INFO - PID 6814 | 1653171052158	webdriver::server	DEBUG	-> POST /session/2ac41215-963c-422a-b734-e5d3254b6fef/window/rect {"x": 100, "y": 100}
[task 2022-05-21T22:10:52.159Z] 22:10:52     INFO - PID 6814 | 1653171052158	Marionette	DEBUG	0 -> [0,71,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2022-05-21T22:10:52.160Z] 22:10:52     INFO - PID 6814 | 1653171052160	Marionette	DEBUG	0 <- [1,71,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2022-05-21T22:10:52.161Z] 22:10:52     INFO - PID 6814 | 1653171052160	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2022-05-21T22:10:52.162Z] 22:10:52     INFO - PID 6814 | 1653171052161	webdriver::server	DEBUG	-> POST /session/2ac41215-963c-422a-b734-e5d3254b6fef/url {"url": "http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cdiv%3E%3C%2Fdiv%3E&mime=text%2Fhtml&charset=UTF-8"}
[task 2022-05-21T22:10:52.163Z] 22:10:52     INFO - PID 6814 | 1653171052162	Marionette	DEBUG	0 -> [0,72,"WebDriver:Navigate",{"url":"http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cdiv%3E%3C%2Fdiv%3E&mime=text%2Fhtml&charset=UTF-8"}]
[task 2022-05-21T22:10:52.168Z] 22:10:52     INFO - PID 6814 | 1653171052167	Marionette	TRACE	[30] Received event beforeunload for about:blank
[task 2022-05-21T22:10:52.189Z] 22:10:52     INFO - PID 6814 | 1653171052188	Marionette	TRACE	Remoteness change detected. Set new top-level browsing context to 39
[task 2022-05-21T22:11:42.614Z] 22:11:42     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/execute_script/cyclic.py | expected OK
[task 2022-05-21T22:11:42.614Z] 22:11:42     INFO - TEST-INFO took 55007ms
[task 2022-05-21T22:11:42.738Z] 22:11:42     INFO - Closing logging queue
[task 2022-05-21T22:11:42.739Z] 22:11:42     INFO - queue closed
[task 2022-05-21T22:11:42.749Z] 22:11:42     INFO - PID 7245 | 1653171102748	geckodriver	INFO	Listening on 127.0.0.1:40495
[task 2022-05-21T22:11:42.750Z] 22:11:42     INFO - Starting runner
[task 2022-05-21T22:11:42.975Z] 22:11:42     INFO - TEST-START | /webdriver/tests/execute_script/execute.py

Times out because serving the page from wptserve seems to never complete. Bug 1760554 should be able to help here.

Depends on: 1760554
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
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.