Closed Bug 1644812 Opened 4 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_window/new.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1641273

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

...
[task 2020-06-10T17:22:26.438Z] 17:22:26     INFO - PID 19578 | 1591809746429	Marionette	DEBUG	0 <- [1,142,null,{"value":null}]
[task 2020-06-10T17:22:26.439Z] 17:22:26     INFO - PID 19578 | 1591809746433	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-06-10T17:22:26.440Z] 17:22:26     INFO - PID 19578 | 1591809746434	webdriver::server	DEBUG	-> GET /session/6bc51192-c155-46c1-8d88-418e5fdac4b9/window/handles
[task 2020-06-10T17:22:26.440Z] 17:22:26     INFO - PID 19578 | 1591809746435	Marionette	DEBUG	0 -> [0,143,"WebDriver:GetWindowHandles",{}]
[task 2020-06-10T17:22:26.442Z] 17:22:26     INFO - PID 19578 | 1591809746436	Marionette	DEBUG	0 <- [1,143,null,["5","18"]]
[task 2020-06-10T17:22:26.442Z] 17:22:26     INFO - PID 19578 | 1591809746436	webdriver::server	DEBUG	<- 200 OK {"value":["5","18"]}
[task 2020-06-10T17:22:26.444Z] 17:22:26     INFO - PID 19578 | 1591809746437	webdriver::server	DEBUG	-> DELETE /session/6bc51192-c155-46c1-8d88-418e5fdac4b9/window
[task 2020-06-10T17:22:26.444Z] 17:22:26     INFO - PID 19578 | 1591809746438	Marionette	DEBUG	0 -> [0,144,"WebDriver:CloseWindow",{}]
[task 2020-06-10T17:22:26.464Z] 17:22:26     INFO - PID 19578 | 1591809746456	Marionette	TRACE	Received DOM event TabClose for [object XULElement]
[task 2020-06-10T17:22:26.480Z] 17:22:26     INFO - PID 19578 | 1591809746474	Marionette	TRACE	Received observer notification message-manager-disconnect
[task 2020-06-10T17:22:26.480Z] 17:22:26     INFO - PID 19578 | 1591809746475	Marionette	DEBUG	0 <- [1,144,null,["5"]]
[task 2020-06-10T17:22:26.481Z] 17:22:26     INFO - PID 19578 | 1591809746478	webdriver::server	DEBUG	<- 200 OK {"value":["5"]}
[task 2020-06-10T17:22:26.483Z] 17:22:26     INFO - PID 19578 | 1591809746479	webdriver::server	DEBUG	-> POST /session/6bc51192-c155-46c1-8d88-418e5fdac4b9/window {"handle": "5"}
[task 2020-06-10T17:22:26.484Z] 17:22:26     INFO - PID 19578 | 1591809746480	Marionette	DEBUG	0 -> [0,145,"WebDriver:SwitchToWindow",{"handle":"5","name":"5"}]
[task 2020-06-10T17:22:26.486Z] 17:22:26     INFO - PID 19578 | 1591809746481	Marionette	DEBUG	0 <- [1,145,null,{"value":null}]
[task 2020-06-10T17:22:26.488Z] 17:22:26     INFO - PID 19578 | 1591809746481	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-06-10T17:22:26.489Z] 17:22:26     INFO - PID 19578 | 1591809746482	webdriver::server	DEBUG	-> POST /session/6bc51192-c155-46c1-8d88-418e5fdac4b9/window/rect {"width": 800, "height": 600}
[task 2020-06-10T17:22:26.489Z] 17:22:26     INFO - PID 19578 | 1591809746483	Marionette	DEBUG	0 -> [0,146,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-06-10T17:22:26.492Z] 17:22:26     INFO - PID 19578 | 1591809746487	Marionette	DEBUG	0 <- [1,146,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-06-10T17:22:26.492Z] 17:22:26     INFO - PID 19578 | 1591809746488	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-06-10T17:22:26.493Z] 17:22:26     INFO - PID 19578 | 1591809746489	webdriver::server	DEBUG	-> POST /session/6bc51192-c155-46c1-8d88-418e5fdac4b9/frame {"id": null}
[task 2020-06-10T17:22:26.494Z] 17:22:26     INFO - PID 19578 | 1591809746490	Marionette	DEBUG	0 -> [0,147,"WebDriver:SwitchToFrame",{"id":null}]
[task 2020-06-10T17:22:40.635Z] 17:22:40     INFO - PID 19578 | DEBUG: Adding blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2020-06-10T17:22:40.635Z] 17:22:40     INFO - PID 19578 | DEBUG: Completed blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2020-06-10T17:23:38.435Z] 17:23:38     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_window/new.py | expected OK
[task 2020-06-10T17:23:38.435Z] 17:23:38     INFO - TEST-INFO took 80020ms

Hang of Firefox after Finish writing Session Restore data for phase profile-before-change:

[task 2020-06-10T17:22:40.635Z] 17:22:40     INFO - PID 19578 | DEBUG: Completed blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2020-06-10T17:23:38.435Z] 17:23:38     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_window/new.py | expected OK

This is similar to bug 1641273 and also needs to be skipped for now.

Status: NEW → RESOLVED
Closed: 4 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.