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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:45:50 INFO - 1499888750983 Marionette TRACE 352 -> [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}]
12:45:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 150ms
12:45:51 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:45:51 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499888750999 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:45:51 INFO - 1499888751000 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:45:51 INFO - 1499888751001 Marionette TRACE 352 <- [1,6,null,{}]
12:45:51 INFO - 1499888751003 Marionette DEBUG Closed connection 352
12:45:51 INFO - 1499888751004 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49774
12:45:51 INFO - 1499888751004 Marionette DEBUG Closed connection 353
12:45:51 INFO - 1499888751005 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49775
12:45:51 INFO - 1499888751006 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:51 INFO - 1499888751007 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751010 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751013 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7f7536ab-1779-4125-9edd-b4ccbe9d827d","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751015 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:45:51 INFO - 1499888751015 Marionette TRACE 354 <- [1,2,null,{}]
12:45:51 INFO - 1499888751016 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:51 INFO - 1499888751016 Marionette TRACE 354 <- [1,3,null,{}]
12:45:51 INFO - 1499888751017 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:51 INFO - 1499888751018 Marionette TRACE 354 <- [1,4,null,{}]
12:45:51 INFO - 1499888751020 Marionette TRACE 354 -> [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}]
12:45:51 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499888751022 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:45:51 INFO - 1499888751024 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:45:51 INFO - 1499888751025 Marionette TRACE 354 <- [1,6,null,{}]
12:45:51 INFO - 1499888751027 Marionette DEBUG Closed connection 354
12:45:51 INFO - 1499888751027 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49776
12:45:51 INFO - 1499888751028 Marionette DEBUG Closed connection 355
12:45:51 INFO - 1499888751029 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49777
12:45:51 INFO - 1499888751030 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:51 INFO - 1499888751031 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751033 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751037 Marionette TRACE 356 <- [1,1,null,{"sessionId":"2602ebfc-31fb-4433-83e3-4434a9271493","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751038 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:45:51 INFO - 1499888751040 Marionette TRACE 356 <- [1,2,null,{}]
12:45:51 INFO - 1499888751041 Marionette DEBUG Closed connection 356
12:45:51 INFO - 1499888751042 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49778
12:45:51 INFO - 1499888751043 Marionette DEBUG Closed connection 357
12:45:51 INFO - 1499888751043 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49779
12:45:51 INFO - 1499888751045 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:51 INFO - 1499888751045 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751048 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751051 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3ad00f49-c9a1-4f28-85ff-d39c811a930e","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751053 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
12:45:51 INFO - 1499888751055 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
12:45:51 INFO - 1499888751062 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
12:45:51 INFO - 1499888751062 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
12:45:51 INFO - 1499888751076 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
12:45:51 INFO - 1499888751081 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
12:45:51 INFO - 1499888751084 Marionette TRACE 358 <- [1,2,null,{}]
12:45:51 INFO - 1499888751088 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:45:51 INFO - 1499888751102 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"03e837a4-767a-48ad-8989-ed9899fac3f3","ELEMENT":"03e837a4-767a-48ad-8989-ed9899fac3f3"}}]
12:45:51 INFO - 1499888751103 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"03e837a4-767a-48ad-8989-ed9899fac3f3"}]
12:45:51 INFO - 1499888751155 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
12:45:51 INFO - 1499888751167 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
12:45:51 INFO - 1499888751168 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
12:45:51 INFO - 1499888751177 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
12:45:51 INFO - 1499888751183 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
12:45:51 INFO - 1499888751186 Marionette TRACE 358 <- [1,4,null,{}]
12:45:51 INFO - 1499888751190 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:45:51 INFO - 1499888751204 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ed4b92c5-79de-459f-aead-fcdd70bb1a70","ELEMENT":"ed4b92c5-79de-459f-aead-fcdd70bb1a70"}}]
12:45:51 INFO - 1499888751205 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:45:51 INFO - 1499888751205 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:45:51 INFO - 1499888751208 Marionette TRACE 358 -> [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}]
12:45:51 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499888751211 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:45:51 INFO - 1499888751212 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:45:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 212ms
12:45:51 INFO - TEST-START | test_click.py TestClick.test_container_element
12:45:51 INFO - 1499888751214 Marionette TRACE 358 <- [1,8,null,{}]
12:45:51 INFO - 1499888751216 Marionette DEBUG Closed connection 358
12:45:51 INFO - 1499888751216 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49782
12:45:51 INFO - 1499888751217 Marionette DEBUG Closed connection 359
12:45:51 INFO - 1499888751218 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49783
12:45:51 INFO - 1499888751219 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:51 INFO - 1499888751219 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751222 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751226 Marionette TRACE 360 <- [1,1,null,{"sessionId":"8b73b159-5d60-45a9-b28a-7646f210f48a","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751227 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:45:51 INFO - 1499888751227 Marionette TRACE 360 <- [1,2,null,{}]
12:45:51 INFO - 1499888751228 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:51 INFO - 1499888751228 Marionette TRACE 360 <- [1,3,null,{}]
12:45:51 INFO - 1499888751229 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:51 INFO - 1499888751230 Marionette TRACE 360 <- [1,4,null,{}]
12:45:51 INFO - 1499888751232 Marionette TRACE 360 -> [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}]
12:45:51 INFO - TEST-START: test_click.py TestClick.test_container_element1499888751235 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:45:51 INFO - 1499888751236 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:45:51 INFO - 1499888751237 Marionette TRACE 360 <- [1,6,null,{}]
12:45:51 INFO - 1499888751239 Marionette DEBUG Closed connection 360
12:45:51 INFO - 1499888751239 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49784
12:45:51 INFO - 1499888751240 Marionette DEBUG Closed connection 361
12:45:51 INFO - 1499888751241 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49785
12:45:51 INFO - 1499888751242 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:51 INFO - 1499888751242 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751245 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751249 Marionette TRACE 362 <- [1,1,null,{"sessionId":"9845d7df-65e0-4472-b6b1-4af2b8fd49ef","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751250 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:45:51 INFO - 1499888751252 Marionette TRACE 362 <- [1,2,null,{}]
12:45:51 INFO - 1499888751253 Marionette DEBUG Closed connection 362
12:45:51 INFO - 1499888751254 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49786
12:45:51 INFO - 1499888751254 Marionette DEBUG Closed connection 363
12:45:51 INFO - 1499888751255 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49787
12:45:51 INFO - 1499888751256 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:51 INFO - 1499888751257 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751260 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751263 Marionette TRACE 364 <- [1,1,null,{"sessionId":"e5351a3a-c006-41b4-aee0-9ae2b44cba06","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751264 Marionette TRACE 364 -> [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"}]
12:45:51 INFO - 1499888751270 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
12:45:51 INFO - 1499888751272 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
12:45:51 INFO - 1499888751272 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
12:45:51 INFO - 1499888751278 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"
12:45:51 INFO - 1499888751282 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"
12:45:51 INFO - 1499888751293 Marionette TRACE 364 <- [1,2,null,{}]
12:45:51 INFO - 1499888751302 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:45:51 INFO - 1499888751305 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8b04cb1e-40bb-4500-b6ca-4f5de04d82a1","ELEMENT":"8b04cb1e-40bb-4500-b6ca-4f5de04d82a1"}}]
12:45:51 INFO - 1499888751306 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8b04cb1e-40bb-4500-b6ca-4f5de04d82a1"}]
12:45:51 INFO - 1499888751552 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:51 INFO - 1499888751553 Marionette TRACE 364 <- [1,4,null,{}]
12:45:51 INFO - 1499888751554 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8b04cb1e-40bb-4500-b6ca-4f5de04d82a1","name":"selected"}]
12:45:51 INFO - 1499888751559 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:45:51 INFO - 1499888751560 Marionette TRACE 364 -> [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}]
12:45:51 INFO - TEST-END: test_click.py TestClick.test_container_element1499888751563 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:45:51 INFO - 1499888751564 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:45:51 INFO - 1499888751565 Marionette TRACE 364 <- [1,7,null,{}]
12:45:51 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 350ms
12:45:51 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:45:51 INFO - 1499888751567 Marionette DEBUG Closed connection 364
12:45:51 INFO - 1499888751568 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49788
12:45:51 INFO - 1499888751569 Marionette DEBUG Closed connection 365
12:45:51 INFO - 1499888751569 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49789
12:45:51 INFO - 1499888751570 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:51 INFO - 1499888751571 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751574 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751577 Marionette TRACE 366 <- [1,1,null,{"sessionId":"922906cc-6667-4002-b6a5-563ea500495c","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751578 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:45:51 INFO - 1499888751579 Marionette TRACE 366 <- [1,2,null,{}]
12:45:51 INFO - 1499888751580 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:51 INFO - 1499888751580 Marionette TRACE 366 <- [1,3,null,{}]
12:45:51 INFO - 1499888751581 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:51 INFO - 1499888751581 Marionette TRACE 366 <- [1,4,null,{}]
12:45:51 INFO - 1499888751583 Marionette TRACE 366 -> [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}]
12:45:51 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499888751586 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:45:51 INFO - 1499888751587 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:45:51 INFO - 1499888751588 Marionette TRACE 366 <- [1,6,null,{}]
12:45:51 INFO - 1499888751590 Marionette DEBUG Closed connection 366
12:45:51 INFO - 1499888751590 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49790
12:45:51 INFO - 1499888751591 Marionette DEBUG Closed connection 367
12:45:51 INFO - 1499888751592 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49791
12:45:51 INFO - 1499888751593 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:51 INFO - 1499888751594 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751596 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751600 Marionette TRACE 368 <- [1,1,null,{"sessionId":"aaab586c-93cb-4a53-a0c9-5af28624422a","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751601 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:45:51 INFO - 1499888751602 Marionette TRACE 368 <- [1,2,null,{}]
12:45:51 INFO - 1499888751604 Marionette DEBUG Closed connection 368
12:45:51 INFO - 1499888751604 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49792
12:45:51 INFO - 1499888751605 Marionette DEBUG Closed connection 369
12:45:51 INFO - 1499888751606 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49793
12:45:51 INFO - 1499888751607 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:51 INFO - 1499888751608 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751610 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751614 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d75717d8-1dd6-4a18-99c1-6fc9f8e3c85e","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751617 Marionette TRACE 370 -> [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"}]
12:45:51 INFO - 1499888751620 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"
12:45:51 INFO - 1499888751622 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"
12:45:51 INFO - 1499888751622 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"
12:45:51 INFO - 1499888751628 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"
12:45:51 INFO - 1499888751632 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"
12:45:51 INFO - 1499888751639 Marionette TRACE 370 <- [1,2,null,{}]
12:45:51 INFO - 1499888751643 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:45:51 INFO - 1499888751646 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"691e8a34-0763-4330-a62d-5789601fda22","ELEMENT":"691e8a34-0763-4330-a62d-5789601fda22"}}]
12:45:51 INFO - 1499888751647 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"691e8a34-0763-4330-a62d-5789601fda22"}]
12:45:51 INFO - 1499888751893 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:51 INFO - 1499888751894 Marionette TRACE 370 <- [1,4,null,{}]
12:45:51 INFO - 1499888751895 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"691e8a34-0763-4330-a62d-5789601fda22","name":"selected"}]
12:45:51 INFO - 1499888751899 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:45:51 INFO - 1499888751901 Marionette TRACE 370 -> [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}]
12:45:51 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499888751904 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:45:51 INFO - 1499888751905 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:45:51 INFO - 1499888751906 Marionette TRACE 370 <- [1,7,null,{}]
12:45:51 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 342ms
12:45:51 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:45:51 INFO - 1499888751908 Marionette DEBUG Closed connection 370
12:45:51 INFO - 1499888751909 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49794
12:45:51 INFO - 1499888751910 Marionette DEBUG Closed connection 371
12:45:51 INFO - 1499888751911 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49795
12:45:51 INFO - 1499888751912 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:51 INFO - 1499888751912 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751915 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751919 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d46de600-d390-4e59-85ef-408117cde53c","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751920 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:45:51 INFO - 1499888751921 Marionette TRACE 372 <- [1,2,null,{}]
12:45:51 INFO - 1499888751922 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:51 INFO - 1499888751922 Marionette TRACE 372 <- [1,3,null,{}]
12:45:51 INFO - 1499888751923 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:51 INFO - 1499888751924 Marionette TRACE 372 <- [1,4,null,{}]
12:45:51 INFO - 1499888751925 Marionette TRACE 372 -> [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}]
12:45:51 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499888751928 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:45:51 INFO - 1499888751930 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:45:51 INFO - 1499888751931 Marionette TRACE 372 <- [1,6,null,{}]
12:45:51 INFO - 1499888751933 Marionette DEBUG Closed connection 372
12:45:51 INFO - 1499888751933 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49796
12:45:51 INFO - 1499888751934 Marionette DEBUG Closed connection 373
12:45:51 INFO - 1499888751935 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49797
12:45:51 INFO - 1499888751936 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:51 INFO - 1499888751936 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751939 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751943 Marionette TRACE 374 <- [1,1,null,{"sessionId":"61ee0f93-e017-4773-bbf6-7b61faf69c0c","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751944 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:45:51 INFO - 1499888751945 Marionette TRACE 374 <- [1,2,null,{}]
12:45:51 INFO - 1499888751947 Marionette DEBUG Closed connection 374
12:45:51 INFO - 1499888751947 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49798
12:45:51 INFO - 1499888751948 Marionette DEBUG Closed connection 375
12:45:51 INFO - 1499888751949 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49799
12:45:51 INFO - 1499888751950 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:51 INFO - 1499888751950 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:51 INFO - 1499888751953 Marionette DEBUG Register listener.js for window 4294967297
12:45:51 INFO - 1499888751956 Marionette TRACE 376 <- [1,1,null,{"sessionId":"d88d6ccd-e5ec-4bdc-ba7a-6ba87fbc0d41","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:51 INFO - 1499888751957 Marionette TRACE 376 -> [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"}]
12:45:51 INFO - 1499888751959 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"
12:45:51 INFO - 1499888751961 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"
12:45:51 INFO - 1499888751961 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"
12:45:51 INFO - 1499888751968 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"
12:45:51 INFO - 1499888751971 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"
12:45:51 INFO - 1499888751976 Marionette TRACE 376 <- [1,2,null,{}]
12:45:51 INFO - 1499888751979 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:45:51 INFO - 1499888751986 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3b80a885-3466-49bb-9b68-ca889ee6a789","ELEMENT":"3b80a885-3466-49bb-9b68-ca889ee6a789"}}]
12:45:51 INFO - 1499888751987 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"3b80a885-3466-49bb-9b68-ca889ee6a789"}]
12:45:52 INFO - 1499888752234 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:52 INFO - 1499888752235 Marionette TRACE 376 <- [1,4,null,{}]
12:45:52 INFO - 1499888752238 Marionette TRACE 376 -> [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}]
12:45:52 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499888752241 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:45:52 INFO - 1499888752242 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:45:52 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 336ms
12:45:52 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:45:52 INFO - 1499888752244 Marionette TRACE 376 <- [1,6,null,{}]
12:45:52 INFO - 1499888752245 Marionette DEBUG Closed connection 376
12:45:52 INFO - 1499888752246 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49800
12:45:52 INFO - 1499888752247 Marionette DEBUG Closed connection 377
12:45:52 INFO - 1499888752247 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49801
12:45:52 INFO - 1499888752248 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:52 INFO - 1499888752249 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752252 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752263 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9dfac16b-c772-468a-8aae-58803c9e4d65","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752264 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:45:52 INFO - 1499888752265 Marionette TRACE 378 <- [1,2,null,{}]
12:45:52 INFO - 1499888752265 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:52 INFO - 1499888752266 Marionette TRACE 378 <- [1,3,null,{}]
12:45:52 INFO - 1499888752267 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:52 INFO - 1499888752267 Marionette TRACE 378 <- [1,4,null,{}]
12:45:52 INFO - 1499888752269 Marionette TRACE 378 -> [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}]
12:45:52 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499888752271 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:45:52 INFO - 1499888752272 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:45:52 INFO - 1499888752274 Marionette TRACE 378 <- [1,6,null,{}]
12:45:52 INFO - 1499888752275 Marionette DEBUG Closed connection 378
12:45:52 INFO - 1499888752276 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49802
12:45:52 INFO - 1499888752277 Marionette DEBUG Closed connection 379
12:45:52 INFO - 1499888752278 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49803
12:45:52 INFO - 1499888752279 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:52 INFO - 1499888752279 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752282 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752285 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c0d599d1-b688-44eb-9d5c-7b81c90d601e","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752287 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:45:52 INFO - 1499888752288 Marionette TRACE 380 <- [1,2,null,{}]
12:45:52 INFO - 1499888752289 Marionette DEBUG Closed connection 380
12:45:52 INFO - 1499888752290 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49804
12:45:52 INFO - 1499888752291 Marionette DEBUG Closed connection 381
12:45:52 INFO - 1499888752291 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49805
12:45:52 INFO - 1499888752292 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:52 INFO - 1499888752293 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752296 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752299 Marionette TRACE 382 <- [1,1,null,{"sessionId":"02c888fe-e6d4-41b5-a0bc-01266e3bf4ce","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752301 Marionette TRACE 382 -> [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"}]
12:45:52 INFO - 1499888752302 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"
12:45:52 INFO - 1499888752304 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"
12:45:52 INFO - 1499888752305 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"
12:45:52 INFO - 1499888752311 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"
12:45:52 INFO - 1499888752314 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"
12:45:52 INFO - 1499888752321 Marionette TRACE 382 <- [1,2,null,{}]
12:45:52 INFO - 1499888752324 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:45:52 INFO - 1499888752327 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e00c0e8d-7acd-4092-9936-4ac958b54c12","ELEMENT":"e00c0e8d-7acd-4092-9936-4ac958b54c12"}}]
12:45:52 INFO - 1499888752328 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e00c0e8d-7acd-4092-9936-4ac958b54c12"}]
12:45:52 INFO - 1499888752569 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:52 INFO - 1499888752570 Marionette TRACE 382 <- [1,4,null,{}]
12:45:52 INFO - 1499888752572 Marionette TRACE 382 -> [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}]
12:45:52 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499888752575 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:45:52 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 333ms
12:45:52 INFO - 1499888752576 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:45:52 INFO - 1499888752577 Marionette TRACE 382 <- [1,6,null,{}]
12:45:52 INFO - TEST-START | test_click.py TestClick.test_input_file
12:45:52 INFO - 1499888752579 Marionette DEBUG Closed connection 382
12:45:52 INFO - 1499888752580 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49806
12:45:52 INFO - 1499888752581 Marionette DEBUG Closed connection 383
12:45:52 INFO - 1499888752582 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49807
12:45:52 INFO - 1499888752583 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:52 INFO - 1499888752583 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752586 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752589 Marionette TRACE 384 <- [1,1,null,{"sessionId":"325c3d13-2da9-464d-8730-1377d2ebf301","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752591 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:45:52 INFO - 1499888752591 Marionette TRACE 384 <- [1,2,null,{}]
12:45:52 INFO - 1499888752592 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:52 INFO - 1499888752592 Marionette TRACE 384 <- [1,3,null,{}]
12:45:52 INFO - 1499888752593 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:52 INFO - 1499888752594 Marionette TRACE 384 <- [1,4,null,{}]
12:45:52 INFO - 1499888752595 Marionette TRACE 384 -> [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}]
12:45:52 INFO - TEST-START: test_click.py TestClick.test_input_file1499888752598 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:45:52 INFO - 1499888752599 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:45:52 INFO - 1499888752601 Marionette TRACE 384 <- [1,6,null,{}]
12:45:52 INFO - 1499888752602 Marionette DEBUG Closed connection 384
12:45:52 INFO - 1499888752603 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49808
12:45:52 INFO - 1499888752604 Marionette DEBUG Closed connection 385
12:45:52 INFO - 1499888752604 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49809
12:45:52 INFO - 1499888752605 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:52 INFO - 1499888752606 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752658 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752665 Marionette TRACE 386 <- [1,1,null,{"sessionId":"316a3855-1067-4056-b62d-00fa1dbcd47f","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752666 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:45:52 INFO - 1499888752667 Marionette TRACE 386 <- [1,2,null,{}]
12:45:52 INFO - 1499888752669 Marionette DEBUG Closed connection 386
12:45:52 INFO - 1499888752670 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49810
12:45:52 INFO - 1499888752670 Marionette DEBUG Closed connection 387
12:45:52 INFO - 1499888752671 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49811
12:45:52 INFO - 1499888752672 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:52 INFO - 1499888752673 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752675 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752679 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f8d240d8-83c9-4c5d-8c93-00a1420b3cba","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752683 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:45:52 INFO - 1499888752708 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"
12:45:52 INFO - 1499888752711 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"
12:45:52 INFO - 1499888752712 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"
12:45:52 INFO - 1499888752722 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:52 INFO - 1499888752728 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:52 INFO - 1499888752728 Marionette TRACE 388 <- [1,2,null,{}]
12:45:52 INFO - 1499888752736 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:45:52 INFO - 1499888752740 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dc72addd-0090-4cfd-8062-6c5a5c07fe5b","ELEMENT":"dc72addd-0090-4cfd-8062-6c5a5c07fe5b"}}]
12:45:52 INFO - 1499888752742 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"dc72addd-0090-4cfd-8062-6c5a5c07fe5b"}]
12:45:52 INFO - 1499888752778 Marionette TRACE 388 <- [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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:45:52 INFO - 1499888752780 Marionette TRACE 388 -> [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}]
12:45:52 INFO - TEST-END: test_click.py TestClick.test_input_file1499888752783 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:45:52 INFO - 1499888752784 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:45:52 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 209ms
12:45:52 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:45:52 INFO - 1499888752786 Marionette TRACE 388 <- [1,6,null,{}]
12:45:52 INFO - 1499888752788 Marionette DEBUG Closed connection 388
12:45:52 INFO - 1499888752789 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49812
12:45:52 INFO - 1499888752789 Marionette DEBUG Closed connection 389
12:45:52 INFO - 1499888752790 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49813
12:45:52 INFO - 1499888752791 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:52 INFO - 1499888752791 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752795 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752799 Marionette TRACE 390 <- [1,1,null,{"sessionId":"eb750072-64ba-4258-ac1c-390ba5e78733","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752800 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:45:52 INFO - 1499888752801 Marionette TRACE 390 <- [1,2,null,{}]
12:45:52 INFO - 1499888752801 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:52 INFO - 1499888752802 Marionette TRACE 390 <- [1,3,null,{}]
12:45:52 INFO - 1499888752803 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:52 INFO - 1499888752804 Marionette TRACE 390 <- [1,4,null,{}]
12:45:52 INFO - 1499888752806 Marionette TRACE 390 -> [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}]
12:45:52 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499888752808 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:45:52 INFO - 1499888752809 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:45:52 INFO - 1499888752811 Marionette TRACE 390 <- [1,6,null,{}]
12:45:52 INFO - 1499888752813 Marionette DEBUG Closed connection 390
12:45:52 INFO - 1499888752813 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49814
12:45:52 INFO - 1499888752814 Marionette DEBUG Closed connection 391
12:45:52 INFO - 1499888752815 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49815
12:45:52 INFO - 1499888752816 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:52 INFO - 1499888752816 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752819 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752823 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fdb9e219-24bf-46da-a536-fcca02894f19","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752824 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:45:52 INFO - 1499888752826 Marionette TRACE 392 <- [1,2,null,{}]
12:45:52 INFO - 1499888752828 Marionette DEBUG Closed connection 392
12:45:52 INFO - 1499888752828 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49816
12:45:52 INFO - 1499888752829 Marionette DEBUG Closed connection 393
12:45:52 INFO - 1499888752830 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49817
12:45:52 INFO - 1499888752831 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:52 INFO - 1499888752832 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:52 INFO - 1499888752835 Marionette DEBUG Register listener.js for window 4294967297
12:45:52 INFO - 1499888752839 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3e278174-f1c0-4ea0-b383-8f84c3541d50","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:52 INFO - 1499888752840 Marionette TRACE 394 -> [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"}]
12:45:52 INFO - 1499888752843 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:52 INFO - 1499888752846 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:52 INFO - 1499888752846 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:52 INFO - 1499888752854 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"
12:45:52 INFO - 1499888752857 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"
12:45:52 INFO - 1499888752860 Marionette TRACE 394 <- [1,2,null,{}]
12:45:52 INFO - 1499888752863 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:45:52 INFO - 1499888752870 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"319dbc82-9db3-4162-a05b-cde612e7e060","ELEMENT":"319dbc82-9db3-4162-a05b-cde612e7e060"}}]
12:45:52 INFO - 1499888752871 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:45:52 INFO - 1499888752874 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"764a348e-0eea-4b9e-89f2-dc1f73c18309","ELEMENT":"764a348e-0eea-4b9e-89f2-dc1f73c18309"}}]
12:45:52 INFO - 1499888752875 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"319dbc82-9db3-4162-a05b-cde612e7e060"}]
12:45:53 INFO - 1499888753119 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:53 INFO - 1499888753120 Marionette TRACE 394 <- [1,5,null,{}]
12:45:53 INFO - 1499888753121 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"764a348e-0eea-4b9e-89f2-dc1f73c18309"}]
12:45:53 INFO - 1499888753139 Marionette TRACE 394 <- [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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:45:53 INFO - 1499888753141 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:45:53 INFO - 1499888753144 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:45:53 INFO - 1499888753146 Marionette TRACE 394 -> [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}]
12:45:53 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499888753149 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:45:53 INFO - 1499888753150 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:45:53 INFO - 1499888753151 Marionette TRACE 394 <- [1,9,null,{}]
12:45:53 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 365ms
12:45:53 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:45:53 INFO - 1499888753153 Marionette DEBUG Closed connection 394
12:45:53 INFO - 1499888753154 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49818
12:45:53 INFO - 1499888753154 Marionette DEBUG Closed connection 395
12:45:53 INFO - 1499888753155 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49819
12:45:53 INFO - 1499888753156 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:53 INFO - 1499888753157 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:53 INFO - 1499888753160 Marionette DEBUG Register listener.js for window 4294967297
12:45:53 INFO - 1499888753164 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6ae97f9b-9f03-4bcf-91b6-6caf5cab37a1","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:53 INFO - 1499888753165 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:45:53 INFO - 1499888753166 Marionette TRACE 396 <- [1,2,null,{}]
12:45:53 INFO - 1499888753167 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:53 INFO - 1499888753167 Marionette TRACE 396 <- [1,3,null,{}]
12:45:53 INFO - 1499888753168 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:53 INFO - 1499888753168 Marionette TRACE 396 <- [1,4,null,{}]
12:45:53 INFO - 1499888753170 Marionette TRACE 396 -> [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}]
12:45:53 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499888753173 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:45:53 INFO - 1499888753174 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:45:53 INFO - 1499888753176 Marionette TRACE 396 <- [1,6,null,{}]
12:45:53 INFO - 1499888753178 Marionette DEBUG Closed connection 396
12:45:53 INFO - 1499888753178 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49820
12:45:53 INFO - 1499888753179 Marionette DEBUG Closed connection 397
12:45:53 INFO - 1499888753179 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49821
12:45:53 INFO - 1499888753180 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:53 INFO - 1499888753181 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:53 INFO - 1499888753184 Marionette DEBUG Register listener.js for window 4294967297
12:45:53 INFO - 1499888753188 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a3a61b98-b7d6-452f-97d2-95c8e12c1fd3","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:53 INFO - 1499888753189 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:45:53 INFO - 1499888753190 Marionette TRACE 398 <- [1,2,null,{}]
12:45:53 INFO - 1499888753192 Marionette DEBUG Closed connection 398
12:45:53 INFO - 1499888753193 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49822
12:45:53 INFO - 1499888753194 Marionette DEBUG Closed connection 399
12:45:53 INFO - 1499888753194 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49823
12:45:53 INFO - 1499888753196 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:53 INFO - 1499888753196 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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}
12:45:53 INFO - 1499888753199 Marionette DEBUG Register listener.js for window 4294967297
12:45:53 INFO - 1499888753203 Marionette TRACE 400 <- [1,1,null,{"sessionId":"f6e90e11-8075-4ee9-9ca3-4971f25e6f12","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":2204,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-019.001\\AppData\\Local\\Temp\\tmpzmtsqk.mozrunner","moz:accessibilityChecks":false}}]
12:45:53 INFO - 1499888753204 Marionette TRACE 400 -> [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"}]
12:45:53 INFO - 1499888753206 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"
12:45:53 INFO - 1499888753209 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"
12:45:53 INFO - 1499888753209 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"
12:45:53 INFO - 1499888753216 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"
12:45:53 INFO - 1499888753219 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"
12:45:53 INFO - 1499888753227 Marionette TRACE 400 <- [1,2,null,{}]
12:45:53 INFO - 1499888753230 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:45:53 INFO - 1499888753237 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2d45538f-601b-41c7-ab1e-6a65f65e091b","ELEMENT":"2d45538f-601b-41c7-ab1e-6a65f65e091b"}}]
12:45:53 INFO - 1499888753238 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2d45538f-601b-41c7-ab1e-6a65f65e091b"}]
12:45:53 INFO - 1499888753263 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:45:53 INFO - 1499888753265 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"2d45538f-601b-41c7-ab1e-6a65f65e091b"}]
12:45:53 INFO - 1499888753282 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element