could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
13:21:15 INFO - 1498854075181 Marionette TRACE 359 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
13:21:15 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498854075185 Marionette TRACE 359 <- [1,5,null,{"value":null}]
13:21:15 INFO - 1498854075187 Marionette TRACE 359 -> [0,6,"deleteSession",{}]
13:21:15 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 308ms
13:21:15 INFO - 1498854075189 Marionette TRACE 359 <- [1,6,null,{}]
13:21:15 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:21:15 INFO - 1498854075193 Marionette DEBUG Closed connection 359
13:21:15 INFO - 1498854075194 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49727
13:21:15 INFO - 1498854075195 Marionette DEBUG Closed connection 360
13:21:15 INFO - 1498854075195 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49728
13:21:15 INFO - 1498854075197 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:15 INFO - 1498854075199 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075202 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075209 Marionette TRACE 361 <- [1,1,null,{"sessionId":"922f39c4-9120-4e29-a97b-2b60f9c124a4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075210 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
13:21:15 INFO - 1498854075211 Marionette TRACE 361 <- [1,2,null,{}]
13:21:15 INFO - 1498854075212 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:21:15 INFO - 1498854075213 Marionette TRACE 361 <- [1,3,null,{}]
13:21:15 INFO - 1498854075215 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
13:21:15 INFO - 1498854075216 Marionette TRACE 361 <- [1,4,null,{}]
13:21:15 INFO - 1498854075218 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
13:21:15 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498854075222 Marionette TRACE 361 <- [1,5,null,{"value":null}]
13:21:15 INFO - 1498854075225 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
13:21:15 INFO - 1498854075228 Marionette TRACE 361 <- [1,6,null,{}]
13:21:15 INFO - 1498854075230 Marionette DEBUG Closed connection 361
13:21:15 INFO - 1498854075231 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49729
13:21:15 INFO - 1498854075232 Marionette DEBUG Closed connection 362
13:21:15 INFO - 1498854075233 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49730
13:21:15 INFO - 1498854075234 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:15 INFO - 1498854075236 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075239 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075245 Marionette TRACE 363 <- [1,1,null,{"sessionId":"5a44f6ee-4c35-491f-a9f2-2a36d287bfce","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075246 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
13:21:15 INFO - 1498854075248 Marionette TRACE 363 <- [1,2,null,{}]
13:21:15 INFO - 1498854075251 Marionette DEBUG Closed connection 363
13:21:15 INFO - 1498854075251 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49731
13:21:15 INFO - 1498854075253 Marionette DEBUG Closed connection 364
13:21:15 INFO - 1498854075253 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49732
13:21:15 INFO - 1498854075254 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:21:15 INFO - 1498854075256 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075259 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075263 Marionette TRACE 365 <- [1,1,null,{"sessionId":"c70d53c8-fd6b-4511-8896-33aa8a7bc884","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075264 Marionette TRACE 365 -> [0,2,"get",{"url":"http://127.0.0.1:49331/clicks.html"}]
13:21:15 INFO - 1498854075267 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/hidden.html"
13:21:15 INFO - 1498854075276 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/hidden.html"
13:21:15 INFO - 1498854075277 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/hidden.html"
13:21:15 INFO - 1498854075284 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/clicks.html"
13:21:15 INFO - 1498854075290 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/clicks.html"
13:21:15 INFO - 1498854075297 Marionette TRACE 365 <- [1,2,null,{}]
13:21:15 INFO - 1498854075304 Marionette TRACE 365 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:21:15 INFO - 1498854075308 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93797c8c-ad63-441e-9d60-49966ea66518","ELEMENT":"93797c8c-ad63-441e-9d60-49966ea66518"}}]
13:21:15 INFO - 1498854075309 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"93797c8c-ad63-441e-9d60-49966ea66518"}]
13:21:15 INFO - 1498854075354 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/clicks.html"
13:21:15 INFO - 1498854075361 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/clicks.html"
13:21:15 INFO - 1498854075361 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/clicks.html"
13:21:15 INFO - 1498854075369 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/test.html"
13:21:15 INFO - 1498854075372 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/test.html"
13:21:15 INFO - 1498854075378 Marionette TRACE 365 <- [1,4,null,{}]
13:21:15 INFO - 1498854075387 Marionette TRACE 365 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:21:15 INFO - 1498854075390 Marionette TRACE 365 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d8d0ddd8-41b9-45c2-ac81-a96705c722b0","ELEMENT":"d8d0ddd8-41b9-45c2-ac81-a96705c722b0"}}]
13:21:15 INFO - 1498854075391 Marionette TRACE 365 -> [0,6,"getTitle",{}]
13:21:15 INFO - 1498854075393 Marionette TRACE 365 <- [1,6,null,{"value":"Marionette Test"}]
13:21:15 INFO - 1498854075395 Marionette TRACE 365 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
13:21:15 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498854075401 Marionette TRACE 365 <- [1,7,null,{"value":null}]
13:21:15 INFO - 1498854075402 Marionette TRACE 365 -> [0,8,"deleteSession",{}]
13:21:15 INFO - 1498854075404 Marionette TRACE 365 <- [1,8,null,{}]
13:21:15 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 213ms
13:21:15 INFO - TEST-START | test_click.py TestClick.test_container_element
13:21:15 INFO - 1498854075406 Marionette DEBUG Closed connection 365
13:21:15 INFO - 1498854075406 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49735
13:21:15 INFO - 1498854075407 Marionette DEBUG Closed connection 366
13:21:15 INFO - 1498854075408 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49736
13:21:15 INFO - 1498854075409 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:15 INFO - 1498854075410 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075414 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075418 Marionette TRACE 367 <- [1,1,null,{"sessionId":"4262ff0b-20e0-4202-abbc-ced73bdda8d4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075420 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
13:21:15 INFO - 1498854075421 Marionette TRACE 367 <- [1,2,null,{}]
13:21:15 INFO - 1498854075422 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:21:15 INFO - 1498854075423 Marionette TRACE 367 <- [1,3,null,{}]
13:21:15 INFO - 1498854075423 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
13:21:15 INFO - 1498854075424 Marionette TRACE 367 <- [1,4,null,{}]
13:21:15 INFO - 1498854075426 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
13:21:15 INFO - TEST-START: test_click.py TestClick.test_container_element1498854075429 Marionette TRACE 367 <- [1,5,null,{"value":null}]
13:21:15 INFO - 1498854075430 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
13:21:15 INFO - 1498854075432 Marionette TRACE 367 <- [1,6,null,{}]
13:21:15 INFO - 1498854075434 Marionette DEBUG Closed connection 367
13:21:15 INFO - 1498854075435 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49737
13:21:15 INFO - 1498854075436 Marionette DEBUG Closed connection 368
13:21:15 INFO - 1498854075436 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49738
13:21:15 INFO - 1498854075437 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:15 INFO - 1498854075438 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075441 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075448 Marionette TRACE 369 <- [1,1,null,{"sessionId":"3ab2a47c-3703-4134-b337-7e797bff28f8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075449 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
13:21:15 INFO - 1498854075451 Marionette TRACE 369 <- [1,2,null,{}]
13:21:15 INFO - 1498854075454 Marionette DEBUG Closed connection 369
13:21:15 INFO - 1498854075456 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49739
13:21:15 INFO - 1498854075457 Marionette DEBUG Closed connection 370
13:21:15 INFO - 1498854075457 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49740
13:21:15 INFO - 1498854075459 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:21:15 INFO - 1498854075459 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075462 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075467 Marionette TRACE 371 <- [1,1,null,{"sessionId":"178bb3ac-5a05-474e-9fb4-d8751df5ffe0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075468 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:21:15 INFO - 1498854075470 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/test.html"
13:21:15 INFO - 1498854075472 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/test.html"
13:21:15 INFO - 1498854075473 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/test.html"
13:21:15 INFO - 1498854075480 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:15 INFO - 1498854075485 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:15 INFO - 1498854075493 Marionette TRACE 371 <- [1,2,null,{}]
13:21:15 INFO - 1498854075501 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:21:15 INFO - 1498854075505 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cd4197e5-65c8-43b7-85e3-11b4546b6124","ELEMENT":"cd4197e5-65c8-43b7-85e3-11b4546b6124"}}]
13:21:15 INFO - 1498854075506 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"cd4197e5-65c8-43b7-85e3-11b4546b6124"}]
13:21:15 INFO - 1498854075754 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:21:15 INFO - 1498854075755 Marionette TRACE 371 <- [1,4,null,{}]
13:21:15 INFO - 1498854075756 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"cd4197e5-65c8-43b7-85e3-11b4546b6124","name":"selected"}]
13:21:15 INFO - 1498854075761 Marionette TRACE 371 <- [1,5,null,{"value":true}]
13:21:15 INFO - 1498854075763 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
13:21:15 INFO - TEST-END: test_click.py TestClick.test_container_element1498854075766 Marionette TRACE 371 <- [1,6,null,{"value":null}]
13:21:15 INFO - 1498854075767 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
13:21:15 INFO - 1498854075769 Marionette TRACE 371 <- [1,7,null,{}]
13:21:15 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 365ms
13:21:15 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:21:15 INFO - 1498854075770 Marionette DEBUG Closed connection 371
13:21:15 INFO - 1498854075771 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49741
13:21:15 INFO - 1498854075772 Marionette DEBUG Closed connection 372
13:21:15 INFO - 1498854075772 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49742
13:21:15 INFO - 1498854075774 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:15 INFO - 1498854075775 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075778 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075781 Marionette TRACE 373 <- [1,1,null,{"sessionId":"adb15c5a-8f68-4862-9e20-ae3cc8d37a77","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075782 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
13:21:15 INFO - 1498854075783 Marionette TRACE 373 <- [1,2,null,{}]
13:21:15 INFO - 1498854075784 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:21:15 INFO - 1498854075785 Marionette TRACE 373 <- [1,3,null,{}]
13:21:15 INFO - 1498854075786 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
13:21:15 INFO - 1498854075787 Marionette TRACE 373 <- [1,4,null,{}]
13:21:15 INFO - 1498854075788 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
13:21:15 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498854075791 Marionette TRACE 373 <- [1,5,null,{"value":null}]
13:21:15 INFO - 1498854075793 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
13:21:15 INFO - 1498854075794 Marionette TRACE 373 <- [1,6,null,{}]
13:21:15 INFO - 1498854075796 Marionette DEBUG Closed connection 373
13:21:15 INFO - 1498854075797 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49743
13:21:15 INFO - 1498854075797 Marionette DEBUG Closed connection 374
13:21:15 INFO - 1498854075798 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49744
13:21:15 INFO - 1498854075799 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:15 INFO - 1498854075800 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075803 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075806 Marionette TRACE 375 <- [1,1,null,{"sessionId":"25125215-f6e1-41aa-9b43-bcfa11be00e9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075807 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
13:21:15 INFO - 1498854075809 Marionette TRACE 375 <- [1,2,null,{}]
13:21:15 INFO - 1498854075810 Marionette DEBUG Closed connection 375
13:21:15 INFO - 1498854075811 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49745
13:21:15 INFO - 1498854075812 Marionette DEBUG Closed connection 376
13:21:15 INFO - 1498854075813 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49746
13:21:15 INFO - 1498854075815 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:21:15 INFO - 1498854075816 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:15 INFO - 1498854075818 Marionette DEBUG Register listener.js for window 2147483649
13:21:15 INFO - 1498854075822 Marionette TRACE 377 <- [1,1,null,{"sessionId":"1ccb793e-bf0b-4ae6-9be0-d07c13713acd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:15 INFO - 1498854075823 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:21:15 INFO - 1498854075825 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:15 INFO - 1498854075827 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:15 INFO - 1498854075828 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:15 INFO - 1498854075834 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:15 INFO - 1498854075838 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:15 INFO - 1498854075843 Marionette TRACE 377 <- [1,2,null,{}]
13:21:15 INFO - 1498854075847 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:21:15 INFO - 1498854075855 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"66a7ebb7-a4da-4dfe-a0b5-1fcf616172ea","ELEMENT":"66a7ebb7-a4da-4dfe-a0b5-1fcf616172ea"}}]
13:21:15 INFO - 1498854075856 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"66a7ebb7-a4da-4dfe-a0b5-1fcf616172ea"}]
13:21:16 INFO - 1498854076101 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:21:16 INFO - 1498854076102 Marionette TRACE 377 <- [1,4,null,{}]
13:21:16 INFO - 1498854076103 Marionette TRACE 377 -> [0,5,"getElementProperty",{"id":"66a7ebb7-a4da-4dfe-a0b5-1fcf616172ea","name":"selected"}]
13:21:16 INFO - 1498854076108 Marionette TRACE 377 <- [1,5,null,{"value":true}]
13:21:16 INFO - 1498854076110 Marionette TRACE 377 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
13:21:16 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498854076113 Marionette TRACE 377 <- [1,6,null,{"value":null}]
13:21:16 INFO - 1498854076114 Marionette TRACE 377 -> [0,7,"deleteSession",{}]
13:21:16 INFO - 1498854076116 Marionette TRACE 377 <- [1,7,null,{}]
13:21:16 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 347ms
13:21:16 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:21:16 INFO - 1498854076117 Marionette DEBUG Closed connection 377
13:21:16 INFO - 1498854076118 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49747
13:21:16 INFO - 1498854076119 Marionette DEBUG Closed connection 378
13:21:16 INFO - 1498854076120 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49748
13:21:16 INFO - 1498854076121 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:16 INFO - 1498854076122 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076124 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076128 Marionette TRACE 379 <- [1,1,null,{"sessionId":"54fd0763-610c-4dcd-a13f-eaaca7e02dd5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076130 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
13:21:16 INFO - 1498854076130 Marionette TRACE 379 <- [1,2,null,{}]
13:21:16 INFO - 1498854076131 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:21:16 INFO - 1498854076132 Marionette TRACE 379 <- [1,3,null,{}]
13:21:16 INFO - 1498854076133 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
13:21:16 INFO - 1498854076133 Marionette TRACE 379 <- [1,4,null,{}]
13:21:16 INFO - 1498854076135 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
13:21:16 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498854076138 Marionette TRACE 379 <- [1,5,null,{"value":null}]
13:21:16 INFO - 1498854076139 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
13:21:16 INFO - 1498854076141 Marionette TRACE 379 <- [1,6,null,{}]
13:21:16 INFO - 1498854076142 Marionette DEBUG Closed connection 379
13:21:16 INFO - 1498854076143 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49749
13:21:16 INFO - 1498854076144 Marionette DEBUG Closed connection 380
13:21:16 INFO - 1498854076145 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49750
13:21:16 INFO - 1498854076146 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:16 INFO - 1498854076147 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076152 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076156 Marionette TRACE 381 <- [1,1,null,{"sessionId":"48a42b16-dd87-4162-8601-fa3b06832070","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076157 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
13:21:16 INFO - 1498854076159 Marionette TRACE 381 <- [1,2,null,{}]
13:21:16 INFO - 1498854076161 Marionette DEBUG Closed connection 381
13:21:16 INFO - 1498854076162 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49751
13:21:16 INFO - 1498854076162 Marionette DEBUG Closed connection 382
13:21:16 INFO - 1498854076163 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49752
13:21:16 INFO - 1498854076164 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:21:16 INFO - 1498854076165 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076168 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076171 Marionette TRACE 383 <- [1,1,null,{"sessionId":"944c8b23-fd43-4a7b-9759-8be11cfbf265","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076173 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
13:21:16 INFO - 1498854076175 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:16 INFO - 1498854076178 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:16 INFO - 1498854076178 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:16 INFO - 1498854076187 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:21:16 INFO - 1498854076191 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:21:16 INFO - 1498854076202 Marionette TRACE 383 <- [1,2,null,{}]
13:21:16 INFO - 1498854076207 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:21:16 INFO - 1498854076210 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6a10eff7-fe64-4449-842a-6d5e969b396c","ELEMENT":"6a10eff7-fe64-4449-842a-6d5e969b396c"}}]
13:21:16 INFO - 1498854076211 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"6a10eff7-fe64-4449-842a-6d5e969b396c"}]
13:21:16 INFO - 1498854076454 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:21:16 INFO - 1498854076455 Marionette TRACE 383 <- [1,4,null,{}]
13:21:16 INFO - 1498854076457 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
13:21:16 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498854076460 Marionette TRACE 383 <- [1,5,null,{"value":null}]
13:21:16 INFO - 1498854076461 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
13:21:16 INFO - 1498854076463 Marionette TRACE 383 <- [1,6,null,{}]
13:21:16 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
13:21:16 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:21:16 INFO - 1498854076464 Marionette DEBUG Closed connection 383
13:21:16 INFO - 1498854076465 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49753
13:21:16 INFO - 1498854076466 Marionette DEBUG Closed connection 384
13:21:16 INFO - 1498854076466 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49754
13:21:16 INFO - 1498854076467 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:16 INFO - 1498854076468 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076471 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076474 Marionette TRACE 385 <- [1,1,null,{"sessionId":"cf01a8b3-4cd7-4b2c-a8aa-09685072d59d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076476 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
13:21:16 INFO - 1498854076476 Marionette TRACE 385 <- [1,2,null,{}]
13:21:16 INFO - 1498854076477 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:21:16 INFO - 1498854076478 Marionette TRACE 385 <- [1,3,null,{}]
13:21:16 INFO - 1498854076479 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
13:21:16 INFO - 1498854076479 Marionette TRACE 385 <- [1,4,null,{}]
13:21:16 INFO - 1498854076481 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
13:21:16 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498854076485 Marionette TRACE 385 <- [1,5,null,{"value":null}]
13:21:16 INFO - 1498854076486 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
13:21:16 INFO - 1498854076487 Marionette TRACE 385 <- [1,6,null,{}]
13:21:16 INFO - 1498854076489 Marionette DEBUG Closed connection 385
13:21:16 INFO - 1498854076490 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49755
13:21:16 INFO - 1498854076490 Marionette DEBUG Closed connection 386
13:21:16 INFO - 1498854076491 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49756
13:21:16 INFO - 1498854076492 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:16 INFO - 1498854076493 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076496 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076499 Marionette TRACE 387 <- [1,1,null,{"sessionId":"f9c59fae-eea4-486e-ade0-6e2ec2ed872d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076501 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
13:21:16 INFO - 1498854076502 Marionette TRACE 387 <- [1,2,null,{}]
13:21:16 INFO - 1498854076504 Marionette DEBUG Closed connection 387
13:21:16 INFO - 1498854076504 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49757
13:21:16 INFO - 1498854076505 Marionette DEBUG Closed connection 388
13:21:16 INFO - 1498854076506 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49758
13:21:16 INFO - 1498854076507 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:21:16 INFO - 1498854076507 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076510 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076514 Marionette TRACE 389 <- [1,1,null,{"sessionId":"a468330e-9296-4386-aa15-88419056076f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076515 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:21:16 INFO - 1498854076517 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:21:16 INFO - 1498854076519 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:21:16 INFO - 1498854076519 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:21:16 INFO - 1498854076525 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:16 INFO - 1498854076529 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:16 INFO - 1498854076536 Marionette TRACE 389 <- [1,2,null,{}]
13:21:16 INFO - 1498854076540 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:21:16 INFO - 1498854076543 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8069046d-9e85-4144-a136-bff6c43c00be","ELEMENT":"8069046d-9e85-4144-a136-bff6c43c00be"}}]
13:21:16 INFO - 1498854076544 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"8069046d-9e85-4144-a136-bff6c43c00be"}]
13:21:16 INFO - 1498854076786 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:21:16 INFO - 1498854076787 Marionette TRACE 389 <- [1,4,null,{}]
13:21:16 INFO - 1498854076789 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
13:21:16 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498854076794 Marionette TRACE 389 <- [1,5,null,{"value":null}]
13:21:16 INFO - 1498854076795 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
13:21:16 INFO - 1498854076797 Marionette TRACE 389 <- [1,6,null,{}]
13:21:16 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 334ms
13:21:16 INFO - TEST-START | test_click.py TestClick.test_input_file
13:21:16 INFO - 1498854076798 Marionette DEBUG Closed connection 389
13:21:16 INFO - 1498854076799 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49759
13:21:16 INFO - 1498854076800 Marionette DEBUG Closed connection 390
13:21:16 INFO - 1498854076800 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49760
13:21:16 INFO - 1498854076802 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:16 INFO - 1498854076802 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076805 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076817 Marionette TRACE 391 <- [1,1,null,{"sessionId":"7f599a9f-8b9a-4efe-b1f0-bcf971b5d990","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076819 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
13:21:16 INFO - 1498854076819 Marionette TRACE 391 <- [1,2,null,{}]
13:21:16 INFO - 1498854076820 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:21:16 INFO - 1498854076821 Marionette TRACE 391 <- [1,3,null,{}]
13:21:16 INFO - 1498854076822 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
13:21:16 INFO - 1498854076822 Marionette TRACE 391 <- [1,4,null,{}]
13:21:16 INFO - 1498854076824 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
13:21:16 INFO - TEST-START: test_click.py TestClick.test_input_file1498854076827 Marionette TRACE 391 <- [1,5,null,{"value":null}]
13:21:16 INFO - 1498854076828 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
13:21:16 INFO - 1498854076830 Marionette TRACE 391 <- [1,6,null,{}]
13:21:16 INFO - 1498854076832 Marionette DEBUG Closed connection 391
13:21:16 INFO - 1498854076832 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49761
13:21:16 INFO - 1498854076833 Marionette DEBUG Closed connection 392
13:21:16 INFO - 1498854076834 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49762
13:21:16 INFO - 1498854076835 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:16 INFO - 1498854076836 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076838 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076842 Marionette TRACE 393 <- [1,1,null,{"sessionId":"5ddb2135-acde-443a-b440-38fc8c9a1a42","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076843 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
13:21:16 INFO - 1498854076845 Marionette TRACE 393 <- [1,2,null,{}]
13:21:16 INFO - 1498854076846 Marionette DEBUG Closed connection 393
13:21:16 INFO - 1498854076847 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49763
13:21:16 INFO - 1498854076848 Marionette DEBUG Closed connection 394
13:21:16 INFO - 1498854076848 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49764
13:21:16 INFO - 1498854076850 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:21:16 INFO - 1498854076850 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076853 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076856 Marionette TRACE 395 <- [1,1,null,{"sessionId":"dcad4a71-b752-4002-a605-2472acbbc03e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076857 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:21:16 INFO - 1498854076859 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:16 INFO - 1498854076861 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:16 INFO - 1498854076862 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:21:16 INFO - 1498854076869 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:21:16 INFO - 1498854076874 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:21:16 INFO - 1498854076876 Marionette TRACE 395 <- [1,2,null,{}]
13:21:16 INFO - 1498854076879 Marionette TRACE 395 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:21:16 INFO - 1498854076886 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ddde33f-2179-47e0-96f5-c25eb0b1087c","ELEMENT":"2ddde33f-2179-47e0-96f5-c25eb0b1087c"}}]
13:21:16 INFO - 1498854076887 Marionette TRACE 395 -> [0,4,"clickElement",{"id":"2ddde33f-2179-47e0-96f5-c25eb0b1087c"}]
13:21:16 INFO - 1498854076916 Marionette TRACE 395 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
13:21:16 INFO - 1498854076918 Marionette TRACE 395 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
13:21:16 INFO - TEST-END: test_click.py TestClick.test_input_file1498854076921 Marionette TRACE 395 <- [1,5,null,{"value":null}]
13:21:16 INFO - 1498854076922 Marionette TRACE 395 -> [0,6,"deleteSession",{}]
13:21:16 INFO - 1498854076924 Marionette TRACE 395 <- [1,6,null,{}]
13:21:16 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 127ms
13:21:16 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:21:16 INFO - 1498854076925 Marionette DEBUG Closed connection 395
13:21:16 INFO - 1498854076926 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49765
13:21:16 INFO - 1498854076927 Marionette DEBUG Closed connection 396
13:21:16 INFO - 1498854076927 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49766
13:21:16 INFO - 1498854076928 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:16 INFO - 1498854076929 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076936 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076940 Marionette TRACE 397 <- [1,1,null,{"sessionId":"658f6206-0733-4983-8626-759791d53514","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076941 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
13:21:16 INFO - 1498854076942 Marionette TRACE 397 <- [1,2,null,{}]
13:21:16 INFO - 1498854076943 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:21:16 INFO - 1498854076943 Marionette TRACE 397 <- [1,3,null,{}]
13:21:16 INFO - 1498854076944 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
13:21:16 INFO - 1498854076945 Marionette TRACE 397 <- [1,4,null,{}]
13:21:16 INFO - 1498854076947 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
13:21:16 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498854076950 Marionette TRACE 397 <- [1,5,null,{"value":null}]
13:21:16 INFO - 1498854076950 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
13:21:16 INFO - 1498854076952 Marionette TRACE 397 <- [1,6,null,{}]
13:21:16 INFO - 1498854076954 Marionette DEBUG Closed connection 397
13:21:16 INFO - 1498854076954 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49767
13:21:16 INFO - 1498854076955 Marionette DEBUG Closed connection 398
13:21:16 INFO - 1498854076956 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49768
13:21:16 INFO - 1498854076957 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:16 INFO - 1498854076958 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076960 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076964 Marionette TRACE 399 <- [1,1,null,{"sessionId":"031a9134-b7ec-4bc7-aef2-052482859fc0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076965 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
13:21:16 INFO - 1498854076967 Marionette TRACE 399 <- [1,2,null,{}]
13:21:16 INFO - 1498854076968 Marionette DEBUG Closed connection 399
13:21:16 INFO - 1498854076969 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49769
13:21:16 INFO - 1498854076970 Marionette DEBUG Closed connection 400
13:21:16 INFO - 1498854076970 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49770
13:21:16 INFO - 1498854076971 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:21:16 INFO - 1498854076972 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:16 INFO - 1498854076975 Marionette DEBUG Register listener.js for window 2147483649
13:21:16 INFO - 1498854076981 Marionette TRACE 401 <- [1,1,null,{"sessionId":"2b8f4af8-79a0-4c6c-a63b-d1598a12e308","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:16 INFO - 1498854076983 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
13:21:16 INFO - 1498854076984 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:21:16 INFO - 1498854076987 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:21:16 INFO - 1498854076987 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:21:16 INFO - 1498854076993 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:21:17 INFO - 1498854076997 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:21:17 INFO - 1498854077004 Marionette TRACE 401 <- [1,2,null,{}]
13:21:17 INFO - 1498854077008 Marionette TRACE 401 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:21:17 INFO - 1498854077011 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fefb80c7-a8fc-4186-b33c-07eba487a894","ELEMENT":"fefb80c7-a8fc-4186-b33c-07eba487a894"}}]
13:21:17 INFO - 1498854077012 Marionette TRACE 401 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:21:17 INFO - 1498854077017 Marionette TRACE 401 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4a9eb3a3-be78-4cee-b7f6-cfc8a670db65","ELEMENT":"4a9eb3a3-be78-4cee-b7f6-cfc8a670db65"}}]
13:21:17 INFO - 1498854077019 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"fefb80c7-a8fc-4186-b33c-07eba487a894"}]
13:21:17 INFO - 1498854077265 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:21:17 INFO - 1498854077266 Marionette TRACE 401 <- [1,5,null,{}]
13:21:17 INFO - 1498854077267 Marionette TRACE 401 -> [0,6,"clickElement",{"id":"4a9eb3a3-be78-4cee-b7f6-cfc8a670db65"}]
13:21:17 INFO - 1498854077284 Marionette TRACE 401 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
13:21:17 INFO - 1498854077286 Marionette TRACE 401 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:21:17 INFO - 1498854077291 Marionette TRACE 401 <- [1,7,null,{"value":false}]
13:21:17 INFO - 1498854077292 Marionette TRACE 401 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
13:21:17 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498854077295 Marionette TRACE 401 <- [1,8,null,{"value":null}]
13:21:17 INFO - 1498854077297 Marionette TRACE 401 -> [0,9,"deleteSession",{}]
13:21:17 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 375ms
13:21:17 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:21:17 INFO - 1498854077299 Marionette TRACE 401 <- [1,9,null,{}]
13:21:17 INFO - 1498854077300 Marionette DEBUG Closed connection 401
13:21:17 INFO - 1498854077301 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49771
13:21:17 INFO - 1498854077302 Marionette DEBUG Closed connection 402
13:21:17 INFO - 1498854077302 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49772
13:21:17 INFO - 1498854077303 Marionette TRACE 403 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:17 INFO - 1498854077304 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:17 INFO - 1498854077307 Marionette DEBUG Register listener.js for window 2147483649
13:21:17 INFO - 1498854077310 Marionette TRACE 403 <- [1,1,null,{"sessionId":"77c030d6-fe6d-4b1b-a9e0-46608590a10e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:17 INFO - 1498854077312 Marionette TRACE 403 -> [0,2,"setTimeouts",{"script":30000}]
13:21:17 INFO - 1498854077312 Marionette TRACE 403 <- [1,2,null,{}]
13:21:17 INFO - 1498854077313 Marionette TRACE 403 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:21:17 INFO - 1498854077314 Marionette TRACE 403 <- [1,3,null,{}]
13:21:17 INFO - 1498854077315 Marionette TRACE 403 -> [0,4,"setTimeouts",{"implicit":0}]
13:21:17 INFO - 1498854077315 Marionette TRACE 403 <- [1,4,null,{}]
13:21:17 INFO - 1498854077317 Marionette TRACE 403 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
13:21:17 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498854077319 Marionette TRACE 403 <- [1,5,null,{"value":null}]
13:21:17 INFO - 1498854077321 Marionette TRACE 403 -> [0,6,"deleteSession",{}]
13:21:17 INFO - 1498854077322 Marionette TRACE 403 <- [1,6,null,{}]
13:21:17 INFO - 1498854077324 Marionette DEBUG Closed connection 403
13:21:17 INFO - 1498854077324 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49773
13:21:17 INFO - 1498854077325 Marionette DEBUG Closed connection 404
13:21:17 INFO - 1498854077326 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49774
13:21:17 INFO - 1498854077327 Marionette TRACE 405 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:21:17 INFO - 1498854077328 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:17 INFO - 1498854077331 Marionette DEBUG Register listener.js for window 2147483649
13:21:17 INFO - 1498854077334 Marionette TRACE 405 <- [1,1,null,{"sessionId":"dbeb170f-fdfc-41a7-b4ea-3aca64aebfda","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:17 INFO - 1498854077335 Marionette TRACE 405 -> [0,2,"deleteSession",{}]
13:21:17 INFO - 1498854077336 Marionette TRACE 405 <- [1,2,null,{}]
13:21:17 INFO - 1498854077338 Marionette DEBUG Closed connection 405
13:21:17 INFO - 1498854077338 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49775
13:21:17 INFO - 1498854077339 Marionette DEBUG Closed connection 406
13:21:17 INFO - 1498854077340 Marionette DEBUG Accepted connection 407 from 127.0.0.1:49776
13:21:17 INFO - 1498854077341 Marionette TRACE 407 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:21:17 INFO - 1498854077342 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}
13:21:17 INFO - 1498854077345 Marionette DEBUG Register listener.js for window 2147483649
13:21:17 INFO - 1498854077349 Marionette TRACE 407 <- [1,1,null,{"sessionId":"5b689a54-78a5-4620-996d-cd64d8a5ab74","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmptmvhxv.mozrunner","moz:accessibilityChecks":false}}]
13:21:17 INFO - 1498854077351 Marionette TRACE 407 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
13:21:17 INFO - 1498854077353 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:21:17 INFO - 1498854077355 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:21:17 INFO - 1498854077355 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:21:17 INFO - 1498854077362 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
13:21:17 INFO - 1498854077366 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
13:21:17 INFO - 1498854077375 Marionette TRACE 407 <- [1,2,null,{}]
13:21:17 INFO - 1498854077378 Marionette TRACE 407 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:21:17 INFO - 1498854077385 Marionette TRACE 407 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ddea4844-d30c-4749-826c-2d9e095e5085","ELEMENT":"ddea4844-d30c-4749-826c-2d9e095e5085"}}]
13:21:17 INFO - 1498854077387 Marionette TRACE 407 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"ddea4844-d30c-4749-826c-2d9e095e5085"}]
13:21:17 INFO - 1498854077414 Marionette TRACE 407 <- [1,4,null,{"value":"none"}]
13:21:17 INFO - 1498854077415 Marionette TRACE 407 -> [0,5,"clickElement",{"id":"ddea4844-d30c-4749-826c-2d9e095e5085"}]
13:21:17 INFO - 1498854077434 Marionette TRACE 407 <- [1,5,{"error":"element click intercepted","message":"Element