Closed Bug 1577701 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/fullscreen_window/fullscreen.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, regression)

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=264187179&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/QBqBjdbOQuO8pq1POOOsWg/runs/0/artifacts/public/logs/live_backing.log


[task 2019-08-30T06:20:01.824Z] 06:20:01 INFO - TEST-START | /webdriver/tests/fullscreen_window/fullscreen.py
[task 2019-08-30T06:20:02.814Z] 06:20:02 INFO - STDOUT: ============================= test session starts =============================
[task 2019-08-30T06:20:02.814Z] 06:20:02 INFO - STDOUT: platform win32 -- Python 2.7.11, pytest-unknown, py-1.5.2, pluggy-0.5.3.dev -- Z:\task_1567142262\build\venv\Scripts\python.exe
[task 2019-08-30T06:20:02.815Z] 06:20:02 INFO - STDOUT: rootdir: Z:\task_1567142262\build, inifile:
[task 2019-08-30T06:20:02.815Z] 06:20:02 INFO - STDOUT: collecting ...
[task 2019-08-30T06:20:02.898Z] 06:20:02 INFO - STDOUT: collected 4 items
[task 2019-08-30T06:20:02.899Z] 06:20:02 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/fullscreen_window/fullscreen.py::test_no_browsing_context
[task 2019-08-30T06:20:02.918Z] 06:20:02 INFO - PID 3504 | 1567146002902 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"binary": "Z:\task_1567142262\build\application\firefox\firefox.exe", "prefs": {"media.peerconnection.mtransport_process": false, "network.dns.localDomains": "www1.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www1.web-platform.test,www.www2.not-web-platform.test,www2.www2.not-web-platform.test,xn--lve-6lad.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.not-web-platform.test,not-web-platform.test,www1.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.web-platform.test,www2.not-web-platform.test,www1.not-web-platform.test,www1.www1.web-platform.test,www2.xn--lve-6lad.web-platform.test,www1.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.not-web-platform.test,www2.www1.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.not-web-platform.test,xn--lve-6lad.www.web-platform.test,www.www.web-platform.test,www1.xn--lve-6lad.web-platform.test,xn--lve-6lad.www2.web-platform.test,www.www2.web-platform.test,www2.www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.not-web-platform.test,www1.www.web-platform.test,www.xn--lve-6lad.web-platform.test,www.www.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.web-platform.test,xn--lve-6lad.www1.not-web-platform.test,www1.www1.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.not-web-platform.test,www2.www2.web-platform.test,xn--lve-6lad.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.web-platform.test,xn--lve-6lad.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www2.xn--lve-6lad.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.web-platform.test,www1.xn--lve-6lad.not-web-platform.test,www1.web-platform.test,www.www1.web-platform.test,www2.web-platform.test,www.xn--lve-6lad.not-web-platform.test,www.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.not-web-platform.test,www2.www1.web-platform.test,www.www1.not-web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www2.not-web-platform.test,www2.www.not-web-platform.test", "network.process.enabled": false}}}}}
[task 2019-08-30T06:20:02.934Z] 06:20:02 INFO - PID 3504 | 1567146002915 mozrunner::runner INFO Running command: "Z:\task_1567142262\build\application\firefox\firefox.exe" "-marionette" "-foreground" "-no-remote" "-profile" "C:\Users\task_1567142262\AppData\Local\Temp\rust_mozprofile1BNzZ0"
[task 2019-08-30T06:20:02.941Z] 06:20:02 INFO - PID 3504 | 1567146002931 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828
[task 2019-08-30T06:20:03.819Z] 06:20:03 INFO - PID 3504 | *** You are running in headless mode.
[task 2019-08-30T06:20:05.198Z] 06:20:05 INFO - PID 3504 | 1567146005167 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-08-30T06:20:05.199Z] 06:20:05 INFO - PID 3504 | 1567146005169 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
[task 2019-08-30T06:20:05.199Z] 06:20:05 INFO - PID 3504 | 1567146005170 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-08-30T06:20:05.199Z] 06:20:05 INFO - PID 3504 | 1567146005170 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
[task 2019-08-30T06:20:05.509Z] 06:20:05 INFO - PID 3504 | 1567146005494 Marionette TRACE Received observer notification profile-after-change
[task 2019-08-30T06:20:05.590Z] 06:20:05 INFO - PID 3504 | 1567146005568 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-08-30T06:20:05.687Z] 06:20:05 INFO - PID 3504 | 1567146005661 Marionette TRACE Received observer notification command-line-startup
[task 2019-08-30T06:20:05.688Z] 06:20:05 INFO - PID 3504 | 1567146005663 Marionette TRACE Received observer notification nsPref:changed
[task 2019-08-30T06:20:05.688Z] 06:20:05 INFO - PID 3504 | 1567146005663 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2019-08-30T06:20:05.917Z] 06:20:05 INFO - PID 3504 | 1567146005900 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-08-30T06:20:22.538Z] 06:20:22 INFO - PID 3504 | 1567146022516 Marionette TRACE Received observer notification marionette-startup-requested
[task 2019-08-30T06:20:22.540Z] 06:20:22 INFO - PID 3504 | 1567146022519 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2019-08-30T06:20:23.170Z] 06:20:23 INFO - PID 3504 | 1567146023120 Marionette TRACE All scripts recorded.
[task 2019-08-30T06:20:23.185Z] 06:20:23 INFO - PID 3504 | 1567146023122 Marionette DEBUG Setting recommended pref apz.content_response_timeout to 60000[task 2019-08-30T06:20:23.189Z] 06:20:23 INFO - PID 3504 | 1567146023126 Marionette DEBUG Setting recommended pref browser.contentblocking.introCount to 99
[task 2019-08-30T06:20:23.191Z] 06:20:23 INFO - PID 3504 | 1567146023127 Marionette DEBUG Setting recommended pref browser.download.panel.shown to true
[task 2019-08-30T06:20:23.192Z] 06:20:23 INFO - PID 3504 | 1567146023128 Marionette DEBUG Setting recommended pref browser.newtabpage.enabled to false
[task 2019-08-30T06:20:23.193Z] 06:20:23 INFO - PID 3504 | 1567146023134 Marionette DEBUG Setting recommended pref browser.pagethumbnails.capturing_disabled to true
[task 2019-08-30T06:20:23.196Z] 06:20:23 INFO - PID 3504 | 1567146023135 Marionette DEBUG Setting recommended pref browser.search.update to false
[task 2019-08-30T06:20:23.198Z] 06:20:23 INFO - PID 3504 | 1567146023135 Marionette DEBUG Setting recommended pref browser.tabs.disableBackgroundZombification to false
[task 2019-08-30T06:20:23.202Z] 06:20:23 INFO - PID 3504 | 1567146023137 Marionette DEBUG Setting recommended pref browser.tabs.remote.separatePrivilegedContentProcess to false
[task 2019-08-30T06:20:23.203Z] 06:20:23 INFO - PID 3504 | 1567146023137 Marionette DEBUG Setting recommended pref browser.tabs.unloadOnLowMemory to false
[task 2019-08-30T06:20:23.204Z] 06:20:23 INFO - PID 3504 | 1567146023137 Marionette DEBUG Setting recommended pref browser.tabs.warnOnCloseOtherTabs to false
[task 2019-08-30T06:20:23.205Z] 06:20:23 INFO - PID 3504 | 1567146023137 Marionette DEBUG Setting recommended pref browser.tabs.warnOnOpen to false
[task 2019-08-30T06:20:23.207Z] 06:20:23 INFO - PID 3504 | 1567146023138 Marionette DEBUG Setting recommended pref browser.usedOnWindows10.introURL to
[task 2019-08-30T06:20:23.207Z] 06:20:23 INFO - PID 3504 | 1567146023139 Marionette DEBUG Setting recommended pref browser.urlbar.suggest.searches to false
[task 2019-08-30T06:20:23.208Z] 06:20:23 INFO - PID 3504 | 1567146023145 Marionette DEBUG Setting recommended pref datareporting.policy.dataSubmissionPolicyAccepted to false
[task 2019-08-30T06:20:23.209Z] 06:20:23 INFO - PID 3504 | 1567146023146 Marionette DEBUG Setting recommended pref dom.disable_beforeunload to true
[task 2019-08-30T06:20:23.210Z] 06:20:23 INFO - PID 3504 | 1567146023147 Marionette DEBUG Setting recommended pref dom.disable_open_during_load to false
[task 2019-08-30T06:20:23.211Z] 06:20:23 INFO - PID 3504 | 1567146023150 Marionette DEBUG Setting recommended pref dom.file.createInChild to true
[task 2019-08-30T06:20:23.211Z] 06:20:23 INFO - PID 3504 | 1567146023151 Marionette DEBUG Setting recommended pref dom.max_chrome_script_run_time to 0
[task 2019-08-30T06:20:23.212Z] 06:20:23 INFO - PID 3504 | 1567146023152 Marionette DEBUG Setting recommended pref dom.max_script_run_time to 0
[task 2019-08-30T06:20:23.212Z] 06:20:23 INFO - PID 3504 | 1567146023161 Marionette DEBUG Setting recommended pref dom.push.connection.enabled to false
[task 2019-08-30T06:20:23.214Z] 06:20:23 INFO - PID 3504 | 1567146023165 Marionette DEBUG Setting recommended pref extensions.getAddons.cache.enabled to false
[task 2019-08-30T06:20:23.215Z] 06:20:23 INFO - PID 3504 | 1567146023166 Marionette DEBUG Setting recommended pref extensions.webservice.discoverURL to http://%(server)s/dummy/discoveryURL
[task 2019-08-30T06:20:23.216Z] 06:20:23 INFO - PID 3504 | 1567146023168 Marionette DEBUG Setting recommended pref network.http.prompt-temp-redirect to false
[task 2019-08-30T06:20:23.216Z] 06:20:23 INFO - PID 3504 | 1567146023168 Marionette DEBUG Setting recommended pref network.http.speculative-parallel-limit to 0
[task 2019-08-30T06:20:23.217Z] 06:20:23 INFO - PID 3504 | 1567146023169 Marionette DEBUG Setting recommended pref privacy.trackingprotection.enabled to false
[task 2019-08-30T06:20:23.217Z] 06:20:23 INFO - PID 3504 | 1567146023170 Marionette DEBUG Setting recommended pref security.fileuri.strict_origin_policy to false
[task 2019-08-30T06:20:23.217Z] 06:20:23 INFO - PID 3504 | 1567146023173 Marionette DEBUG Setting recommended pref security.notification_enable_delay to 0
[task 2019-08-30T06:20:23.218Z] 06:20:23 INFO - PID 3504 | 1567146023174 Marionette DEBUG Setting recommended pref signon.autofillForms to false
[task 2019-08-30T06:20:23.218Z] 06:20:23 INFO - PID 3504 | 1567146023176 Marionette DEBUG Setting recommended pref signon.rememberSignons to false
[task 2019-08-30T06:20:23.218Z] 06:20:23 INFO - PID 3504 | 1567146023177 Marionette DEBUG Setting recommended pref toolkit.cosmeticAnimations.enabled to false
[task 2019-08-30T06:20:23.775Z] 06:20:23 INFO - PID 3504 | 1567146023750 Marionette INFO Listening on port 2828
[task 2019-08-30T06:20:23.776Z] 06:20:23 INFO - PID 3504 | 1567146023754 Marionette DEBUG Remote service is active
[task 2019-08-30T06:20:23.878Z] 06:20:23 INFO - PID 3504 | 1567146023840 geckodriver::marionette DEBUG Connection established on 127.0.0.1:2828. Waiting for Marionette handshake
[task 2019-08-30T06:20:23.924Z] 06:20:23 INFO - PID 3504 | 1567146023900 Marionette DEBUG Accepted connection 0 from 127.0.0.1:51761
[task 2019-08-30T06:20:23.925Z] 06:20:23 INFO - PID 3504 | 1567146023906 geckodriver::marionette DEBUG Connected to Marionette
[task 2019-08-30T06:20:23.955Z] 06:20:23 INFO - PID 3504 | 1567146023933 Marionette DEBUG 0 -> [0,1,"WebDriver:NewSession",{}]
[task 2019-08-30T06:20:24.154Z] 06:20:24 INFO - PID 3504 | 1567146024116 Marionette TRACE [20] Frame script loaded
[task 2019-08-30T06:20:24.179Z] 06:20:24 INFO - PID 3504 | 1567146024154 Marionette TRACE [20] Frame script registered
[task 2019-08-30T06:20:24.287Z] 06:20:24 INFO - PID 3504 | 1567146024262 Marionette DEBUG 0 <- [1,1,null,{"sessionId":"c7eb56e4-dcc7-44ad-a2f3-03d43b879920","capabilities":{"browserName":"firefox","browserVersion":"70.0a ... \rust_mozprofile1BNzZ0","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-08-30T06:20:24.339Z] 06:20:24 INFO - PID 3504 | 1567146024305 webdriver::server DEBUG <- 200 OK {"value":{"sessionId":"c7eb56e4-dcc7-44ad-a2f3-03d43b879920","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"70.0a1","moz:accessibilityChecks":false,"moz:buildID":"20190830032952","moz:geckodriverVersion":"0.24.0","moz:headless":true,"moz:processID":3516,"moz:profile":"C:\Users\task_1567142262\AppData\Local\Temp\rust_mozprofile1BNzZ0","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"pageLoadStrategy":"normal","platformName":"windows","platformVersion":"6.1","rotatable":false,"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
[task 2019-08-30T06:20:24.356Z] 06:20:24 INFO - PID 3504 | 1567146024337 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"width": 800, "height": 600}
[task 2019-08-30T06:20:24.363Z] 06:20:24 INFO - PID 3504 | 1567146024352 Marionette DEBUG 0 -> [0,2,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2019-08-30T06:20:24.804Z] 06:20:24 INFO - PID 3504 | 1567146024772 Marionette DEBUG 0 <- [1,2,null,{"x":4,"y":4,"width":800,"height":600}]
[task 2019-08-30T06:20:24.804Z] 06:20:24 INFO - PID 3504 | 1567146024784 webdriver::server DEBUG <- 200 OK {"value":{"x":4,"y":4,"width":800,"height":600}}
[task 2019-08-30T06:20:24.804Z] 06:20:24 INFO - PID 3504 | 1567146024793 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"y": 100, "x": 100}
[task 2019-08-30T06:20:24.829Z] 06:20:24 INFO - PID 3504 | 1567146024802 Marionette DEBUG 0 -> [0,3,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2019-08-30T06:20:24.949Z] 06:20:24 INFO - PID 3504 | 1567146024926 Marionette DEBUG 0 <- [1,3,null,{"x":100,"y":100,"width":800,"height":600}][task 2019-08-30T06:20:24.950Z] 06:20:24 INFO - PID 3504 | 1567146024935 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:24.952Z] 06:20:24 INFO - PID 3504 | 1567146024949 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:24.989Z] 06:20:24 INFO - PID 3504 | 1567146024961 Marionette DEBUG 0 -> [0,4,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:24.990Z] 06:20:24 INFO - PID 3504 | 1567146024970 Marionette DEBUG 0 <- [1,4,null,{"value":"20"}]
[task 2019-08-30T06:20:24.990Z] 06:20:24 INFO - PID 3504 | 1567146024976 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:25.014Z] 06:20:25 INFO - PID 3504 | 1567146024989 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:25.029Z] 06:20:25 INFO - PID 3504 | 1567146025011 Marionette DEBUG 0 -> [0,5,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:25.029Z] 06:20:25 INFO - PID 3504 | 1567146025018 Marionette DEBUG 0 <- [1,5,null,["20"]]
[task 2019-08-30T06:20:25.081Z] 06:20:25 INFO - PID 3504 | 1567146025053 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:25.099Z] 06:20:25 INFO - PID 3504 | 1567146025079 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/execute/sync {"args": [], "script": "window.open()"}
[task 2019-08-30T06:20:25.100Z] 06:20:25 INFO - PID 3504 | 1567146025092 Marionette DEBUG 0 -> [0,6,"WebDriver:ExecuteScript",{"args":[],"script":"window.open()"}]
[task 2019-08-30T06:20:25.578Z] 06:20:25 INFO - PID 3504 | 1567146025559 Marionette TRACE [6442450946] Frame script loaded
[task 2019-08-30T06:20:25.721Z] 06:20:25 INFO - PID 3504 | 1567146025691 Marionette TRACE [6442450946] Frame script registered
[task 2019-08-30T06:20:25.967Z] 06:20:25 INFO - PID 3504 | 1567146025944 Marionette DEBUG 0 <- [1,6,null,{"value":null}]
[task 2019-08-30T06:20:25.994Z] 06:20:25 INFO - PID 3504 | 1567146025968 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:26.002Z] 06:20:26 INFO - PID 3504 | 1567146025989 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:26.026Z] 06:20:26 INFO - PID 3504 | 1567146026010 Marionette DEBUG 0 -> [0,7,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:26.026Z] 06:20:26 INFO - PID 3504 | 1567146026013 Marionette DEBUG 0 <- [1,7,null,["20","6442450946"]]
[task 2019-08-30T06:20:26.026Z] 06:20:26 INFO - PID 3504 | 1567146026014 webdriver::server DEBUG <- 200 OK {"value":["20","6442450946"]}
[task 2019-08-30T06:20:26.035Z] 06:20:26 INFO - PID 3504 | 1567146026022 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:26.035Z] 06:20:26 INFO - PID 3504 | 1567146026025 Marionette DEBUG 0 -> [0,8,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:26.036Z] 06:20:26 INFO - PID 3504 | 1567146026027 Marionette DEBUG 0 <- [1,8,null,["20","6442450946"]]
[task 2019-08-30T06:20:26.036Z] 06:20:26 INFO - PID 3504 | 1567146026028 webdriver::server DEBUG <- 200 OK {"value":["20","6442450946"]}
[task 2019-08-30T06:20:26.060Z] 06:20:26 INFO - PID 3504 | 1567146026033 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "6442450946"}
[task 2019-08-30T06:20:26.194Z] 06:20:26 INFO - PID 3504 | 1567146026171 Marionette DEBUG 0 -> [0,9,"WebDriver:SwitchToWindow",{"handle":"6442450946","name":"6442450946"}]
[task 2019-08-30T06:20:26.195Z] 06:20:26 INFO - PID 3504 | 1567146026181 Marionette DEBUG 0 <- [1,9,null,{"value":null}]
[task 2019-08-30T06:20:26.213Z] 06:20:26 INFO - PID 3504 | 1567146026192 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:26.214Z] 06:20:26 INFO - PID 3504 | 1567146026205 webdriver::server DEBUG -> DELETE /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:26.234Z] 06:20:26 INFO - PID 3504 | 1567146026218 Marionette DEBUG 0 -> [0,10,"WebDriver:CloseWindow",{}]
[task 2019-08-30T06:20:26.341Z] 06:20:26 INFO - PID 3504 | 1567146026313 Marionette TRACE Received DOM event TabClose for [object XULElement]
[task 2019-08-30T06:20:26.561Z] 06:20:26 INFO - PID 3504 | 1567146026525 Marionette TRACE Received observer notification message-manager-disconnect
[task 2019-08-30T06:20:26.563Z] 06:20:26 INFO - PID 3504 | 1567146026531 Marionette DEBUG 0 <- [1,10,null,["20"]]
[task 2019-08-30T06:20:26.585Z] 06:20:26 INFO - PID 3504 | 1567146026556 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:26.591Z] 06:20:26 INFO - PID 3504 | 1567146026577 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:26.618Z] 06:20:26 INFO - PID 3504 | 1567146026595 Marionette DEBUG 0 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:26.619Z] 06:20:26 INFO - PID 3504 | 1567146026602 Marionette DEBUG 0 <- [1,11,null,["20"]]
[task 2019-08-30T06:20:26.643Z] 06:20:26 INFO - PID 3504 | 1567146026611 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:26.662Z] 06:20:26 INFO - PID 3504 | 1567146026642 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/fullscreen {}
[task 2019-08-30T06:20:26.663Z] 06:20:26 INFO - PID 3504 | 1567146026653 Marionette DEBUG 0 -> [0,12,"WebDriver:FullscreenWindow",{}][task 2019-08-30T06:20:26.669Z] 06:20:26 INFO - PID 3504 | 1567146026658 Marionette DEBUG 0 <- [1,12,{"error":"no such window","message":"Browsing context has been discarded","stacktrace":"WebDriverError@chrome://marione ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2019-08-30T06:20:26.669Z] 06:20:26 INFO - PID 3504 | 1567146026661 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such window","message":"Browsing context has been discarded","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchWindowError@chrome://marionette/content/error.js:409:5\nassert.that/<@chrome://marionette/content/assert.js:428:13\nassert.open@chrome://marionette/content/assert.js:183:72\nGeckoDriver.prototype.fullscreenWindow@chrome://marionette/content/driver.js:3259:22\ndespatch@chrome://marionette/content/server.js:305:40\nexecute@chrome://marionette/content/server.js:275:16\nonPacket/<@chrome://marionette/content/server.js:248:20\nonPacket@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"}}
[task 2019-08-30T06:20:26.694Z] 06:20:26 INFO - STDOUT: PASSED
[task 2019-08-30T06:20:26.695Z] 06:20:26 INFO - PID 3504 | 1567146026680 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:26.742Z] 06:20:26 INFO - PID 3504 | 1567146026724 Marionette DEBUG 0 -> [0,13,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:26.742Z] 06:20:26 INFO - PID 3504 | 1567146026731 Marionette DEBUG 0 <- [1,13,null,{"value":null}]
[task 2019-08-30T06:20:26.744Z] 06:20:26 INFO - PID 3504 | 1567146026735 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:26.754Z] 06:20:26 INFO - PID 3504 | 1567146026745 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"implicit": 0}
[task 2019-08-30T06:20:26.832Z] 06:20:26 INFO - PID 3504 | 1567146026802 Marionette DEBUG 0 -> [0,14,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2019-08-30T06:20:26.833Z] 06:20:26 INFO - PID 3504 | 1567146026804 Marionette DEBUG 0 <- [1,14,null,{"value":null}]
[task 2019-08-30T06:20:26.835Z] 06:20:26 INFO - PID 3504 | 1567146026806 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:26.836Z] 06:20:26 INFO - PID 3504 | 1567146026816 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"pageLoad": 300000}
[task 2019-08-30T06:20:26.900Z] 06:20:26 INFO - PID 3504 | 1567146026873 Marionette DEBUG 0 -> [0,15,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-08-30T06:20:26.901Z] 06:20:26 INFO - PID 3504 | 1567146026877 Marionette DEBUG 0 <- [1,15,null,{"value":null}]
[task 2019-08-30T06:20:26.901Z] 06:20:26 INFO - PID 3504 | 1567146026879 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:26.902Z] 06:20:26 INFO - PID 3504 | 1567146026888 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"script": 30000}
[task 2019-08-30T06:20:26.934Z] 06:20:26 INFO - PID 3504 | 1567146026908 Marionette DEBUG 0 -> [0,16,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-08-30T06:20:26.935Z] 06:20:26 INFO - PID 3504 | 1567146026915 Marionette DEBUG 0 <- [1,16,null,{"value":null}]
[task 2019-08-30T06:20:26.936Z] 06:20:26 INFO - PID 3504 | 1567146026921 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:26.953Z] 06:20:26 INFO - PID 3504 | 1567146026941 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:26.958Z] 06:20:26 INFO - PID 3504 | 1567146026951 Marionette DEBUG 0 -> [0,17,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:26.960Z] 06:20:26 INFO - PID 3504 | 1567146026954 Marionette DEBUG 0 <- [1,17,null,{"value":"20"}]
[task 2019-08-30T06:20:26.962Z] 06:20:26 INFO - PID 3504 | 1567146026954 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:26.985Z] 06:20:26 INFO - PID 3504 | 1567146026966 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:27.046Z] 06:20:27 INFO - PID 3504 | 1567146027024 Marionette DEBUG 0 -> [0,18,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:27.047Z] 06:20:27 INFO - PID 3504 | 1567146027026 Marionette DEBUG 0 <- [1,18,null,{"value":"20"}]
[task 2019-08-30T06:20:27.048Z] 06:20:27 INFO - PID 3504 | 1567146027032 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:27.072Z] 06:20:27 INFO - PID 3504 | 1567146027048 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:27.119Z] 06:20:27 INFO - PID 3504 | 1567146027092 Marionette DEBUG 0 -> [0,19,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:27.121Z] 06:20:27 INFO - PID 3504 | 1567146027095 Marionette DEBUG 0 <- [1,19,null,["20"]]
[task 2019-08-30T06:20:27.122Z] 06:20:27 INFO - PID 3504 | 1567146027097 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:27.132Z] 06:20:27 INFO - PID 3504 | 1567146027113 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:27.177Z] 06:20:27 INFO - PID 3504 | 1567146027157 Marionette DEBUG 0 -> [0,20,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:27.178Z] 06:20:27 INFO - PID 3504 | 1567146027162 Marionette DEBUG 0 <- [1,20,null,{"value":null}]
[task 2019-08-30T06:20:27.179Z] 06:20:27 INFO - PID 3504 | 1567146027164 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:27.180Z] 06:20:27 INFO - PID 3504 | 1567146027174 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/alert/dismiss {}
[task 2019-08-30T06:20:27.242Z] 06:20:27 INFO - PID 3504 | 1567146027228 Marionette DEBUG 0 -> [0,21,"WebDriver:DismissAlert",{}][task 2019-08-30T06:20:27.243Z] 06:20:27 INFO - PID 3504 | 1567146027233 Marionette DEBUG 0 <- [1,21,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAl ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2019-08-30T06:20:27.245Z] 06:20:27 INFO - PID 3504 | 1567146027237 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAlertError@chrome://marionette/content/error.js:376:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3379:11\nGeckoDriver.prototype.dismissDialog@chrome://marionette/content/driver.js:3293:8\ndespatch@chrome://marionette/content/server.js:305:40\nexecute@chrome://marionette/content/server.js:275:16\nonPacket/<@chrome://marionette/content/server.js:248:20\nonPacket@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"}}
[task 2019-08-30T06:20:27.265Z] 06:20:27 INFO - PID 3504 | 1567146027248 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:27.318Z] 06:20:27 INFO - PID 3504 | 1567146027298 Marionette DEBUG 0 -> [0,22,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:27.319Z] 06:20:27 INFO - PID 3504 | 1567146027305 Marionette DEBUG 0 <- [1,22,null,{"value":null}]
[task 2019-08-30T06:20:27.319Z] 06:20:27 INFO - PID 3504 | 1567146027309 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:27.322Z] 06:20:27 INFO - PID 3504 | 1567146027316 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:27.397Z] 06:20:27 INFO - PID 3504 | 1567146027371 Marionette DEBUG 0 -> [0,23,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:27.398Z] 06:20:27 INFO - PID 3504 | 1567146027377 Marionette DEBUG 0 <- [1,23,null,{"value":"20"}]
[task 2019-08-30T06:20:27.398Z] 06:20:27 INFO - PID 3504 | 1567146027379 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:27.398Z] 06:20:27 INFO - PID 3504 | 1567146027386 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:27.429Z] 06:20:27 INFO - PID 3504 | 1567146027396 Marionette DEBUG 0 -> [0,24,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:27.430Z] 06:20:27 INFO - PID 3504 | 1567146027402 Marionette DEBUG 0 <- [1,24,null,["20"]]
[task 2019-08-30T06:20:27.432Z] 06:20:27 INFO - PID 3504 | 1567146027407 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:27.436Z] 06:20:27 INFO - PID 3504 | 1567146027424 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:27.484Z] 06:20:27 INFO - PID 3504 | 1567146027464 Marionette DEBUG 0 -> [0,25,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:27.484Z] 06:20:27 INFO - PID 3504 | 1567146027469 Marionette DEBUG 0 <- [1,25,null,{"value":null}]
[task 2019-08-30T06:20:27.493Z] 06:20:27 INFO - PID 3504 | 1567146027471 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:27.502Z] 06:20:27 INFO - PID 3504 | 1567146027489 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"width": 800, "height": 600}
[task 2019-08-30T06:20:27.574Z] 06:20:27 INFO - PID 3504 | 1567146027539 Marionette DEBUG 0 -> [0,26,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2019-08-30T06:20:27.576Z] 06:20:27 INFO - PID 3504 | 1567146027543 Marionette DEBUG 0 <- [1,26,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:27.577Z] 06:20:27 INFO - PID 3504 | 1567146027545 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:27.578Z] 06:20:27 INFO - PID 3504 | 1567146027561 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/frame {"id": null}
[task 2019-08-30T06:20:27.629Z] 06:20:27 INFO - PID 3504 | 1567146027607 Marionette DEBUG 0 -> [0,27,"WebDriver:SwitchToFrame",{}]
[task 2019-08-30T06:20:27.717Z] 06:20:27 INFO - PID 3504 | 1567146027689 Marionette DEBUG 0 <- [1,27,null,{"value":null}]
[task 2019-08-30T06:20:27.719Z] 06:20:27 INFO - PID 3504 | 1567146027693 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:27.747Z] 06:20:27 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/fullscreen_window/fullscreen.py::test_fullscreen
[task 2019-08-30T06:20:27.768Z] 06:20:27 INFO - PID 3504 | 1567146027748 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"width": 800, "height": 600}
[task 2019-08-30T06:20:27.768Z] 06:20:27 INFO - PID 3504 | 1567146027765 Marionette DEBUG 0 -> [0,28,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2019-08-30T06:20:27.793Z] 06:20:27 INFO - PID 3504 | 1567146027769 Marionette DEBUG 0 <- [1,28,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:27.795Z] 06:20:27 INFO - PID 3504 | 1567146027770 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:27.796Z] 06:20:27 INFO - PID 3504 | 1567146027778 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"y": 100, "x": 100}
[task 2019-08-30T06:20:27.797Z] 06:20:27 INFO - PID 3504 | 1567146027785 Marionette DEBUG 0 -> [0,29,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2019-08-30T06:20:27.821Z] 06:20:27 INFO - PID 3504 | 1567146027798 Marionette DEBUG 0 <- [1,29,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:27.823Z] 06:20:27 INFO - PID 3504 | 1567146027805 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:27.848Z] 06:20:27 INFO - PID 3504 | 1567146027828 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/fullscreen {}
[task 2019-08-30T06:20:28.344Z] 06:20:28 INFO - PID 3504 | 1567146028304 Marionette DEBUG 0 -> [0,30,"WebDriver:FullscreenWindow",{}]
[task 2019-08-30T06:20:28.756Z] 06:20:28 INFO - PID 3504 | 1567146028725 Marionette DEBUG 0 <- [1,30,null,{"x":100,"y":100,"width":1600,"height":1200}]
[task 2019-08-30T06:20:28.796Z] 06:20:28 INFO - PID 3504 | 1567146028761 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":1600,"height":1200}}[task 2019-08-30T06:20:28.798Z] 06:20:28 INFO - PID 3504 | 1567146028776 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/execute/sync {"args": [], "script": "\n return !!(window.fullScreen || document.webkitIsFullScreen)\n "}
[task 2019-08-30T06:20:28.818Z] 06:20:28 INFO - PID 3504 | 1567146028790 Marionette DEBUG 0 -> [0,31,"WebDriver:ExecuteScript",{"args":[],"script":"\n return !!(window.fullScreen || document.webkitIsFullScreen)\n "}]
[task 2019-08-30T06:20:28.872Z] 06:20:28 INFO - PID 3504 | 1567146028843 Marionette DEBUG 0 <- [1,31,null,{"value":true}]
[task 2019-08-30T06:20:28.874Z] 06:20:28 INFO - PID 3504 | 1567146028849 webdriver::server DEBUG <- 200 OK {"value":true}
[task 2019-08-30T06:20:28.893Z] 06:20:28 INFO - STDOUT: PASSED
[task 2019-08-30T06:20:28.915Z] 06:20:28 INFO - PID 3504 | 1567146028888 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"implicit": 0}
[task 2019-08-30T06:20:28.919Z] 06:20:28 INFO - PID 3504 | 1567146028898 Marionette DEBUG 0 -> [0,32,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2019-08-30T06:20:28.921Z] 06:20:28 INFO - PID 3504 | 1567146028903 Marionette DEBUG 0 <- [1,32,null,{"value":null}]
[task 2019-08-30T06:20:28.921Z] 06:20:28 INFO - PID 3504 | 1567146028906 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:28.947Z] 06:20:28 INFO - PID 3504 | 1567146028926 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"pageLoad": 300000}
[task 2019-08-30T06:20:29.001Z] 06:20:28 INFO - PID 3504 | 1567146028972 Marionette DEBUG 0 -> [0,33,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-08-30T06:20:29.003Z] 06:20:28 INFO - PID 3504 | 1567146028980 Marionette DEBUG 0 <- [1,33,null,{"value":null}]
[task 2019-08-30T06:20:29.006Z] 06:20:29 INFO - PID 3504 | 1567146028988 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:29.036Z] 06:20:29 INFO - PID 3504 | 1567146029013 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"script": 30000}
[task 2019-08-30T06:20:29.102Z] 06:20:29 INFO - PID 3504 | 1567146029079 Marionette DEBUG 0 -> [0,34,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-08-30T06:20:29.103Z] 06:20:29 INFO - PID 3504 | 1567146029088 Marionette DEBUG 0 <- [1,34,null,{"value":null}]
[task 2019-08-30T06:20:29.118Z] 06:20:29 INFO - PID 3504 | 1567146029092 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:29.120Z] 06:20:29 INFO - PID 3504 | 1567146029105 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:29.148Z] 06:20:29 INFO - PID 3504 | 1567146029124 Marionette DEBUG 0 -> [0,35,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:29.148Z] 06:20:29 INFO - PID 3504 | 1567146029135 Marionette DEBUG 0 <- [1,35,null,{"value":"20"}]
[task 2019-08-30T06:20:29.156Z] 06:20:29 INFO - PID 3504 | 1567146029142 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:29.168Z] 06:20:29 INFO - PID 3504 | 1567146029155 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:29.197Z] 06:20:29 INFO - PID 3504 | 1567146029176 Marionette DEBUG 0 -> [0,36,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:29.198Z] 06:20:29 INFO - PID 3504 | 1567146029181 Marionette DEBUG 0 <- [1,36,null,{"value":"20"}]
[task 2019-08-30T06:20:29.199Z] 06:20:29 INFO - PID 3504 | 1567146029185 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:29.217Z] 06:20:29 INFO - PID 3504 | 1567146029202 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:29.248Z] 06:20:29 INFO - PID 3504 | 1567146029222 Marionette DEBUG 0 -> [0,37,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:29.249Z] 06:20:29 INFO - PID 3504 | 1567146029228 Marionette DEBUG 0 <- [1,37,null,["20"]]
[task 2019-08-30T06:20:29.258Z] 06:20:29 INFO - PID 3504 | 1567146029234 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:29.282Z] 06:20:29 INFO - PID 3504 | 1567146029257 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:29.329Z] 06:20:29 INFO - PID 3504 | 1567146029305 Marionette DEBUG 0 -> [0,38,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:29.336Z] 06:20:29 INFO - PID 3504 | 1567146029317 Marionette DEBUG 0 <- [1,38,null,{"value":null}]
[task 2019-08-30T06:20:29.337Z] 06:20:29 INFO - PID 3504 | 1567146029324 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:29.357Z] 06:20:29 INFO - PID 3504 | 1567146029339 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/alert/dismiss {}
[task 2019-08-30T06:20:29.394Z] 06:20:29 INFO - PID 3504 | 1567146029370 Marionette DEBUG 0 -> [0,39,"WebDriver:DismissAlert",{}]
[task 2019-08-30T06:20:29.396Z] 06:20:29 INFO - PID 3504 | 1567146029376 Marionette DEBUG 0 <- [1,39,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAl ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2019-08-30T06:20:29.397Z] 06:20:29 INFO - PID 3504 | 1567146029381 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAlertError@chrome://marionette/content/error.js:376:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3379:11\nGeckoDriver.prototype.dismissDialog@chrome://marionette/content/driver.js:3293:8\ndespatch@chrome://marionette/content/server.js:305:40\nexecute@chrome://marionette/content/server.js:275:16\nonPacket/<@chrome://marionette/content/server.js:248:20\nonPacket@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"}}
[task 2019-08-30T06:20:29.425Z] 06:20:29 INFO - PID 3504 | 1567146029401 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:29.444Z] 06:20:29 INFO - PID 3504 | 1567146029422 Marionette DEBUG 0 -> [0,40,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:29.445Z] 06:20:29 INFO - PID 3504 | 1567146029433 Marionette DEBUG 0 <- [1,40,null,{"value":null}]
[task 2019-08-30T06:20:29.451Z] 06:20:29 INFO - PID 3504 | 1567146029437 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:29.454Z] 06:20:29 INFO - PID 3504 | 1567146029449 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:29.465Z] 06:20:29 INFO - PID 3504 | 1567146029456 Marionette DEBUG 0 -> [0,41,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:29.469Z] 06:20:29 INFO - PID 3504 | 1567146029462 Marionette DEBUG 0 <- [1,41,null,{"value":"20"}]
[task 2019-08-30T06:20:29.477Z] 06:20:29 INFO - PID 3504 | 1567146029467 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:29.508Z] 06:20:29 INFO - PID 3504 | 1567146029478 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles[task 2019-08-30T06:20:29.509Z] 06:20:29 INFO - PID 3504 | 1567146029490 Marionette DEBUG 0 -> [0,42,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:29.509Z] 06:20:29 INFO - PID 3504 | 1567146029499 Marionette DEBUG 0 <- [1,42,null,["20"]]
[task 2019-08-30T06:20:29.516Z] 06:20:29 INFO - PID 3504 | 1567146029505 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:29.540Z] 06:20:29 INFO - PID 3504 | 1567146029516 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:29.542Z] 06:20:29 INFO - PID 3504 | 1567146029523 Marionette DEBUG 0 -> [0,43,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:29.543Z] 06:20:29 INFO - PID 3504 | 1567146029528 Marionette DEBUG 0 <- [1,43,null,{"value":null}]
[task 2019-08-30T06:20:29.543Z] 06:20:29 INFO - PID 3504 | 1567146029528 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:29.552Z] 06:20:29 INFO - PID 3504 | 1567146029538 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"width": 800, "height": 600}
[task 2019-08-30T06:20:29.581Z] 06:20:29 INFO - PID 3504 | 1567146029552 Marionette DEBUG 0 -> [0,44,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2019-08-30T06:20:29.856Z] 06:20:29 INFO - PID 3504 | 1567146029826 Marionette DEBUG 0 <- [1,44,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:29.856Z] 06:20:29 INFO - PID 3504 | 1567146029835 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:29.876Z] 06:20:29 INFO - PID 3504 | 1567146029853 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/frame {"id": null}
[task 2019-08-30T06:20:29.913Z] 06:20:29 INFO - PID 3504 | 1567146029891 Marionette DEBUG 0 -> [0,45,"WebDriver:SwitchToFrame",{}]
[task 2019-08-30T06:20:29.955Z] 06:20:29 INFO - PID 3504 | 1567146029935 Marionette DEBUG 0 <- [1,45,null,{"value":null}]
[task 2019-08-30T06:20:29.956Z] 06:20:29 INFO - PID 3504 | 1567146029938 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:29.993Z] 06:20:29 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/fullscreen_window/fullscreen.py::test_payload
[task 2019-08-30T06:20:29.999Z] 06:20:29 INFO - PID 3504 | 1567146029980 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"width": 800, "height": 600}
[task 2019-08-30T06:20:30.052Z] 06:20:30 INFO - PID 3504 | 1567146030026 Marionette DEBUG 0 -> [0,46,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2019-08-30T06:20:30.054Z] 06:20:30 INFO - PID 3504 | 1567146030031 Marionette DEBUG 0 <- [1,46,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:30.056Z] 06:20:30 INFO - PID 3504 | 1567146030035 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:30.079Z] 06:20:30 INFO - PID 3504 | 1567146030048 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"y": 100, "x": 100}
[task 2019-08-30T06:20:30.079Z] 06:20:30 INFO - PID 3504 | 1567146030061 Marionette DEBUG 0 -> [0,47,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2019-08-30T06:20:30.080Z] 06:20:30 INFO - PID 3504 | 1567146030069 Marionette DEBUG 0 <- [1,47,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:30.086Z] 06:20:30 INFO - PID 3504 | 1567146030073 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:30.108Z] 06:20:30 INFO - PID 3504 | 1567146030088 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/fullscreen {}
[task 2019-08-30T06:20:30.109Z] 06:20:30 INFO - PID 3504 | 1567146030095 Marionette DEBUG 0 -> [0,48,"WebDriver:FullscreenWindow",{}]
[task 2019-08-30T06:20:30.403Z] 06:20:30 INFO - PID 3504 | 1567146030388 Marionette DEBUG 0 <- [1,48,null,{"x":100,"y":100,"width":1600,"height":1200}]
[task 2019-08-30T06:20:30.404Z] 06:20:30 INFO - PID 3504 | 1567146030391 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":1600,"height":1200}}
[task 2019-08-30T06:20:30.404Z] 06:20:30 INFO - STDOUT: PASSED
[task 2019-08-30T06:20:30.434Z] 06:20:30 INFO - PID 3504 | 1567146030410 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"implicit": 0}
[task 2019-08-30T06:20:30.455Z] 06:20:30 INFO - PID 3504 | 1567146030429 Marionette DEBUG 0 -> [0,49,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2019-08-30T06:20:30.456Z] 06:20:30 INFO - PID 3504 | 1567146030439 Marionette DEBUG 0 <- [1,49,null,{"value":null}]
[task 2019-08-30T06:20:30.461Z] 06:20:30 INFO - PID 3504 | 1567146030446 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:30.490Z] 06:20:30 INFO - PID 3504 | 1567146030463 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"pageLoad": 300000}
[task 2019-08-30T06:20:30.496Z] 06:20:30 INFO - PID 3504 | 1567146030479 Marionette DEBUG 0 -> [0,50,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-08-30T06:20:30.497Z] 06:20:30 INFO - PID 3504 | 1567146030487 Marionette DEBUG 0 <- [1,50,null,{"value":null}]
[task 2019-08-30T06:20:30.524Z] 06:20:30 INFO - PID 3504 | 1567146030495 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:30.541Z] 06:20:30 INFO - PID 3504 | 1567146030514 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/timeouts {"script": 30000}
[task 2019-08-30T06:20:30.543Z] 06:20:30 INFO - PID 3504 | 1567146030529 Marionette DEBUG 0 -> [0,51,"WebDriver:SetTimeouts",{"script":30000}][task 2019-08-30T06:20:30.545Z] 06:20:30 INFO - PID 3504 | 1567146030532 Marionette DEBUG 0 <- [1,51,null,{"value":null}]
[task 2019-08-30T06:20:30.546Z] 06:20:30 INFO - PID 3504 | 1567146030536 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:30.578Z] 06:20:30 INFO - PID 3504 | 1567146030552 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:30.597Z] 06:20:30 INFO - PID 3504 | 1567146030567 Marionette DEBUG 0 -> [0,52,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:30.598Z] 06:20:30 INFO - PID 3504 | 1567146030574 Marionette DEBUG 0 <- [1,52,null,{"value":"20"}]
[task 2019-08-30T06:20:30.598Z] 06:20:30 INFO - PID 3504 | 1567146030584 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:30.625Z] 06:20:30 INFO - PID 3504 | 1567146030598 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:30.626Z] 06:20:30 INFO - PID 3504 | 1567146030607 Marionette DEBUG 0 -> [0,53,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:30.627Z] 06:20:30 INFO - PID 3504 | 1567146030611 Marionette DEBUG 0 <- [1,53,null,{"value":"20"}]
[task 2019-08-30T06:20:30.629Z] 06:20:30 INFO - PID 3504 | 1567146030613 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:30.630Z] 06:20:30 INFO - PID 3504 | 1567146030618 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:30.634Z] 06:20:30 INFO - PID 3504 | 1567146030627 Marionette DEBUG 0 -> [0,54,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:30.643Z] 06:20:30 INFO - PID 3504 | 1567146030634 Marionette DEBUG 0 <- [1,54,null,["20"]]
[task 2019-08-30T06:20:30.651Z] 06:20:30 INFO - PID 3504 | 1567146030639 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:30.681Z] 06:20:30 INFO - PID 3504 | 1567146030654 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:30.682Z] 06:20:30 INFO - PID 3504 | 1567146030670 Marionette DEBUG 0 -> [0,55,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:30.699Z] 06:20:30 INFO - PID 3504 | 1567146030679 Marionette DEBUG 0 <- [1,55,null,{"value":null}]
[task 2019-08-30T06:20:30.700Z] 06:20:30 INFO - PID 3504 | 1567146030684 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:30.703Z] 06:20:30 INFO - PID 3504 | 1567146030695 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/alert/dismiss {}
[task 2019-08-30T06:20:30.733Z] 06:20:30 INFO - PID 3504 | 1567146030710 Marionette DEBUG 0 -> [0,56,"WebDriver:DismissAlert",{}]
[task 2019-08-30T06:20:30.736Z] 06:20:30 INFO - PID 3504 | 1567146030720 Marionette DEBUG 0 <- [1,56,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAl ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2019-08-30T06:20:30.758Z] 06:20:30 INFO - PID 3504 | 1567146030729 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAlertError@chrome://marionette/content/error.js:376:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3379:11\nGeckoDriver.prototype.dismissDialog@chrome://marionette/content/driver.js:3293:8\ndespatch@chrome://marionette/content/server.js:305:40\nexecute@chrome://marionette/content/server.js:275:16\nonPacket/<@chrome://marionette/content/server.js:248:20\nonPacket@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"}}
[task 2019-08-30T06:20:30.773Z] 06:20:30 INFO - PID 3504 | 1567146030754 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:30.773Z] 06:20:30 INFO - PID 3504 | 1567146030767 Marionette DEBUG 0 -> [0,57,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:30.781Z] 06:20:30 INFO - PID 3504 | 1567146030772 Marionette DEBUG 0 <- [1,57,null,{"value":null}]
[task 2019-08-30T06:20:30.782Z] 06:20:30 INFO - PID 3504 | 1567146030773 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:30.803Z] 06:20:30 INFO - PID 3504 | 1567146030781 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window
[task 2019-08-30T06:20:30.828Z] 06:20:30 INFO - PID 3504 | 1567146030794 Marionette DEBUG 0 -> [0,58,"WebDriver:GetWindowHandle",{}]
[task 2019-08-30T06:20:30.830Z] 06:20:30 INFO - PID 3504 | 1567146030799 Marionette DEBUG 0 <- [1,58,null,{"value":"20"}]
[task 2019-08-30T06:20:30.830Z] 06:20:30 INFO - PID 3504 | 1567146030804 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2019-08-30T06:20:30.832Z] 06:20:30 INFO - PID 3504 | 1567146030821 webdriver::server DEBUG -> GET /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/handles
[task 2019-08-30T06:20:30.858Z] 06:20:30 INFO - PID 3504 | 1567146030840 Marionette DEBUG 0 -> [0,59,"WebDriver:GetWindowHandles",{}]
[task 2019-08-30T06:20:30.862Z] 06:20:30 INFO - PID 3504 | 1567146030843 Marionette DEBUG 0 <- [1,59,null,["20"]]
[task 2019-08-30T06:20:30.864Z] 06:20:30 INFO - PID 3504 | 1567146030844 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2019-08-30T06:20:30.865Z] 06:20:30 INFO - PID 3504 | 1567146030853 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window {"handle": "20"}
[task 2019-08-30T06:20:30.894Z] 06:20:30 INFO - PID 3504 | 1567146030863 Marionette DEBUG 0 -> [0,60,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2019-08-30T06:20:30.895Z] 06:20:30 INFO - PID 3504 | 1567146030876 Marionette DEBUG 0 <- [1,60,null,{"value":null}]
[task 2019-08-30T06:20:30.914Z] 06:20:30 INFO - PID 3504 | 1567146030883 webdriver::server DEBUG <- 200 OK {"value":null}[task 2019-08-30T06:20:30.915Z] 06:20:30 INFO - PID 3504 | 1567146030897 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"width": 800, "height": 600}
[task 2019-08-30T06:20:30.915Z] 06:20:30 INFO - PID 3504 | 1567146030910 Marionette DEBUG 0 -> [0,61,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2019-08-30T06:20:31.237Z] 06:20:31 INFO - PID 3504 | 1567146031215 Marionette DEBUG 0 <- [1,61,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:31.238Z] 06:20:31 INFO - PID 3504 | 1567146031224 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:31.253Z] 06:20:31 INFO - PID 3504 | 1567146031240 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/frame {"id": null}
[task 2019-08-30T06:20:31.254Z] 06:20:31 INFO - PID 3504 | 1567146031247 Marionette DEBUG 0 -> [0,62,"WebDriver:SwitchToFrame",{}]
[task 2019-08-30T06:20:31.283Z] 06:20:31 INFO - PID 3504 | 1567146031264 Marionette DEBUG 0 <- [1,62,null,{"value":null}]
[task 2019-08-30T06:20:31.284Z] 06:20:31 INFO - PID 3504 | 1567146031270 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-08-30T06:20:31.310Z] 06:20:31 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/fullscreen_window/fullscreen.py::test_fullscreen_twice_is_idempotent
[task 2019-08-30T06:20:31.331Z] 06:20:31 INFO - PID 3504 | 1567146031312 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"width": 800, "height": 600}
[task 2019-08-30T06:20:31.355Z] 06:20:31 INFO - PID 3504 | 1567146031324 Marionette DEBUG 0 -> [0,63,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2019-08-30T06:20:31.356Z] 06:20:31 INFO - PID 3504 | 1567146031333 Marionette DEBUG 0 <- [1,63,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:31.376Z] 06:20:31 INFO - PID 3504 | 1567146031341 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:31.387Z] 06:20:31 INFO - PID 3504 | 1567146031363 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/rect {"y": 100, "x": 100}
[task 2019-08-30T06:20:31.390Z] 06:20:31 INFO - PID 3504 | 1567146031377 Marionette DEBUG 0 -> [0,64,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2019-08-30T06:20:31.390Z] 06:20:31 INFO - PID 3504 | 1567146031382 Marionette DEBUG 0 <- [1,64,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-08-30T06:20:31.422Z] 06:20:31 INFO - PID 3504 | 1567146031390 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-08-30T06:20:31.440Z] 06:20:31 INFO - PID 3504 | 1567146031420 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/execute/sync {"args": [], "script": "\n return !!(window.fullScreen || document.webkitIsFullScreen)\n "}
[task 2019-08-30T06:20:31.441Z] 06:20:31 INFO - PID 3504 | 1567146031435 Marionette DEBUG 0 -> [0,65,"WebDriver:ExecuteScript",{"args":[],"script":"\n return !!(window.fullScreen || document.webkitIsFullScreen)\n "}]
[task 2019-08-30T06:20:31.498Z] 06:20:31 INFO - PID 3504 | 1567146031474 Marionette DEBUG 0 <- [1,65,null,{"value":false}]
[task 2019-08-30T06:20:31.499Z] 06:20:31 INFO - PID 3504 | 1567146031481 webdriver::server DEBUG <- 200 OK {"value":false}
[task 2019-08-30T06:20:31.519Z] 06:20:31 INFO - PID 3504 | 1567146031497 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/window/fullscreen {}
[task 2019-08-30T06:20:31.520Z] 06:20:31 INFO - PID 3504 | 1567146031508 Marionette DEBUG 0 -> [0,66,"WebDriver:FullscreenWindow",{}]
[task 2019-08-30T06:20:31.801Z] 06:20:31 INFO - PID 3504 | 1567146031782 Marionette DEBUG 0 <- [1,66,null,{"x":100,"y":100,"width":1600,"height":1200}]
[task 2019-08-30T06:20:31.802Z] 06:20:31 INFO - PID 3504 | 1567146031785 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":1600,"height":1200}}
[task 2019-08-30T06:20:31.803Z] 06:20:31 INFO - PID 3504 | 1567146031791 webdriver::server DEBUG -> POST /session/c7eb56e4-dcc7-44ad-a2f3-03d43b879920/execute/sync {"args": [], "script": "\n return !!(window.fullScreen || document.webkitIsFullScreen)\n "}
[task 2019-08-30T06:20:31.803Z] 06:20:31 INFO - PID 3504 | 1567146031798 Marionette DEBUG 0 -> [0,67,"WebDriver:ExecuteScript",{"args":[],"script":"\n return !!(window.fullScreen || document.webkitIsFullScreen)\n "}]
[task 2019-08-30T06:20:31.878Z] 06:20:31 INFO - PID 3504 | 1567146031846 Marionette DEBUG 0 <- [1,67,null,{"value":true}]
[task 2019-08-30T06:20:31.878Z] 06:20:31 INFO - PID 3504 | 1567146031854 webdriver::server DEBUG <- 200 OK {"value":true}
[task 2019-08-30T06:20:31.878Z] 06:20:31 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/fullscreen_window/fullscreen.py | expected OK

Look how long this opt build took to start-up:

[task 2019-08-30T06:20:02.941Z] 06:20:02 INFO - PID 3504 | 1567146002931 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828
[task 2019-08-30T06:20:03.819Z] 06:20:03 INFO - PID 3504 | *** You are running in headless mode.
[task 2019-08-30T06:20:05.198Z] 06:20:05 INFO - PID 3504 | 1567146005167 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-08-30T06:20:05.199Z] 06:20:05 INFO - PID 3504 | 1567146005169 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
[task 2019-08-30T06:20:05.199Z] 06:20:05 INFO - PID 3504 | 1567146005170 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-08-30T06:20:05.199Z] 06:20:05 INFO - PID 3504 | 1567146005170 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
[task 2019-08-30T06:20:05.509Z] 06:20:05 INFO - PID 3504 | 1567146005494 Marionette TRACE Received observer notification profile-after-change
[task 2019-08-30T06:20:05.590Z] 06:20:05 INFO - PID 3504 | 1567146005568 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-08-30T06:20:05.687Z] 06:20:05 INFO - PID 3504 | 1567146005661 Marionette TRACE Received observer notification command-line-startup
[task 2019-08-30T06:20:05.688Z] 06:20:05 INFO - PID 3504 | 1567146005663 Marionette TRACE Received observer notification nsPref:changed
[task 2019-08-30T06:20:05.688Z] 06:20:05 INFO - PID 3504 | 1567146005663 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2019-08-30T06:20:05.917Z] 06:20:05 INFO - PID 3504 | 1567146005900 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-08-30T06:20:22.538Z] 06:20:22 INFO - PID 3504 | 1567146022516 Marionette TRACE Received observer notification marionette-startup-requested

There is a gap of 17 seconds between the toplevel-window-ready and marionette-startup-requested observer notifications.

I will file a tracking bug for that.

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 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.