Closed Bug 1694319 Opened 5 years ago Closed 5 years ago

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

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1676582

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


...
[task 2021-02-23T05:31:42.124Z] 05:31:42     INFO - PID 10860 | DEBUG: Adding blocker ScriptPreloader: Saving bytecode cache for phase xpcom-will-shutdown
[task 2021-02-23T05:31:42.547Z] 05:31:42     INFO - PID 10860 | 1614058302546	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:50945
[task 2021-02-23T05:31:42.550Z] 05:31:42     INFO - PID 10860 | 1614058302548	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:2828.
[task 2021-02-23T05:31:42.557Z] 05:31:42     INFO - PID 10860 | 1614058302555	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{}]
[task 2021-02-23T05:31:42.571Z] 05:31:42     INFO - PID 10860 | 1614058302569	Marionette	DEBUG	0 <- [1,1,null,{"sessionId":"7ccf8967-531a-4715-8b4e-a0bd7394c2b0","capabilities":{"browserName":"firefox","browserVersion":"88.0a ... \rust_mozprofilewABKiK","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2021-02-23T05:31:42.584Z] 05:31:42     INFO - PID 10860 | 1614058302580	webdriver::server	DEBUG	<- 200 OK {"value":{"sessionId":"7ccf8967-531a-4715-8b4e-a0bd7394c2b0","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"88.0a1","moz:accessibilityChecks":false,"moz:buildID":"20210223033612","moz:geckodriverVersion":"0.29.0","moz:headless":false,"moz:processID":4796,"moz:profile":"C:\\Users\\task_1614057326\\AppData\\Local\\Temp\\rust_mozprofilewABKiK","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"pageLoadStrategy":"normal","platformName":"windows","platformVersion":"10.0","rotatable":false,"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
[task 2021-02-23T05:31:42.586Z] 05:31:42     INFO - PID 10860 | 1614058302581	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/window/rect {"width": 800, "height": 600}
[task 2021-02-23T05:31:42.587Z] 05:31:42     INFO - PID 10860 | 1614058302585	Marionette	DEBUG	0 -> [0,2,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2021-02-23T05:31:42.642Z] 05:31:42     INFO - PID 10860 | 1614058302640	Marionette	DEBUG	0 <- [1,2,null,{"x":4,"y":4,"width":800,"height":600}]
[task 2021-02-23T05:31:42.646Z] 05:31:42     INFO - PID 10860 | 1614058302644	webdriver::server	DEBUG	<- 200 OK {"value":{"x":4,"y":4,"width":800,"height":600}}
[task 2021-02-23T05:31:42.647Z] 05:31:42     INFO - PID 10860 | 1614058302645	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/window/rect {"x": 100, "y": 100}
[task 2021-02-23T05:31:42.649Z] 05:31:42     INFO - PID 10860 | 1614058302648	Marionette	DEBUG	0 -> [0,3,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2021-02-23T05:31:42.657Z] 05:31:42     INFO - PID 10860 | 1614058302655	Marionette	DEBUG	0 <- [1,3,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2021-02-23T05:31:42.663Z] 05:31:42     INFO - PID 10860 | 1614058302660	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2021-02-23T05:31:42.664Z] 05:31:42     INFO - PID 10860 | 1614058302662	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:42.665Z] 05:31:42     INFO - PID 10860 | 1614058302664	Marionette	DEBUG	0 -> [0,4,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:42.670Z] 05:31:42     INFO - PID 10860 | 1614058302669	Marionette	TRACE	[17] MarionetteCommands actor created for window id 2147483649
[task 2021-02-23T05:31:42.676Z] 05:31:42     INFO - PID 10860 | 1614058302674	Marionette	DEBUG	0 <- [1,4,null,{"value":false}]
[task 2021-02-23T05:31:42.676Z] 05:31:42     INFO - PID 10860 | 1614058302674	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:42.766Z] 05:31:42     INFO - PID 10860 | 1614058302764	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:42.768Z] 05:31:42     INFO - PID 10860 | 1614058302766	Marionette	DEBUG	0 -> [0,5,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:42.772Z] 05:31:42     INFO - PID 10860 | 1614058302770	Marionette	DEBUG	0 <- [1,5,null,{"value":false}]
[task 2021-02-23T05:31:42.773Z] 05:31:42     INFO - PID 10860 | 1614058302771	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:42.867Z] 05:31:42     INFO - PID 10860 | 1614058302864	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:42.868Z] 05:31:42     INFO - PID 10860 | 1614058302866	Marionette	DEBUG	0 -> [0,6,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:42.870Z] 05:31:42     INFO - PID 10860 | 1614058302869	Marionette	DEBUG	0 <- [1,6,null,{"value":false}]
[task 2021-02-23T05:31:42.871Z] 05:31:42     INFO - PID 10860 | 1614058302869	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:42.968Z] 05:31:42     INFO - PID 10860 | 1614058302966	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:42.969Z] 05:31:42     INFO - PID 10860 | 1614058302968	Marionette	DEBUG	0 -> [0,7,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:42.972Z] 05:31:42     INFO - PID 10860 | 1614058302971	Marionette	DEBUG	0 <- [1,7,null,{"value":false}]
[task 2021-02-23T05:31:42.973Z] 05:31:42     INFO - PID 10860 | 1614058302971	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.071Z] 05:31:43     INFO - PID 10860 | 1614058303069	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.072Z] 05:31:43     INFO - PID 10860 | 1614058303071	Marionette	DEBUG	0 -> [0,8,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.074Z] 05:31:43     INFO - PID 10860 | 1614058303074	Marionette	DEBUG	0 <- [1,8,null,{"value":false}]
[task 2021-02-23T05:31:43.075Z] 05:31:43     INFO - PID 10860 | 1614058303073	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.172Z] 05:31:43     INFO - PID 10860 | 1614058303170	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.174Z] 05:31:43     INFO - PID 10860 | 1614058303172	Marionette	DEBUG	0 -> [0,9,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.176Z] 05:31:43     INFO - PID 10860 | 1614058303175	Marionette	DEBUG	0 <- [1,9,null,{"value":false}]
[task 2021-02-23T05:31:43.177Z] 05:31:43     INFO - PID 10860 | 1614058303175	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.274Z] 05:31:43     INFO - PID 10860 | 1614058303272	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.275Z] 05:31:43     INFO - PID 10860 | 1614058303274	Marionette	DEBUG	0 -> [0,10,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.278Z] 05:31:43     INFO - PID 10860 | 1614058303277	Marionette	DEBUG	0 <- [1,10,null,{"value":false}]
[task 2021-02-23T05:31:43.279Z] 05:31:43     INFO - PID 10860 | 1614058303277	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.375Z] 05:31:43     INFO - PID 10860 | 1614058303373	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.377Z] 05:31:43     INFO - PID 10860 | 1614058303376	Marionette	DEBUG	0 -> [0,11,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.379Z] 05:31:43     INFO - PID 10860 | 1614058303378	Marionette	DEBUG	0 <- [1,11,null,{"value":false}]
[task 2021-02-23T05:31:43.380Z] 05:31:43     INFO - PID 10860 | 1614058303378	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.477Z] 05:31:43     INFO - PID 10860 | 1614058303475	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.478Z] 05:31:43     INFO - PID 10860 | 1614058303477	Marionette	DEBUG	0 -> [0,12,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.480Z] 05:31:43     INFO - PID 10860 | 1614058303479	Marionette	DEBUG	0 <- [1,12,null,{"value":false}]
[task 2021-02-23T05:31:43.481Z] 05:31:43     INFO - PID 10860 | 1614058303479	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.578Z] 05:31:43     INFO - PID 10860 | 1614058303577	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.579Z] 05:31:43     INFO - PID 10860 | 1614058303579	Marionette	DEBUG	0 -> [0,13,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.582Z] 05:31:43     INFO - PID 10860 | 1614058303581	Marionette	DEBUG	0 <- [1,13,null,{"value":false}]
[task 2021-02-23T05:31:43.582Z] 05:31:43     INFO - PID 10860 | 1614058303581	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.681Z] 05:31:43     INFO - PID 10860 | 1614058303679	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.682Z] 05:31:43     INFO - PID 10860 | 1614058303681	Marionette	DEBUG	0 -> [0,14,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.685Z] 05:31:43     INFO - PID 10860 | 1614058303684	Marionette	DEBUG	0 <- [1,14,null,{"value":false}]
[task 2021-02-23T05:31:43.685Z] 05:31:43     INFO - PID 10860 | 1614058303684	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.785Z] 05:31:43     INFO - PID 10860 | 1614058303783	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.794Z] 05:31:43     INFO - PID 10860 | DEBUG: Adding blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2021-02-23T05:31:43.795Z] 05:31:43     INFO - PID 10860 | 1614058303794	Marionette	DEBUG	0 -> [0,15,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.798Z] 05:31:43     INFO - PID 10860 | 1614058303797	Marionette	DEBUG	0 <- [1,15,null,{"value":false}]
[task 2021-02-23T05:31:43.799Z] 05:31:43     INFO - PID 10860 | 1614058303797	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.804Z] 05:31:43     INFO - PID 10860 | DEBUG: Completed blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2021-02-23T05:31:43.886Z] 05:31:43     INFO - PID 10860 | 1614058303883	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.887Z] 05:31:43     INFO - PID 10860 | 1614058303885	Marionette	DEBUG	0 -> [0,16,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.889Z] 05:31:43     INFO - PID 10860 | 1614058303888	Marionette	DEBUG	0 <- [1,16,null,{"value":false}]
[task 2021-02-23T05:31:43.890Z] 05:31:43     INFO - PID 10860 | 1614058303888	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:43.987Z] 05:31:43     INFO - PID 10860 | 1614058303985	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:43.988Z] 05:31:43     INFO - PID 10860 | 1614058303987	Marionette	DEBUG	0 -> [0,17,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:43.990Z] 05:31:43     INFO - PID 10860 | 1614058303989	Marionette	DEBUG	0 <- [1,17,null,{"value":false}]
[task 2021-02-23T05:31:43.991Z] 05:31:43     INFO - PID 10860 | 1614058303989	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.088Z] 05:31:44     INFO - PID 10860 | 1614058304086	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.089Z] 05:31:44     INFO - PID 10860 | 1614058304088	Marionette	DEBUG	0 -> [0,18,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.091Z] 05:31:44     INFO - PID 10860 | 1614058304091	Marionette	DEBUG	0 <- [1,18,null,{"value":false}]
[task 2021-02-23T05:31:44.092Z] 05:31:44     INFO - PID 10860 | 1614058304090	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.190Z] 05:31:44     INFO - PID 10860 | 1614058304188	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.191Z] 05:31:44     INFO - PID 10860 | 1614058304190	Marionette	DEBUG	0 -> [0,19,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.193Z] 05:31:44     INFO - PID 10860 | 1614058304192	Marionette	DEBUG	0 <- [1,19,null,{"value":false}]
[task 2021-02-23T05:31:44.194Z] 05:31:44     INFO - PID 10860 | 1614058304193	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.292Z] 05:31:44     INFO - PID 10860 | 1614058304290	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.293Z] 05:31:44     INFO - PID 10860 | 1614058304292	Marionette	DEBUG	0 -> [0,20,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.295Z] 05:31:44     INFO - PID 10860 | 1614058304294	Marionette	DEBUG	0 <- [1,20,null,{"value":false}]
[task 2021-02-23T05:31:44.295Z] 05:31:44     INFO - PID 10860 | 1614058304294	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.394Z] 05:31:44     INFO - PID 10860 | 1614058304392	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.394Z] 05:31:44     INFO - PID 10860 | 1614058304393	Marionette	DEBUG	0 -> [0,21,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.396Z] 05:31:44     INFO - PID 10860 | 1614058304396	Marionette	DEBUG	0 <- [1,21,null,{"value":false}]
[task 2021-02-23T05:31:44.397Z] 05:31:44     INFO - PID 10860 | 1614058304395	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.495Z] 05:31:44     INFO - PID 10860 | 1614058304493	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.496Z] 05:31:44     INFO - PID 10860 | 1614058304495	Marionette	DEBUG	0 -> [0,22,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.498Z] 05:31:44     INFO - PID 10860 | 1614058304497	Marionette	DEBUG	0 <- [1,22,null,{"value":false}]
[task 2021-02-23T05:31:44.499Z] 05:31:44     INFO - PID 10860 | 1614058304497	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.596Z] 05:31:44     INFO - PID 10860 | 1614058304595	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.597Z] 05:31:44     INFO - PID 10860 | 1614058304596	Marionette	DEBUG	0 -> [0,23,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.600Z] 05:31:44     INFO - PID 10860 | 1614058304599	Marionette	DEBUG	0 <- [1,23,null,{"value":false}]
[task 2021-02-23T05:31:44.601Z] 05:31:44     INFO - PID 10860 | 1614058304599	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.698Z] 05:31:44     INFO - PID 10860 | 1614058304696	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.699Z] 05:31:44     INFO - PID 10860 | 1614058304698	Marionette	DEBUG	0 -> [0,24,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.702Z] 05:31:44     INFO - PID 10860 | 1614058304700	Marionette	DEBUG	0 <- [1,24,null,{"value":false}]
[task 2021-02-23T05:31:44.702Z] 05:31:44     INFO - PID 10860 | 1614058304701	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.800Z] 05:31:44     INFO - PID 10860 | 1614058304798	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.801Z] 05:31:44     INFO - PID 10860 | 1614058304800	Marionette	DEBUG	0 -> [0,25,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.803Z] 05:31:44     INFO - PID 10860 | 1614058304802	Marionette	DEBUG	0 <- [1,25,null,{"value":false}]
[task 2021-02-23T05:31:44.803Z] 05:31:44     INFO - PID 10860 | 1614058304802	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:44.902Z] 05:31:44     INFO - PID 10860 | 1614058304900	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:44.903Z] 05:31:44     INFO - PID 10860 | 1614058304902	Marionette	DEBUG	0 -> [0,26,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:44.906Z] 05:31:44     INFO - PID 10860 | 1614058304905	Marionette	DEBUG	0 <- [1,26,null,{"value":false}]
[task 2021-02-23T05:31:44.907Z] 05:31:44     INFO - PID 10860 | 1614058304905	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.004Z] 05:31:45     INFO - PID 10860 | 1614058305002	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.005Z] 05:31:45     INFO - PID 10860 | 1614058305004	Marionette	DEBUG	0 -> [0,27,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.008Z] 05:31:45     INFO - PID 10860 | 1614058305006	Marionette	DEBUG	0 <- [1,27,null,{"value":false}]
[task 2021-02-23T05:31:45.009Z] 05:31:45     INFO - PID 10860 | 1614058305007	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.105Z] 05:31:45     INFO - PID 10860 | 1614058305103	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.107Z] 05:31:45     INFO - PID 10860 | 1614058305106	Marionette	DEBUG	0 -> [0,28,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.110Z] 05:31:45     INFO - PID 10860 | 1614058305109	Marionette	DEBUG	0 <- [1,28,null,{"value":false}]
[task 2021-02-23T05:31:45.111Z] 05:31:45     INFO - PID 10860 | 1614058305109	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.208Z] 05:31:45     INFO - PID 10860 | 1614058305207	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.210Z] 05:31:45     INFO - PID 10860 | 1614058305208	Marionette	DEBUG	0 -> [0,29,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.212Z] 05:31:45     INFO - PID 10860 | 1614058305211	Marionette	DEBUG	0 <- [1,29,null,{"value":false}]
[task 2021-02-23T05:31:45.213Z] 05:31:45     INFO - PID 10860 | 1614058305211	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.310Z] 05:31:45     INFO - PID 10860 | 1614058305308	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.310Z] 05:31:45     INFO - PID 10860 | 1614058305310	Marionette	DEBUG	0 -> [0,30,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.313Z] 05:31:45     INFO - PID 10860 | 1614058305312	Marionette	DEBUG	0 <- [1,30,null,{"value":false}]
[task 2021-02-23T05:31:45.314Z] 05:31:45     INFO - PID 10860 | 1614058305312	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.412Z] 05:31:45     INFO - PID 10860 | 1614058305410	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.413Z] 05:31:45     INFO - PID 10860 | 1614058305412	Marionette	DEBUG	0 -> [0,31,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.416Z] 05:31:45     INFO - PID 10860 | 1614058305415	Marionette	DEBUG	0 <- [1,31,null,{"value":false}]
[task 2021-02-23T05:31:45.417Z] 05:31:45     INFO - PID 10860 | 1614058305415	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.514Z] 05:31:45     INFO - PID 10860 | 1614058305512	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.515Z] 05:31:45     INFO - PID 10860 | 1614058305514	Marionette	DEBUG	0 -> [0,32,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.518Z] 05:31:45     INFO - PID 10860 | 1614058305517	Marionette	DEBUG	0 <- [1,32,null,{"value":false}]
[task 2021-02-23T05:31:45.519Z] 05:31:45     INFO - PID 10860 | 1614058305517	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.615Z] 05:31:45     INFO - PID 10860 | 1614058305613	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.617Z] 05:31:45     INFO - PID 10860 | 1614058305616	Marionette	DEBUG	0 -> [0,33,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.619Z] 05:31:45     INFO - PID 10860 | 1614058305618	Marionette	DEBUG	0 <- [1,33,null,{"value":false}]
[task 2021-02-23T05:31:45.620Z] 05:31:45     INFO - PID 10860 | 1614058305618	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.718Z] 05:31:45     INFO - PID 10860 | 1614058305715	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/async {"script": "\n            let dialog_type = arguments[0];\n            let text = arguments[1];\n\n            setTimeout(function() {\n              if (dialog_type == 'prompt') {\n                window.dialog_return_value = window[dialog_type](text, '');\n              } else {\n                window.dialog_return_value = window[dialog_type](text);\n              }\n            }, 0);\n            ", "args": ["alert", "alert"]}
[task 2021-02-23T05:31:45.719Z] 05:31:45     INFO - PID 10860 | 1614058305717	Marionette	DEBUG	0 -> [0,34,"WebDriver:ExecuteAsyncScript",{"args":["alert","alert"],"script":"\n            let dialog_type = arguments[0];\n      ...                 window.dialog_return_value = window[dialog_type](text);\n              }\n            }, 0);\n            "}]
[task 2021-02-23T05:31:45.736Z] 05:31:45     INFO - PID 10860 | 1614058305735	Marionette	TRACE	Received observer notification tabmodal-dialog-loaded
[task 2021-02-23T05:31:45.737Z] 05:31:45     INFO - PID 10860 | 1614058305735	Marionette	TRACE	Received observer notification tabmodal-dialog-loaded
[task 2021-02-23T05:31:45.738Z] 05:31:45     INFO - PID 10860 | 1614058305736	Marionette	DEBUG	0 <- [1,34,null,{"value":null}]
[task 2021-02-23T05:31:45.743Z] 05:31:45     INFO - PID 10860 | 1614058305741	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-02-23T05:31:45.744Z] 05:31:45     INFO - PID 10860 | 1614058305742	webdriver::server	DEBUG	-> GET /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/alert/text
[task 2021-02-23T05:31:45.745Z] 05:31:45     INFO - PID 10860 | 1614058305744	Marionette	DEBUG	0 -> [0,35,"WebDriver:GetAlertText",{}]
[task 2021-02-23T05:31:45.746Z] 05:31:45     INFO - PID 10860 | 1614058305744	Marionette	DEBUG	0 <- [1,35,null,{"value":"alert"}]
[task 2021-02-23T05:31:45.747Z] 05:31:45     INFO - PID 10860 | 1614058305744	webdriver::server	DEBUG	<- 200 OK {"value":"alert"}
[task 2021-02-23T05:31:45.748Z] 05:31:45     INFO - PID 10860 | 1614058305745	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/window/minimize {}
[task 2021-02-23T05:31:45.748Z] 05:31:45     INFO - PID 10860 | 1614058305747	Marionette	DEBUG	0 -> [0,36,"WebDriver:MinimizeWindow",{}]
[task 2021-02-23T05:31:45.751Z] 05:31:45     INFO - PID 10860 | 1614058305750	Marionette	TRACE	Received event DOMModalDialogClosed
[task 2021-02-23T05:31:45.752Z] 05:31:45     INFO - PID 10860 | 1614058305750	Marionette	TRACE	Received event DOMModalDialogClosed
[task 2021-02-23T05:31:45.753Z] 05:31:45     INFO - PID 10860 | 1614058305751	Marionette	TRACE	Received DOM event DOMModalDialogClosed for [object XULFrameElement]
[task 2021-02-23T05:31:45.757Z] 05:31:45     INFO - PID 10860 | 1614058305756	Marionette	DEBUG	0 <- [1,36,{"error":"unexpected alert open","message":"Dismissed user prompt dialog: alert","stacktrace":"WebDriverError@chrome:// ... ette/content/error.js:466:5\nGeckoDriver.prototype._handleUserPrompts@chrome://marionette/content/driver.js:2792:13\n"},null]
[task 2021-02-23T05:31:45.760Z] 05:31:45     INFO - PID 10860 | 1614058305757	webdriver::server	DEBUG	<- 500 Internal Server Error {"value":{"error":"unexpected alert open","message":"Dismissed user prompt dialog: alert","stacktrace":"WebDriverError@chrome://marionette/content/error.js:181:5\nUnexpectedAlertOpenError@chrome://marionette/content/error.js:466:5\nGeckoDriver.prototype._handleUserPrompts@chrome://marionette/content/driver.js:2792:13\n"}}
[task 2021-02-23T05:31:45.761Z] 05:31:45     INFO - PID 10860 | 1614058305759	webdriver::server	DEBUG	-> GET /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/alert/text
[task 2021-02-23T05:31:45.763Z] 05:31:45     INFO - PID 10860 | 1614058305762	Marionette	DEBUG	0 -> [0,37,"WebDriver:GetAlertText",{}]
[task 2021-02-23T05:31:45.764Z] 05:31:45     INFO - PID 10860 | 1614058305762	Marionette	DEBUG	0 <- [1,37,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:181:5\nNoSuchAl ... t@chrome://marionette/content/server.js:239:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20\n"},null]
[task 2021-02-23T05:31:45.766Z] 05:31:45     INFO - PID 10860 | 1614058305762	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:181:5\nNoSuchAlertError@chrome://marionette/content/error.js:382:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:2763:11\nGeckoDriver.prototype.getTextFromDialog@chrome://marionette/content/driver.js:2708:8\ndespatch@chrome://marionette/content/server.js:295:40\nexecute@chrome://marionette/content/server.js:265:16\nonPacket/<@chrome://marionette/content/server.js:238:20\nonPacket@chrome://marionette/content/server.js:239:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20\n"}}
[task 2021-02-23T05:31:45.767Z] 05:31:45     INFO - PID 10860 | 1614058305763	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": " return window.dialog_return_value;", "args": []}
[task 2021-02-23T05:31:45.768Z] 05:31:45     INFO - PID 10860 | 1614058305765	Marionette	DEBUG	0 -> [0,38,"WebDriver:ExecuteScript",{"args":[],"script":" return window.dialog_return_value;"}]
[task 2021-02-23T05:31:45.768Z] 05:31:45     INFO - PID 10860 | 1614058305767	Marionette	DEBUG	0 <- [1,38,null,{"value":null}]
[task 2021-02-23T05:31:45.769Z] 05:31:45     INFO - PID 10860 | 1614058305767	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-02-23T05:31:45.770Z] 05:31:45     INFO - PID 10860 | 1614058305769	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.771Z] 05:31:45     INFO - PID 10860 | 1614058305769	Marionette	DEBUG	0 -> [0,39,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.773Z] 05:31:45     INFO - PID 10860 | 1614058305772	Marionette	DEBUG	0 <- [1,39,null,{"value":false}]
[task 2021-02-23T05:31:45.773Z] 05:31:45     INFO - PID 10860 | 1614058305772	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.871Z] 05:31:45     INFO - PID 10860 | 1614058305869	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.872Z] 05:31:45     INFO - PID 10860 | 1614058305871	Marionette	DEBUG	0 -> [0,40,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.875Z] 05:31:45     INFO - PID 10860 | 1614058305874	Marionette	DEBUG	0 <- [1,40,null,{"value":false}]
[task 2021-02-23T05:31:45.876Z] 05:31:45     INFO - PID 10860 | 1614058305874	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:45.973Z] 05:31:45     INFO - PID 10860 | 1614058305971	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:45.974Z] 05:31:45     INFO - PID 10860 | 1614058305973	Marionette	DEBUG	0 -> [0,41,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:45.976Z] 05:31:45     INFO - PID 10860 | 1614058305975	Marionette	DEBUG	0 <- [1,41,null,{"value":false}]
[task 2021-02-23T05:31:45.977Z] 05:31:45     INFO - PID 10860 | 1614058305976	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.075Z] 05:31:46     INFO - PID 10860 | 1614058306073	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.076Z] 05:31:46     INFO - PID 10860 | 1614058306075	Marionette	DEBUG	0 -> [0,42,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.079Z] 05:31:46     INFO - PID 10860 | 1614058306078	Marionette	DEBUG	0 <- [1,42,null,{"value":false}]
[task 2021-02-23T05:31:46.079Z] 05:31:46     INFO - PID 10860 | 1614058306078	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.178Z] 05:31:46     INFO - PID 10860 | 1614058306176	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.182Z] 05:31:46     INFO - PID 10860 | 1614058306181	Marionette	DEBUG	0 -> [0,43,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.184Z] 05:31:46     INFO - PID 10860 | 1614058306183	Marionette	DEBUG	0 <- [1,43,null,{"value":false}]
[task 2021-02-23T05:31:46.185Z] 05:31:46     INFO - PID 10860 | 1614058306183	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.280Z] 05:31:46     INFO - PID 10860 | 1614058306278	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.285Z] 05:31:46     INFO - PID 10860 | 1614058306284	Marionette	DEBUG	0 -> [0,44,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.287Z] 05:31:46     INFO - PID 10860 | 1614058306287	Marionette	DEBUG	0 <- [1,44,null,{"value":false}]
[task 2021-02-23T05:31:46.288Z] 05:31:46     INFO - PID 10860 | 1614058306286	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.382Z] 05:31:46     INFO - PID 10860 | 1614058306379	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.383Z] 05:31:46     INFO - PID 10860 | 1614058306381	Marionette	DEBUG	0 -> [0,45,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.385Z] 05:31:46     INFO - PID 10860 | 1614058306384	Marionette	DEBUG	0 <- [1,45,null,{"value":false}]
[task 2021-02-23T05:31:46.386Z] 05:31:46     INFO - PID 10860 | 1614058306384	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.483Z] 05:31:46     INFO - PID 10860 | 1614058306482	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.484Z] 05:31:46     INFO - PID 10860 | 1614058306483	Marionette	DEBUG	0 -> [0,46,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.487Z] 05:31:46     INFO - PID 10860 | 1614058306486	Marionette	DEBUG	0 <- [1,46,null,{"value":false}]
[task 2021-02-23T05:31:46.488Z] 05:31:46     INFO - PID 10860 | 1614058306486	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.585Z] 05:31:46     INFO - PID 10860 | 1614058306583	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.586Z] 05:31:46     INFO - PID 10860 | 1614058306585	Marionette	DEBUG	0 -> [0,47,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.588Z] 05:31:46     INFO - PID 10860 | 1614058306587	Marionette	DEBUG	0 <- [1,47,null,{"value":false}]
[task 2021-02-23T05:31:46.589Z] 05:31:46     INFO - PID 10860 | 1614058306587	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.686Z] 05:31:46     INFO - PID 10860 | 1614058306684	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.687Z] 05:31:46     INFO - PID 10860 | 1614058306686	Marionette	DEBUG	0 -> [0,48,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.689Z] 05:31:46     INFO - PID 10860 | 1614058306688	Marionette	DEBUG	0 <- [1,48,null,{"value":false}]
[task 2021-02-23T05:31:46.690Z] 05:31:46     INFO - PID 10860 | 1614058306688	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.787Z] 05:31:46     INFO - PID 10860 | 1614058306786	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.788Z] 05:31:46     INFO - PID 10860 | 1614058306787	Marionette	DEBUG	0 -> [0,49,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.790Z] 05:31:46     INFO - PID 10860 | 1614058306790	Marionette	DEBUG	0 <- [1,49,null,{"value":false}]
[task 2021-02-23T05:31:46.791Z] 05:31:46     INFO - PID 10860 | 1614058306789	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.889Z] 05:31:46     INFO - PID 10860 | 1614058306887	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.891Z] 05:31:46     INFO - PID 10860 | 1614058306890	Marionette	DEBUG	0 -> [0,50,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.894Z] 05:31:46     INFO - PID 10860 | 1614058306893	Marionette	DEBUG	0 <- [1,50,null,{"value":false}]
[task 2021-02-23T05:31:46.895Z] 05:31:46     INFO - PID 10860 | 1614058306893	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:46.991Z] 05:31:46     INFO - PID 10860 | 1614058306989	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:46.992Z] 05:31:46     INFO - PID 10860 | 1614058306991	Marionette	DEBUG	0 -> [0,51,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:46.994Z] 05:31:46     INFO - PID 10860 | 1614058306993	Marionette	DEBUG	0 <- [1,51,null,{"value":false}]
[task 2021-02-23T05:31:46.995Z] 05:31:46     INFO - PID 10860 | 1614058306993	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:47.092Z] 05:31:47     INFO - PID 10860 | 1614058307090	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:47.094Z] 05:31:47     INFO - PID 10860 | 1614058307092	Marionette	DEBUG	0 -> [0,52,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:47.095Z] 05:31:47     INFO - PID 10860 | 1614058307095	Marionette	DEBUG	0 <- [1,52,null,{"value":false}]
[task 2021-02-23T05:31:47.097Z] 05:31:47     INFO - PID 10860 | 1614058307095	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:47.196Z] 05:31:47     INFO - PID 10860 | 1614058307194	webdriver::server	DEBUG	-> POST /session/7ccf8967-531a-4715-8b4e-a0bd7394c2b0/execute/sync {"script": "return document.hidden", "args": []}
[task 2021-02-23T05:31:47.197Z] 05:31:47     INFO - PID 10860 | 1614058307195	Marionette	DEBUG	0 -> [0,53,"WebDriver:ExecuteScript",{"args":[],"script":"return document.hidden"}]
[task 2021-02-23T05:31:47.199Z] 05:31:47     INFO - PID 10860 | 1614058307197	Marionette	DEBUG	0 <- [1,53,null,{"value":false}]
[task 2021-02-23T05:31:47.200Z] 05:31:47     INFO - PID 10860 | 1614058307198	webdriver::server	DEBUG	<- 200 OK {"value":false}
[task 2021-02-23T05:31:47.271Z] 05:31:47     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/minimize_window/user_prompts.py | expected OK
[task 2021-02-23T05:31:47.271Z] 05:31:47     INFO - TEST-INFO took 185061ms```

There was a 1min timeout when starting Firefox due to a missing toplevel-window-ready notification:

[task 2021-02-23T05:30:01.110Z] 05:30:01     INFO - PID 10860 | 1614058201109	Marionette	TRACE	Marionette enabled
[task 2021-02-23T05:31:01.293Z] 05:31:01     INFO - PID 10860 | 1614058261291	mozrunner::runner	DEBUG	Killing process 1508
[task 2021-02-23T05:31:01.295Z] 05:31:01     INFO - PID 10860 | 1614058261293	webdriver::server	DEBUG	<- 500 Internal Server Error {"value":{"error":"timeout","message":"No connection could be made because the target machine actively refused it. (os error 10061)","stacktrace":""}}
Status: NEW → RESOLVED
Closed: 5 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.