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]
11:09:11 INFO - 1500574151927 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}]
11:09:11 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500574151930 Marionette TRACE 352 <- [1,5,null,{"value":null}]
11:09:11 INFO - 1500574151931 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
11:09:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 136ms
11:09:11 INFO - 1500574151932 Marionette TRACE 352 <- [1,6,null,{}]
11:09:11 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
11:09:11 INFO - 1500574151934 Marionette DEBUG Closed connection 352
11:09:11 INFO - 1500574151936 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49768
11:09:11 INFO - 1500574151936 Marionette DEBUG Closed connection 353
11:09:11 INFO - 1500574151937 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49769
11:09:11 INFO - 1500574151938 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:11 INFO - 1500574151939 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:11 INFO - 1500574151942 Marionette DEBUG Register listener.js for window 6442450945
11:09:11 INFO - 1500574151953 Marionette TRACE 354 <- [1,1,null,{"sessionId":"054ef57e-fa26-495e-969c-c4099e36fc39","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:11 INFO - 1500574151954 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
11:09:11 INFO - 1500574151954 Marionette TRACE 354 <- [1,2,null,{}]
11:09:11 INFO - 1500574151955 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:09:11 INFO - 1500574151956 Marionette TRACE 354 <- [1,3,null,{}]
11:09:11 INFO - 1500574151957 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
11:09:11 INFO - 1500574151957 Marionette TRACE 354 <- [1,4,null,{}]
11:09:11 INFO - 1500574151959 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}]
11:09:11 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500574151962 Marionette TRACE 354 <- [1,5,null,{"value":null}]
11:09:11 INFO - 1500574151963 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
11:09:11 INFO - 1500574151964 Marionette TRACE 354 <- [1,6,null,{}]
11:09:11 INFO - 1500574151966 Marionette DEBUG Closed connection 354
11:09:11 INFO - 1500574151966 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49770
11:09:11 INFO - 1500574151967 Marionette DEBUG Closed connection 355
11:09:11 INFO - 1500574151968 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49771
11:09:11 INFO - 1500574151969 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:11 INFO - 1500574151969 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:11 INFO - 1500574151972 Marionette DEBUG Register listener.js for window 6442450945
11:09:11 INFO - 1500574151976 Marionette TRACE 356 <- [1,1,null,{"sessionId":"26b74e29-8a74-49b5-bad6-5dc3280bd86c","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:11 INFO - 1500574151977 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
11:09:11 INFO - 1500574151979 Marionette TRACE 356 <- [1,2,null,{}]
11:09:11 INFO - 1500574151980 Marionette DEBUG Closed connection 356
11:09:11 INFO - 1500574151981 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49772
11:09:11 INFO - 1500574151982 Marionette DEBUG Closed connection 357
11:09:11 INFO - 1500574151982 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49773
11:09:11 INFO - 1500574151984 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:09:11 INFO - 1500574151985 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:11 INFO - 1500574151988 Marionette DEBUG Register listener.js for window 6442450945
11:09:11 INFO - 1500574151992 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0cbd89b5-7079-4c7e-a95a-ad0de9cbfb9f","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:11 INFO - 1500574151993 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
11:09:11 INFO - 1500574151995 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
11:09:12 INFO - 1500574152003 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
11:09:12 INFO - 1500574152003 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
11:09:12 INFO - 1500574152010 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
11:09:12 INFO - 1500574152015 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
11:09:12 INFO - 1500574152017 Marionette TRACE 358 <- [1,2,null,{}]
11:09:12 INFO - 1500574152025 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
11:09:12 INFO - 1500574152029 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f8d8f027-847b-4364-9bd4-314b4dae2c22","ELEMENT":"f8d8f027-847b-4364-9bd4-314b4dae2c22"}}]
11:09:12 INFO - 1500574152029 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f8d8f027-847b-4364-9bd4-314b4dae2c22"}]
11:09:12 INFO - 1500574152076 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
11:09:12 INFO - 1500574152084 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
11:09:12 INFO - 1500574152084 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
11:09:12 INFO - 1500574152092 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
11:09:12 INFO - 1500574152095 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
11:09:12 INFO - 1500574152112 Marionette TRACE 358 <- [1,4,null,{}]
11:09:12 INFO - 1500574152119 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
11:09:12 INFO - 1500574152125 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"78f88a4d-bbf6-4091-a7c7-5598ccbabe84","ELEMENT":"78f88a4d-bbf6-4091-a7c7-5598ccbabe84"}}]
11:09:12 INFO - 1500574152127 Marionette TRACE 358 -> [0,6,"getTitle",{}]
11:09:12 INFO - 1500574152127 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
11:09:12 INFO - 1500574152129 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}]
11:09:12 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500574152132 Marionette TRACE 358 <- [1,7,null,{"value":null}]
11:09:12 INFO - 1500574152133 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
11:09:12 INFO - 1500574152134 Marionette TRACE 358 <- [1,8,null,{}]
11:09:12 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 201ms
11:09:12 INFO - TEST-START | test_click.py TestClick.test_container_element
11:09:12 INFO - 1500574152136 Marionette DEBUG Closed connection 358
11:09:12 INFO - 1500574152137 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49776
11:09:12 INFO - 1500574152138 Marionette DEBUG Closed connection 359
11:09:12 INFO - 1500574152139 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49777
11:09:12 INFO - 1500574152140 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:12 INFO - 1500574152141 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152144 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152148 Marionette TRACE 360 <- [1,1,null,{"sessionId":"97e0ea39-f1c3-4db5-9517-553dc36199c8","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152149 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
11:09:12 INFO - 1500574152150 Marionette TRACE 360 <- [1,2,null,{}]
11:09:12 INFO - 1500574152151 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:09:12 INFO - 1500574152152 Marionette TRACE 360 <- [1,3,null,{}]
11:09:12 INFO - 1500574152153 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
11:09:12 INFO - 1500574152153 Marionette TRACE 360 <- [1,4,null,{}]
11:09:12 INFO - 1500574152155 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}]
11:09:12 INFO - TEST-START: test_click.py TestClick.test_container_element1500574152158 Marionette TRACE 360 <- [1,5,null,{"value":null}]
11:09:12 INFO - 1500574152159 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
11:09:12 INFO - 1500574152161 Marionette TRACE 360 <- [1,6,null,{}]
11:09:12 INFO - 1500574152163 Marionette DEBUG Closed connection 360
11:09:12 INFO - 1500574152163 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49778
11:09:12 INFO - 1500574152164 Marionette DEBUG Closed connection 361
11:09:12 INFO - 1500574152165 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49779
11:09:12 INFO - 1500574152166 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:12 INFO - 1500574152167 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152170 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152174 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4dbd0a04-567a-4436-903f-0ebb47ace8f1","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152175 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
11:09:12 INFO - 1500574152177 Marionette TRACE 362 <- [1,2,null,{}]
11:09:12 INFO - 1500574152178 Marionette DEBUG Closed connection 362
11:09:12 INFO - 1500574152179 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49780
11:09:12 INFO - 1500574152180 Marionette DEBUG Closed connection 363
11:09:12 INFO - 1500574152180 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49781
11:09:12 INFO - 1500574152182 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:09:12 INFO - 1500574152183 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152187 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152190 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f5e8cbfd-2e8f-4f0c-9200-455a5ba4025c","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152192 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"}]
11:09:12 INFO - 1500574152194 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
11:09:12 INFO - 1500574152197 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
11:09:12 INFO - 1500574152198 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
11:09:12 INFO - 1500574152209 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"
11:09:12 INFO - 1500574152213 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"
11:09:12 INFO - 1500574152220 Marionette TRACE 364 <- [1,2,null,{}]
11:09:12 INFO - 1500574152226 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:09:12 INFO - 1500574152229 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da7a0db0-d3a2-4bf0-8138-fde81755d305","ELEMENT":"da7a0db0-d3a2-4bf0-8138-fde81755d305"}}]
11:09:12 INFO - 1500574152230 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"da7a0db0-d3a2-4bf0-8138-fde81755d305"}]
11:09:12 INFO - 1500574152481 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:09:12 INFO - 1500574152482 Marionette TRACE 364 <- [1,4,null,{}]
11:09:12 INFO - 1500574152483 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"da7a0db0-d3a2-4bf0-8138-fde81755d305","name":"selected"}]
11:09:12 INFO - 1500574152489 Marionette TRACE 364 <- [1,5,null,{"value":true}]
11:09:12 INFO - 1500574152491 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}]
11:09:12 INFO - TEST-END: test_click.py TestClick.test_container_element1500574152494 Marionette TRACE 364 <- [1,6,null,{"value":null}]
11:09:12 INFO - 1500574152495 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
11:09:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 361ms
11:09:12 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
11:09:12 INFO - 1500574152496 Marionette TRACE 364 <- [1,7,null,{}]
11:09:12 INFO - 1500574152498 Marionette DEBUG Closed connection 364
11:09:12 INFO - 1500574152499 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49782
11:09:12 INFO - 1500574152500 Marionette DEBUG Closed connection 365
11:09:12 INFO - 1500574152500 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49783
11:09:12 INFO - 1500574152501 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:12 INFO - 1500574152502 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152505 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152509 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ca9dadef-ad71-4601-8a77-1397dd4a79a2","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152510 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
11:09:12 INFO - 1500574152510 Marionette TRACE 366 <- [1,2,null,{}]
11:09:12 INFO - 1500574152511 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:09:12 INFO - 1500574152512 Marionette TRACE 366 <- [1,3,null,{}]
11:09:12 INFO - 1500574152513 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
11:09:12 INFO - 1500574152513 Marionette TRACE 366 <- [1,4,null,{}]
11:09:12 INFO - 1500574152515 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}]
11:09:12 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500574152518 Marionette TRACE 366 <- [1,5,null,{"value":null}]
11:09:12 INFO - 1500574152519 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
11:09:12 INFO - 1500574152521 Marionette TRACE 366 <- [1,6,null,{}]
11:09:12 INFO - 1500574152522 Marionette DEBUG Closed connection 366
11:09:12 INFO - 1500574152523 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49784
11:09:12 INFO - 1500574152524 Marionette DEBUG Closed connection 367
11:09:12 INFO - 1500574152524 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49785
11:09:12 INFO - 1500574152525 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:12 INFO - 1500574152526 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152529 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152532 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cf4ba08b-1c69-47c9-8630-574969adefc5","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152534 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
11:09:12 INFO - 1500574152535 Marionette TRACE 368 <- [1,2,null,{}]
11:09:12 INFO - 1500574152537 Marionette DEBUG Closed connection 368
11:09:12 INFO - 1500574152538 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49786
11:09:12 INFO - 1500574152539 Marionette DEBUG Closed connection 369
11:09:12 INFO - 1500574152539 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49787
11:09:12 INFO - 1500574152540 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:09:12 INFO - 1500574152541 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152544 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152547 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7b249cd8-0a0b-42f8-87d8-f0db375a643f","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152548 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"}]
11:09:12 INFO - 1500574152551 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"
11:09:12 INFO - 1500574152553 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"
11:09:12 INFO - 1500574152553 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"
11:09:12 INFO - 1500574152559 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"
11:09:12 INFO - 1500574152562 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"
11:09:12 INFO - 1500574152569 Marionette TRACE 370 <- [1,2,null,{}]
11:09:12 INFO - 1500574152578 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:09:12 INFO - 1500574152581 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a6ad865d-365e-4713-8bb9-42f72fabeef0","ELEMENT":"a6ad865d-365e-4713-8bb9-42f72fabeef0"}}]
11:09:12 INFO - 1500574152581 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a6ad865d-365e-4713-8bb9-42f72fabeef0"}]
11:09:12 INFO - 1500574152833 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:09:12 INFO - 1500574152834 Marionette TRACE 370 <- [1,4,null,{}]
11:09:12 INFO - 1500574152835 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a6ad865d-365e-4713-8bb9-42f72fabeef0","name":"selected"}]
11:09:12 INFO - 1500574152840 Marionette TRACE 370 <- [1,5,null,{"value":true}]
11:09:12 INFO - 1500574152842 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}]
11:09:12 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500574152845 Marionette TRACE 370 <- [1,6,null,{"value":null}]
11:09:12 INFO - 1500574152846 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
11:09:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 351ms
11:09:12 INFO - TEST-START | test_click.py TestClick.test_css_transforms
11:09:12 INFO - 1500574152848 Marionette TRACE 370 <- [1,7,null,{}]
11:09:12 INFO - 1500574152849 Marionette DEBUG Closed connection 370
11:09:12 INFO - 1500574152850 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49788
11:09:12 INFO - 1500574152851 Marionette DEBUG Closed connection 371
11:09:12 INFO - 1500574152852 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49789
11:09:12 INFO - 1500574152853 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:12 INFO - 1500574152853 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152862 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152866 Marionette TRACE 372 <- [1,1,null,{"sessionId":"f08f71e2-5cf4-43f4-a8fd-2eee42d9b7d2","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152867 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
11:09:12 INFO - 1500574152868 Marionette TRACE 372 <- [1,2,null,{}]
11:09:12 INFO - 1500574152869 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:09:12 INFO - 1500574152869 Marionette TRACE 372 <- [1,3,null,{}]
11:09:12 INFO - 1500574152870 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
11:09:12 INFO - 1500574152870 Marionette TRACE 372 <- [1,4,null,{}]
11:09:12 INFO - 1500574152872 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}]
11:09:12 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500574152875 Marionette TRACE 372 <- [1,5,null,{"value":null}]
11:09:12 INFO - 1500574152876 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
11:09:12 INFO - 1500574152877 Marionette TRACE 372 <- [1,6,null,{}]
11:09:12 INFO - 1500574152879 Marionette DEBUG Closed connection 372
11:09:12 INFO - 1500574152880 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49790
11:09:12 INFO - 1500574152880 Marionette DEBUG Closed connection 373
11:09:12 INFO - 1500574152881 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49791
11:09:12 INFO - 1500574152882 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:12 INFO - 1500574152883 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152885 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152889 Marionette TRACE 374 <- [1,1,null,{"sessionId":"bd6abfff-f3f8-4678-8dce-53b84d9088ee","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152890 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
11:09:12 INFO - 1500574152891 Marionette TRACE 374 <- [1,2,null,{}]
11:09:12 INFO - 1500574152893 Marionette DEBUG Closed connection 374
11:09:12 INFO - 1500574152894 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49792
11:09:12 INFO - 1500574152894 Marionette DEBUG Closed connection 375
11:09:12 INFO - 1500574152895 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49793
11:09:12 INFO - 1500574152896 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:09:12 INFO - 1500574152897 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:12 INFO - 1500574152900 Marionette DEBUG Register listener.js for window 6442450945
11:09:12 INFO - 1500574152903 Marionette TRACE 376 <- [1,1,null,{"sessionId":"f85991ee-ff7c-47c1-bef3-7cad12de5340","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:12 INFO - 1500574152904 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"}]
11:09:12 INFO - 1500574152907 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"
11:09:12 INFO - 1500574152908 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"
11:09:12 INFO - 1500574152909 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"
11:09:12 INFO - 1500574152915 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"
11:09:12 INFO - 1500574152917 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"
11:09:12 INFO - 1500574152921 Marionette TRACE 376 <- [1,2,null,{}]
11:09:12 INFO - 1500574152930 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
11:09:12 INFO - 1500574152933 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f4de0d5-5f38-4997-b676-d9d160f18b4b","ELEMENT":"7f4de0d5-5f38-4997-b676-d9d160f18b4b"}}]
11:09:12 INFO - 1500574152934 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"7f4de0d5-5f38-4997-b676-d9d160f18b4b"}]
11:09:13 INFO - 1500574153176 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:09:13 INFO - 1500574153177 Marionette TRACE 376 <- [1,4,null,{}]
11:09:13 INFO - 1500574153179 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}]
11:09:13 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500574153181 Marionette TRACE 376 <- [1,5,null,{"value":null}]
11:09:13 INFO - 1500574153182 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
11:09:13 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 335ms
11:09:13 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
11:09:13 INFO - 1500574153184 Marionette TRACE 376 <- [1,6,null,{}]
11:09:13 INFO - 1500574153185 Marionette DEBUG Closed connection 376
11:09:13 INFO - 1500574153186 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49794
11:09:13 INFO - 1500574153187 Marionette DEBUG Closed connection 377
11:09:13 INFO - 1500574153188 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49795
11:09:13 INFO - 1500574153189 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:13 INFO - 1500574153189 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153192 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153195 Marionette TRACE 378 <- [1,1,null,{"sessionId":"55692880-4fc3-412c-9ae9-140af6a41bb9","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153197 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
11:09:13 INFO - 1500574153197 Marionette TRACE 378 <- [1,2,null,{}]
11:09:13 INFO - 1500574153198 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:09:13 INFO - 1500574153199 Marionette TRACE 378 <- [1,3,null,{}]
11:09:13 INFO - 1500574153200 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
11:09:13 INFO - 1500574153200 Marionette TRACE 378 <- [1,4,null,{}]
11:09:13 INFO - 1500574153202 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}]
11:09:13 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500574153205 Marionette TRACE 378 <- [1,5,null,{"value":null}]
11:09:13 INFO - 1500574153206 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
11:09:13 INFO - 1500574153207 Marionette TRACE 378 <- [1,6,null,{}]
11:09:13 INFO - 1500574153209 Marionette DEBUG Closed connection 378
11:09:13 INFO - 1500574153209 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49796
11:09:13 INFO - 1500574153210 Marionette DEBUG Closed connection 379
11:09:13 INFO - 1500574153211 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49797
11:09:13 INFO - 1500574153212 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:13 INFO - 1500574153213 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153215 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153219 Marionette TRACE 380 <- [1,1,null,{"sessionId":"cb2dc392-0dae-40bb-bf74-d50c71440de8","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153220 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
11:09:13 INFO - 1500574153221 Marionette TRACE 380 <- [1,2,null,{}]
11:09:13 INFO - 1500574153223 Marionette DEBUG Closed connection 380
11:09:13 INFO - 1500574153223 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49798
11:09:13 INFO - 1500574153224 Marionette DEBUG Closed connection 381
11:09:13 INFO - 1500574153225 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49799
11:09:13 INFO - 1500574153226 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:09:13 INFO - 1500574153227 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153230 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153240 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c77ecdf0-77d9-43d4-9c86-3991b406486a","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153242 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"}]
11:09:13 INFO - 1500574153244 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"
11:09:13 INFO - 1500574153245 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"
11:09:13 INFO - 1500574153246 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"
11:09:13 INFO - 1500574153252 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"
11:09:13 INFO - 1500574153256 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"
11:09:13 INFO - 1500574153264 Marionette TRACE 382 <- [1,2,null,{}]
11:09:13 INFO - 1500574153268 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
11:09:13 INFO - 1500574153271 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"70ed4f84-665f-4006-9969-2e3c2955dd0a","ELEMENT":"70ed4f84-665f-4006-9969-2e3c2955dd0a"}}]
11:09:13 INFO - 1500574153276 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"70ed4f84-665f-4006-9969-2e3c2955dd0a"}]
11:09:13 INFO - 1500574153526 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:09:13 INFO - 1500574153527 Marionette TRACE 382 <- [1,4,null,{}]
11:09:13 INFO - 1500574153529 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}]
11:09:13 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500574153532 Marionette TRACE 382 <- [1,5,null,{"value":null}]
11:09:13 INFO - 1500574153532 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
11:09:13 INFO - 1500574153534 Marionette TRACE 382 <- [1,6,null,{}]
11:09:13 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 352ms
11:09:13 INFO - TEST-START | test_click.py TestClick.test_input_file
11:09:13 INFO - 1500574153536 Marionette DEBUG Closed connection 382
11:09:13 INFO - 1500574153537 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49800
11:09:13 INFO - 1500574153538 Marionette DEBUG Closed connection 383
11:09:13 INFO - 1500574153538 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49801
11:09:13 INFO - 1500574153539 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:13 INFO - 1500574153540 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153543 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153547 Marionette TRACE 384 <- [1,1,null,{"sessionId":"06f7e00a-ed9a-4521-bc89-1b67e80295b0","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153548 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
11:09:13 INFO - 1500574153548 Marionette TRACE 384 <- [1,2,null,{}]
11:09:13 INFO - 1500574153549 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:09:13 INFO - 1500574153550 Marionette TRACE 384 <- [1,3,null,{}]
11:09:13 INFO - 1500574153551 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
11:09:13 INFO - 1500574153551 Marionette TRACE 384 <- [1,4,null,{}]
11:09:13 INFO - 1500574153553 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}]
11:09:13 INFO - TEST-START: test_click.py TestClick.test_input_file1500574153556 Marionette TRACE 384 <- [1,5,null,{"value":null}]
11:09:13 INFO - 1500574153557 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
11:09:13 INFO - 1500574153558 Marionette TRACE 384 <- [1,6,null,{}]
11:09:13 INFO - 1500574153560 Marionette DEBUG Closed connection 384
11:09:13 INFO - 1500574153560 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49802
11:09:13 INFO - 1500574153561 Marionette DEBUG Closed connection 385
11:09:13 INFO - 1500574153562 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49803
11:09:13 INFO - 1500574153563 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:13 INFO - 1500574153564 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153566 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153570 Marionette TRACE 386 <- [1,1,null,{"sessionId":"aee85398-72d2-4e31-a8f7-9b55a88bab1c","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153571 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
11:09:13 INFO - 1500574153572 Marionette TRACE 386 <- [1,2,null,{}]
11:09:13 INFO - 1500574153574 Marionette DEBUG Closed connection 386
11:09:13 INFO - 1500574153575 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49804
11:09:13 INFO - 1500574153575 Marionette DEBUG Closed connection 387
11:09:13 INFO - 1500574153576 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49805
11:09:13 INFO - 1500574153577 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:09:13 INFO - 1500574153578 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153580 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153584 Marionette TRACE 388 <- [1,1,null,{"sessionId":"c0f725a1-bc52-45a2-9faf-733e5071ec01","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153585 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
11:09:13 INFO - 1500574153587 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"
11:09:13 INFO - 1500574153589 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"
11:09:13 INFO - 1500574153589 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"
11:09:13 INFO - 1500574153596 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:09:13 INFO - 1500574153600 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:09:13 INFO - 1500574153602 Marionette TRACE 388 <- [1,2,null,{}]
11:09:13 INFO - 1500574153610 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
11:09:13 INFO - 1500574153613 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e9a92609-d34f-4179-ba81-9b3abf378ba3","ELEMENT":"e9a92609-d34f-4179-ba81-9b3abf378ba3"}}]
11:09:13 INFO - 1500574153614 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"e9a92609-d34f-4179-ba81-9b3abf378ba3"}]
11:09:13 INFO - 1500574153644 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]
11:09:13 INFO - 1500574153646 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}]
11:09:13 INFO - TEST-END: test_click.py TestClick.test_input_file1500574153649 Marionette TRACE 388 <- [1,5,null,{"value":null}]
11:09:13 INFO - 1500574153650 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
11:09:13 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 117ms
11:09:13 INFO - 1500574153651 Marionette TRACE 388 <- [1,6,null,{}]
11:09:13 INFO - TEST-START | test_click.py TestClick.test_obscured_element
11:09:13 INFO - 1500574153653 Marionette DEBUG Closed connection 388
11:09:13 INFO - 1500574153654 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49806
11:09:13 INFO - 1500574153655 Marionette DEBUG Closed connection 389
11:09:13 INFO - 1500574153656 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49807
11:09:13 INFO - 1500574153657 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:13 INFO - 1500574153657 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153660 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153664 Marionette TRACE 390 <- [1,1,null,{"sessionId":"b53d736b-2b6c-4a55-a237-2a46bc868b06","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153665 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
11:09:13 INFO - 1500574153666 Marionette TRACE 390 <- [1,2,null,{}]
11:09:13 INFO - 1500574153667 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:09:13 INFO - 1500574153668 Marionette TRACE 390 <- [1,3,null,{}]
11:09:13 INFO - 1500574153669 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
11:09:13 INFO - 1500574153669 Marionette TRACE 390 <- [1,4,null,{}]
11:09:13 INFO - 1500574153671 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}]
11:09:13 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500574153674 Marionette TRACE 390 <- [1,5,null,{"value":null}]
11:09:13 INFO - 1500574153675 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
11:09:13 INFO - 1500574153676 Marionette TRACE 390 <- [1,6,null,{}]
11:09:13 INFO - 1500574153678 Marionette DEBUG Closed connection 390
11:09:13 INFO - 1500574153678 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49808
11:09:13 INFO - 1500574153679 Marionette DEBUG Closed connection 391
11:09:13 INFO - 1500574153680 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49809
11:09:13 INFO - 1500574153681 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:13 INFO - 1500574153682 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153685 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153688 Marionette TRACE 392 <- [1,1,null,{"sessionId":"cac555f8-3afa-4853-bc04-b871bf061f88","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153689 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
11:09:13 INFO - 1500574153691 Marionette TRACE 392 <- [1,2,null,{}]
11:09:13 INFO - 1500574153692 Marionette DEBUG Closed connection 392
11:09:13 INFO - 1500574153693 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49810
11:09:13 INFO - 1500574153694 Marionette DEBUG Closed connection 393
11:09:13 INFO - 1500574153694 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49811
11:09:13 INFO - 1500574153695 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:09:13 INFO - 1500574153696 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:13 INFO - 1500574153699 Marionette DEBUG Register listener.js for window 6442450945
11:09:13 INFO - 1500574153703 Marionette TRACE 394 <- [1,1,null,{"sessionId":"16505364-37b7-4dc7-aae3-77adcfece6cc","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:13 INFO - 1500574153704 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"}]
11:09:13 INFO - 1500574153706 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:09:13 INFO - 1500574153708 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:09:13 INFO - 1500574153708 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:09:13 INFO - 1500574153716 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"
11:09:13 INFO - 1500574153718 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"
11:09:13 INFO - 1500574153721 Marionette TRACE 394 <- [1,2,null,{}]
11:09:13 INFO - 1500574153729 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
11:09:13 INFO - 1500574153732 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"56020159-6916-47f6-a3c8-796f91a78d7c","ELEMENT":"56020159-6916-47f6-a3c8-796f91a78d7c"}}]
11:09:13 INFO - 1500574153733 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
11:09:13 INFO - 1500574153736 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"89aa7493-57bd-4b63-93e4-d358cdf1443a","ELEMENT":"89aa7493-57bd-4b63-93e4-d358cdf1443a"}}]
11:09:13 INFO - 1500574153737 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"56020159-6916-47f6-a3c8-796f91a78d7c"}]
11:09:14 INFO - 1500574153991 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:09:14 INFO - 1500574153992 Marionette TRACE 394 <- [1,5,null,{}]
11:09:14 INFO - 1500574153993 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"89aa7493-57bd-4b63-93e4-d358cdf1443a"}]
11:09:14 INFO - 1500574154010 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]
11:09:14 INFO - 1500574154012 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
11:09:14 INFO - 1500574154015 Marionette TRACE 394 <- [1,7,null,{"value":false}]
11:09:14 INFO - 1500574154017 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}]
11:09:14 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500574154019 Marionette TRACE 394 <- [1,8,null,{"value":null}]
11:09:14 INFO - 1500574154020 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
11:09:14 INFO - 1500574154022 Marionette TRACE 394 <- [1,9,null,{}]
11:09:14 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 369ms
11:09:14 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
11:09:14 INFO - 1500574154023 Marionette DEBUG Closed connection 394
11:09:14 INFO - 1500574154024 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49812
11:09:14 INFO - 1500574154025 Marionette DEBUG Closed connection 395
11:09:14 INFO - 1500574154025 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49813
11:09:14 INFO - 1500574154026 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:14 INFO - 1500574154027 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:14 INFO - 1500574154030 Marionette DEBUG Register listener.js for window 6442450945
11:09:14 INFO - 1500574154033 Marionette TRACE 396 <- [1,1,null,{"sessionId":"4e14302d-5b74-429a-957a-9a6e2201180f","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:14 INFO - 1500574154035 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
11:09:14 INFO - 1500574154035 Marionette TRACE 396 <- [1,2,null,{}]
11:09:14 INFO - 1500574154036 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:09:14 INFO - 1500574154037 Marionette TRACE 396 <- [1,3,null,{}]
11:09:14 INFO - 1500574154038 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
11:09:14 INFO - 1500574154038 Marionette TRACE 396 <- [1,4,null,{}]
11:09:14 INFO - 1500574154040 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}]
11:09:14 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500574154042 Marionette TRACE 396 <- [1,5,null,{"value":null}]
11:09:14 INFO - 1500574154044 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
11:09:14 INFO - 1500574154045 Marionette TRACE 396 <- [1,6,null,{}]
11:09:14 INFO - 1500574154047 Marionette DEBUG Closed connection 396
11:09:14 INFO - 1500574154047 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49814
11:09:14 INFO - 1500574154048 Marionette DEBUG Closed connection 397
11:09:14 INFO - 1500574154049 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49815
11:09:14 INFO - 1500574154050 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:09:14 INFO - 1500574154050 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:14 INFO - 1500574154054 Marionette DEBUG Register listener.js for window 6442450945
11:09:14 INFO - 1500574154057 Marionette TRACE 398 <- [1,1,null,{"sessionId":"0400df0f-6681-473c-b978-fa0a6254e31f","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:14 INFO - 1500574154058 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
11:09:14 INFO - 1500574154060 Marionette TRACE 398 <- [1,2,null,{}]
11:09:14 INFO - 1500574154061 Marionette DEBUG Closed connection 398
11:09:14 INFO - 1500574154062 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49816
11:09:14 INFO - 1500574154063 Marionette DEBUG Closed connection 399
11:09:14 INFO - 1500574154063 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49817
11:09:14 INFO - 1500574154064 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:09:14 INFO - 1500574154065 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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}
11:09:14 INFO - 1500574154068 Marionette DEBUG Register listener.js for window 6442450945
11:09:14 INFO - 1500574154071 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c04467ea-6aa0-4ceb-9ceb-e7278e281537","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":1608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmpffmyge.mozrunner","moz:accessibilityChecks":false}}]
11:09:14 INFO - 1500574154073 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"}]
11:09:14 INFO - 1500574154075 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"
11:09:14 INFO - 1500574154077 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"
11:09:14 INFO - 1500574154078 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"
11:09:14 INFO - 1500574154085 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"
11:09:14 INFO - 1500574154087 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"
11:09:14 INFO - 1500574154095 Marionette TRACE 400 <- [1,2,null,{}]
11:09:14 INFO - 1500574154099 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
11:09:14 INFO - 1500574154101 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"07563d9b-e9e7-428d-bfdc-9d61cb291674","ELEMENT":"07563d9b-e9e7-428d-bfdc-9d61cb291674"}}]
11:09:14 INFO - 1500574154103 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"07563d9b-e9e7-428d-bfdc-9d61cb291674"}]
11:09:14 INFO - 1500574154130 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
11:09:14 INFO - 1500574154131 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"07563d9b-e9e7-428d-bfdc-9d61cb291674"}]
11:09:14 INFO - 1500574154148 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element