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]
15:22:29 INFO - 1500934949997 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}]
15:22:30 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500934950000 Marionette TRACE 352 <- [1,5,null,{"value":null}]
15:22:30 INFO - 1500934950001 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
15:22:30 INFO - 1500934950002 Marionette TRACE 352 <- [1,6,null,{}]
15:22:30 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 135ms
15:22:30 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:22:30 INFO - 1500934950004 Marionette DEBUG Closed connection 352
15:22:30 INFO - 1500934950005 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49788
15:22:30 INFO - 1500934950006 Marionette DEBUG Closed connection 353
15:22:30 INFO - 1500934950007 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49789
15:22:30 INFO - 1500934950007 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:30 INFO - 1500934950008 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950010 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950013 Marionette TRACE 354 <- [1,1,null,{"sessionId":"349b836e-b4c1-40c9-8cc5-212fc4ba26d0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950015 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
15:22:30 INFO - 1500934950016 Marionette TRACE 354 <- [1,2,null,{}]
15:22:30 INFO - 1500934950016 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:22:30 INFO - 1500934950017 Marionette TRACE 354 <- [1,3,null,{}]
15:22:30 INFO - 1500934950018 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
15:22:30 INFO - 1500934950018 Marionette TRACE 354 <- [1,4,null,{}]
15:22:30 INFO - 1500934950020 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}]
15:22:30 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500934950022 Marionette TRACE 354 <- [1,5,null,{"value":null}]
15:22:30 INFO - 1500934950023 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
15:22:30 INFO - 1500934950025 Marionette TRACE 354 <- [1,6,null,{}]
15:22:30 INFO - 1500934950027 Marionette DEBUG Closed connection 354
15:22:30 INFO - 1500934950027 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49790
15:22:30 INFO - 1500934950028 Marionette DEBUG Closed connection 355
15:22:30 INFO - 1500934950029 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49791
15:22:30 INFO - 1500934950030 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:30 INFO - 1500934950030 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950032 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950035 Marionette TRACE 356 <- [1,1,null,{"sessionId":"14bd65c9-2895-424b-8738-c6586925ccfe","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950037 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
15:22:30 INFO - 1500934950039 Marionette TRACE 356 <- [1,2,null,{}]
15:22:30 INFO - 1500934950041 Marionette DEBUG Closed connection 356
15:22:30 INFO - 1500934950042 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49792
15:22:30 INFO - 1500934950043 Marionette DEBUG Closed connection 357
15:22:30 INFO - 1500934950044 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49793
15:22:30 INFO - 1500934950045 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:22:30 INFO - 1500934950046 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950048 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950051 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a330a8d1-3b62-44a0-825c-a0eb329258e8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950053 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49355/clicks.html"}]
15:22:30 INFO - 1500934950055 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49355/hidden.html"
15:22:30 INFO - 1500934950060 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49355/hidden.html"
15:22:30 INFO - 1500934950061 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49355/hidden.html"
15:22:30 INFO - 1500934950081 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49355/clicks.html"
15:22:30 INFO - 1500934950083 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49355/clicks.html"
15:22:30 INFO - 1500934950087 Marionette TRACE 358 <- [1,2,null,{}]
15:22:30 INFO - 1500934950095 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:22:30 INFO - 1500934950098 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"66d7c3a8-a530-4804-bd6f-dc194395d642","ELEMENT":"66d7c3a8-a530-4804-bd6f-dc194395d642"}}]
15:22:30 INFO - 1500934950099 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"66d7c3a8-a530-4804-bd6f-dc194395d642"}]
15:22:30 INFO - 1500934950147 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49355/clicks.html"
15:22:30 INFO - 1500934950158 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49355/clicks.html"
15:22:30 INFO - 1500934950159 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49355/clicks.html"
15:22:30 INFO - 1500934950183 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49355/test.html"
15:22:30 INFO - 1500934950188 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49355/test.html"
15:22:30 INFO - 1500934950199 Marionette TRACE 358 <- [1,4,null,{}]
15:22:30 INFO - 1500934950201 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:22:30 INFO - 1500934950203 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce37e134-0803-45a4-8c67-480155eabef2","ELEMENT":"ce37e134-0803-45a4-8c67-480155eabef2"}}]
15:22:30 INFO - 1500934950205 Marionette TRACE 358 -> [0,6,"getTitle",{}]
15:22:30 INFO - 1500934950206 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
15:22:30 INFO - 1500934950208 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}]
15:22:30 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500934950211 Marionette TRACE 358 <- [1,7,null,{"value":null}]
15:22:30 INFO - 1500934950212 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
15:22:30 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 211ms
15:22:30 INFO - TEST-START | test_click.py TestClick.test_container_element
15:22:30 INFO - 1500934950213 Marionette TRACE 358 <- [1,8,null,{}]
15:22:30 INFO - 1500934950215 Marionette DEBUG Closed connection 358
15:22:30 INFO - 1500934950216 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49796
15:22:30 INFO - 1500934950217 Marionette DEBUG Closed connection 359
15:22:30 INFO - 1500934950218 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49797
15:22:30 INFO - 1500934950219 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:30 INFO - 1500934950220 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950222 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950225 Marionette TRACE 360 <- [1,1,null,{"sessionId":"e2d0954c-8a44-4433-95f1-f54c3f53099b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950227 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
15:22:30 INFO - 1500934950228 Marionette TRACE 360 <- [1,2,null,{}]
15:22:30 INFO - 1500934950228 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:22:30 INFO - 1500934950229 Marionette TRACE 360 <- [1,3,null,{}]
15:22:30 INFO - 1500934950230 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
15:22:30 INFO - 1500934950230 Marionette TRACE 360 <- [1,4,null,{}]
15:22:30 INFO - 1500934950232 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}]
15:22:30 INFO - TEST-START: test_click.py TestClick.test_container_element1500934950235 Marionette TRACE 360 <- [1,5,null,{"value":null}]
15:22:30 INFO - 1500934950236 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
15:22:30 INFO - 1500934950238 Marionette TRACE 360 <- [1,6,null,{}]
15:22:30 INFO - 1500934950240 Marionette DEBUG Closed connection 360
15:22:30 INFO - 1500934950240 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49798
15:22:30 INFO - 1500934950241 Marionette DEBUG Closed connection 361
15:22:30 INFO - 1500934950242 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49799
15:22:30 INFO - 1500934950243 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:30 INFO - 1500934950244 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950246 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950249 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1bed061e-c413-4834-9af1-71d3d4100b96","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950251 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
15:22:30 INFO - 1500934950252 Marionette TRACE 362 <- [1,2,null,{}]
15:22:30 INFO - 1500934950254 Marionette DEBUG Closed connection 362
15:22:30 INFO - 1500934950255 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49800
15:22:30 INFO - 1500934950255 Marionette DEBUG Closed connection 363
15:22:30 INFO - 1500934950256 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49801
15:22:30 INFO - 1500934950257 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:22:30 INFO - 1500934950258 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950260 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950262 Marionette TRACE 364 <- [1,1,null,{"sessionId":"ab820d4e-f736-4b57-a59f-1b3fbdc91664","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950264 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"}]
15:22:30 INFO - 1500934950266 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49355/test.html"
15:22:30 INFO - 1500934950268 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49355/test.html"
15:22:30 INFO - 1500934950268 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49355/test.html"
15:22:30 INFO - 1500934950292 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"
15:22:30 INFO - 1500934950293 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"
15:22:30 INFO - 1500934950297 Marionette TRACE 364 <- [1,2,null,{}]
15:22:30 INFO - 1500934950301 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:22:30 INFO - 1500934950303 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"76f140f0-5aed-40ef-8107-cbc65b123873","ELEMENT":"76f140f0-5aed-40ef-8107-cbc65b123873"}}]
15:22:30 INFO - 1500934950310 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"76f140f0-5aed-40ef-8107-cbc65b123873"}]
15:22:30 INFO - 1500934950548 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:22:30 INFO - 1500934950549 Marionette TRACE 364 <- [1,4,null,{}]
15:22:30 INFO - 1500934950550 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"76f140f0-5aed-40ef-8107-cbc65b123873","name":"selected"}]
15:22:30 INFO - 1500934950556 Marionette TRACE 364 <- [1,5,null,{"value":true}]
15:22:30 INFO - 1500934950561 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}]
15:22:30 INFO - TEST-END: test_click.py TestClick.test_container_element1500934950563 Marionette TRACE 364 <- [1,6,null,{"value":null}]
15:22:30 INFO - 1500934950564 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
15:22:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 352ms
15:22:30 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:22:30 INFO - 1500934950565 Marionette TRACE 364 <- [1,7,null,{}]
15:22:30 INFO - 1500934950567 Marionette DEBUG Closed connection 364
15:22:30 INFO - 1500934950568 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49804
15:22:30 INFO - 1500934950569 Marionette DEBUG Closed connection 365
15:22:30 INFO - 1500934950570 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49805
15:22:30 INFO - 1500934950593 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:30 INFO - 1500934950593 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950595 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950598 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b4a75bf6-0fa9-4c1f-b0ec-6a3e151b79e1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950601 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
15:22:30 INFO - 1500934950601 Marionette TRACE 366 <- [1,2,null,{}]
15:22:30 INFO - 1500934950602 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:22:30 INFO - 1500934950602 Marionette TRACE 366 <- [1,3,null,{}]
15:22:30 INFO - 1500934950603 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
15:22:30 INFO - 1500934950604 Marionette TRACE 366 <- [1,4,null,{}]
15:22:30 INFO - 1500934950605 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}]
15:22:30 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500934950608 Marionette TRACE 366 <- [1,5,null,{"value":null}]
15:22:30 INFO - 1500934950609 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
15:22:30 INFO - 1500934950610 Marionette TRACE 366 <- [1,6,null,{}]
15:22:30 INFO - 1500934950612 Marionette DEBUG Closed connection 366
15:22:30 INFO - 1500934950613 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49806
15:22:30 INFO - 1500934950614 Marionette DEBUG Closed connection 367
15:22:30 INFO - 1500934950615 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49807
15:22:30 INFO - 1500934950616 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:30 INFO - 1500934950616 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950618 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950621 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b06371e2-1773-4734-9906-26b74232cd8e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950623 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
15:22:30 INFO - 1500934950625 Marionette TRACE 368 <- [1,2,null,{}]
15:22:30 INFO - 1500934950627 Marionette DEBUG Closed connection 368
15:22:30 INFO - 1500934950627 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49808
15:22:30 INFO - 1500934950628 Marionette DEBUG Closed connection 369
15:22:30 INFO - 1500934950629 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49809
15:22:30 INFO - 1500934950630 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:22:30 INFO - 1500934950631 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950633 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950636 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d9ca324a-4b6c-40d7-b0af-3b88f33cd332","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950638 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"}]
15:22:30 INFO - 1500934950640 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"
15:22:30 INFO - 1500934950643 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"
15:22:30 INFO - 1500934950644 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"
15:22:30 INFO - 1500934950661 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"
15:22:30 INFO - 1500934950662 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"
15:22:30 INFO - 1500934950665 Marionette TRACE 370 <- [1,2,null,{}]
15:22:30 INFO - 1500934950666 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:22:30 INFO - 1500934950669 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"119b7a51-8036-4d4d-a33f-f6bf20be9319","ELEMENT":"119b7a51-8036-4d4d-a33f-f6bf20be9319"}}]
15:22:30 INFO - 1500934950675 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"119b7a51-8036-4d4d-a33f-f6bf20be9319"}]
15:22:30 INFO - 1500934950915 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:22:30 INFO - 1500934950916 Marionette TRACE 370 <- [1,4,null,{}]
15:22:30 INFO - 1500934950917 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"119b7a51-8036-4d4d-a33f-f6bf20be9319","name":"selected"}]
15:22:30 INFO - 1500934950921 Marionette TRACE 370 <- [1,5,null,{"value":true}]
15:22:30 INFO - 1500934950923 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}]
15:22:30 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500934950926 Marionette TRACE 370 <- [1,6,null,{"value":null}]
15:22:30 INFO - 1500934950927 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
15:22:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 363ms
15:22:30 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:22:30 INFO - 1500934950928 Marionette TRACE 370 <- [1,7,null,{}]
15:22:30 INFO - 1500934950930 Marionette DEBUG Closed connection 370
15:22:30 INFO - 1500934950931 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49810
15:22:30 INFO - 1500934950932 Marionette DEBUG Closed connection 371
15:22:30 INFO - 1500934950933 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49811
15:22:30 INFO - 1500934950934 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:30 INFO - 1500934950942 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:30 INFO - 1500934950944 Marionette DEBUG Register listener.js for window 12
15:22:30 INFO - 1500934950947 Marionette TRACE 372 <- [1,1,null,{"sessionId":"87d9847b-ec54-4c46-bfa0-6df0f7852a01","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:30 INFO - 1500934950949 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
15:22:30 INFO - 1500934950949 Marionette TRACE 372 <- [1,2,null,{}]
15:22:30 INFO - 1500934950950 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:22:30 INFO - 1500934950950 Marionette TRACE 372 <- [1,3,null,{}]
15:22:30 INFO - 1500934950951 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
15:22:30 INFO - 1500934950952 Marionette TRACE 372 <- [1,4,null,{}]
15:22:30 INFO - 1500934950953 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}]
15:22:30 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500934950956 Marionette TRACE 372 <- [1,5,null,{"value":null}]
15:22:30 INFO - 1500934950957 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
15:22:30 INFO - 1500934950958 Marionette TRACE 372 <- [1,6,null,{}]
15:22:30 INFO - 1500934950960 Marionette DEBUG Closed connection 372
15:22:30 INFO - 1500934950961 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49812
15:22:30 INFO - 1500934950961 Marionette DEBUG Closed connection 373
15:22:30 INFO - 1500934950962 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49813
15:22:31 INFO - 1500934950964 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:31 INFO - 1500934950964 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934950966 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934950969 Marionette TRACE 374 <- [1,1,null,{"sessionId":"bad58748-0bb6-47e1-bd27-2667b26e7ebd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934950972 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
15:22:31 INFO - 1500934950973 Marionette TRACE 374 <- [1,2,null,{}]
15:22:31 INFO - 1500934950975 Marionette DEBUG Closed connection 374
15:22:31 INFO - 1500934950975 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49814
15:22:31 INFO - 1500934950976 Marionette DEBUG Closed connection 375
15:22:31 INFO - 1500934950977 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49815
15:22:31 INFO - 1500934950978 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:22:31 INFO - 1500934950979 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934950981 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934950984 Marionette TRACE 376 <- [1,1,null,{"sessionId":"b554086d-bd5a-48cf-9206-7c1d61118c7c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934950986 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"}]
15:22:31 INFO - 1500934950987 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"
15:22:31 INFO - 1500934950989 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"
15:22:31 INFO - 1500934950989 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"
15:22:31 INFO - 1500934951000 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"
15:22:31 INFO - 1500934951004 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"
15:22:31 INFO - 1500934951014 Marionette TRACE 376 <- [1,2,null,{}]
15:22:31 INFO - 1500934951017 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:22:31 INFO - 1500934951019 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"511b15e6-32b1-41c9-af6b-59735494e54f","ELEMENT":"511b15e6-32b1-41c9-af6b-59735494e54f"}}]
15:22:31 INFO - 1500934951020 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"511b15e6-32b1-41c9-af6b-59735494e54f"}]
15:22:31 INFO - 1500934951256 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:22:31 INFO - 1500934951257 Marionette TRACE 376 <- [1,4,null,{}]
15:22:31 INFO - 1500934951259 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}]
15:22:31 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500934951262 Marionette TRACE 376 <- [1,5,null,{"value":null}]
15:22:31 INFO - 1500934951263 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
15:22:31 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 336ms
15:22:31 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:22:31 INFO - 1500934951264 Marionette TRACE 376 <- [1,6,null,{}]
15:22:31 INFO - 1500934951266 Marionette DEBUG Closed connection 376
15:22:31 INFO - 1500934951267 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49816
15:22:31 INFO - 1500934951268 Marionette DEBUG Closed connection 377
15:22:31 INFO - 1500934951269 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49817
15:22:31 INFO - 1500934951270 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:31 INFO - 1500934951271 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951273 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951276 Marionette TRACE 378 <- [1,1,null,{"sessionId":"04a42d7c-9e6c-4c56-a2a5-8f441198e090","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951279 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
15:22:31 INFO - 1500934951279 Marionette TRACE 378 <- [1,2,null,{}]
15:22:31 INFO - 1500934951280 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:22:31 INFO - 1500934951280 Marionette TRACE 378 <- [1,3,null,{}]
15:22:31 INFO - 1500934951281 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
15:22:31 INFO - 1500934951282 Marionette TRACE 378 <- [1,4,null,{}]
15:22:31 INFO - 1500934951283 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}]
15:22:31 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500934951286 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:22:31 INFO - 1500934951287 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:22:31 INFO - 1500934951289 Marionette TRACE 378 <- [1,6,null,{}]
15:22:31 INFO - 1500934951292 Marionette DEBUG Closed connection 378
15:22:31 INFO - 1500934951292 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49818
15:22:31 INFO - 1500934951293 Marionette DEBUG Closed connection 379
15:22:31 INFO - 1500934951294 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49819
15:22:31 INFO - 1500934951296 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:31 INFO - 1500934951296 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951298 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951301 Marionette TRACE 380 <- [1,1,null,{"sessionId":"dcd9500e-8609-481f-80c1-f72fe64a5469","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951303 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
15:22:31 INFO - 1500934951305 Marionette TRACE 380 <- [1,2,null,{}]
15:22:31 INFO - 1500934951307 Marionette DEBUG Closed connection 380
15:22:31 INFO - 1500934951307 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49820
15:22:31 INFO - 1500934951308 Marionette DEBUG Closed connection 381
15:22:31 INFO - 1500934951309 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49821
15:22:31 INFO - 1500934951310 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:22:31 INFO - 1500934951311 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951313 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951315 Marionette TRACE 382 <- [1,1,null,{"sessionId":"0b4aa5ae-3257-4228-b38e-80344e51aebb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951318 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"}]
15:22:31 INFO - 1500934951319 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"
15:22:31 INFO - 1500934951321 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"
15:22:31 INFO - 1500934951322 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"
15:22:31 INFO - 1500934951336 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"
15:22:31 INFO - 1500934951344 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"
15:22:31 INFO - 1500934951348 Marionette TRACE 382 <- [1,2,null,{}]
15:22:31 INFO - 1500934951349 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:22:31 INFO - 1500934951352 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"91d08529-3a61-4504-96dd-403688fbfc12","ELEMENT":"91d08529-3a61-4504-96dd-403688fbfc12"}}]
15:22:31 INFO - 1500934951359 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"91d08529-3a61-4504-96dd-403688fbfc12"}]
15:22:31 INFO - 1500934951599 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:22:31 INFO - 1500934951600 Marionette TRACE 382 <- [1,4,null,{}]
15:22:31 INFO - 1500934951602 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}]
15:22:31 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500934951605 Marionette TRACE 382 <- [1,5,null,{"value":null}]
15:22:31 INFO - 1500934951606 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
15:22:31 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 343ms
15:22:31 INFO - TEST-START | test_click.py TestClick.test_input_file
15:22:31 INFO - 1500934951607 Marionette TRACE 382 <- [1,6,null,{}]
15:22:31 INFO - 1500934951609 Marionette DEBUG Closed connection 382
15:22:31 INFO - 1500934951610 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49822
15:22:31 INFO - 1500934951611 Marionette DEBUG Closed connection 383
15:22:31 INFO - 1500934951612 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49823
15:22:31 INFO - 1500934951613 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:31 INFO - 1500934951614 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951616 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951625 Marionette TRACE 384 <- [1,1,null,{"sessionId":"23fdb341-8694-4c72-9dc1-a98ef828aa22","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951627 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
15:22:31 INFO - 1500934951628 Marionette TRACE 384 <- [1,2,null,{}]
15:22:31 INFO - 1500934951629 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:22:31 INFO - 1500934951629 Marionette TRACE 384 <- [1,3,null,{}]
15:22:31 INFO - 1500934951630 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
15:22:31 INFO - 1500934951630 Marionette TRACE 384 <- [1,4,null,{}]
15:22:31 INFO - 1500934951632 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}]
15:22:31 INFO - TEST-START: test_click.py TestClick.test_input_file1500934951635 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:22:31 INFO - 1500934951636 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:22:31 INFO - 1500934951637 Marionette TRACE 384 <- [1,6,null,{}]
15:22:31 INFO - 1500934951639 Marionette DEBUG Closed connection 384
15:22:31 INFO - 1500934951640 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49824
15:22:31 INFO - 1500934951641 Marionette DEBUG Closed connection 385
15:22:31 INFO - 1500934951642 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49825
15:22:31 INFO - 1500934951643 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:31 INFO - 1500934951643 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951645 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951648 Marionette TRACE 386 <- [1,1,null,{"sessionId":"038f1051-21c9-40ed-bfeb-9bb03bdb2eb0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951650 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
15:22:31 INFO - 1500934951651 Marionette TRACE 386 <- [1,2,null,{}]
15:22:31 INFO - 1500934951653 Marionette DEBUG Closed connection 386
15:22:31 INFO - 1500934951653 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49826
15:22:31 INFO - 1500934951654 Marionette DEBUG Closed connection 387
15:22:31 INFO - 1500934951655 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49827
15:22:31 INFO - 1500934951656 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:22:31 INFO - 1500934951657 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951658 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951661 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f2191725-6361-4ff9-9592-e9791894d95d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951663 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:22:31 INFO - 1500934951664 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"
15:22:31 INFO - 1500934951666 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"
15:22:31 INFO - 1500934951667 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"
15:22:31 INFO - 1500934951684 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:22:31 INFO - 1500934951687 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:22:31 INFO - 1500934951696 Marionette TRACE 388 <- [1,2,null,{}]
15:22:31 INFO - 1500934951700 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:22:31 INFO - 1500934951702 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f994f4b-0d3b-49fd-8272-5bbbd903dffd","ELEMENT":"6f994f4b-0d3b-49fd-8272-5bbbd903dffd"}}]
15:22:31 INFO - 1500934951703 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6f994f4b-0d3b-49fd-8272-5bbbd903dffd"}]
15:22:31 INFO - 1500934951730 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]
15:22:31 INFO - 1500934951732 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}]
15:22:31 INFO - TEST-END: test_click.py TestClick.test_input_file1500934951735 Marionette TRACE 388 <- [1,5,null,{"value":null}]
15:22:31 INFO - 1500934951736 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
15:22:31 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 130ms
15:22:31 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:22:31 INFO - 1500934951737 Marionette TRACE 388 <- [1,6,null,{}]
15:22:31 INFO - 1500934951739 Marionette DEBUG Closed connection 388
15:22:31 INFO - 1500934951740 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49828
15:22:31 INFO - 1500934951741 Marionette DEBUG Closed connection 389
15:22:31 INFO - 1500934951742 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49829
15:22:31 INFO - 1500934951743 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:31 INFO - 1500934951744 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951746 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951748 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d0f4abbf-a745-4725-9906-9fc87483a986","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951750 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
15:22:31 INFO - 1500934951751 Marionette TRACE 390 <- [1,2,null,{}]
15:22:31 INFO - 1500934951752 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:22:31 INFO - 1500934951753 Marionette TRACE 390 <- [1,3,null,{}]
15:22:31 INFO - 1500934951753 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
15:22:31 INFO - 1500934951754 Marionette TRACE 390 <- [1,4,null,{}]
15:22:31 INFO - 1500934951756 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}]
15:22:31 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500934951758 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:22:31 INFO - 1500934951759 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:22:31 INFO - 1500934951760 Marionette TRACE 390 <- [1,6,null,{}]
15:22:31 INFO - 1500934951762 Marionette DEBUG Closed connection 390
15:22:31 INFO - 1500934951763 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49830
15:22:31 INFO - 1500934951764 Marionette DEBUG Closed connection 391
15:22:31 INFO - 1500934951765 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49831
15:22:31 INFO - 1500934951766 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:31 INFO - 1500934951767 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951769 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951772 Marionette TRACE 392 <- [1,1,null,{"sessionId":"48eb305b-59a4-4aa0-a9db-51031b9b5244","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951776 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
15:22:31 INFO - 1500934951777 Marionette TRACE 392 <- [1,2,null,{}]
15:22:31 INFO - 1500934951780 Marionette DEBUG Closed connection 392
15:22:31 INFO - 1500934951780 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49832
15:22:31 INFO - 1500934951783 Marionette DEBUG Closed connection 393
15:22:31 INFO - 1500934951783 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49833
15:22:31 INFO - 1500934951784 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:22:31 INFO - 1500934951785 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:31 INFO - 1500934951787 Marionette DEBUG Register listener.js for window 12
15:22:31 INFO - 1500934951790 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a424a8d3-ffed-493f-8d20-48db1a07dd69","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:31 INFO - 1500934951792 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"}]
15:22:31 INFO - 1500934951794 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:22:31 INFO - 1500934951796 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:22:31 INFO - 1500934951796 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:22:31 INFO - 1500934951815 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"
15:22:31 INFO - 1500934951817 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"
15:22:31 INFO - 1500934951820 Marionette TRACE 394 <- [1,2,null,{}]
15:22:31 INFO - 1500934951828 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:22:31 INFO - 1500934951830 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"23e8d85f-85b8-46c4-b66e-76753aed8a10","ELEMENT":"23e8d85f-85b8-46c4-b66e-76753aed8a10"}}]
15:22:31 INFO - 1500934951831 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:22:31 INFO - 1500934951833 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ac877ace-8e5b-4690-98e8-e733d5ffe638","ELEMENT":"ac877ace-8e5b-4690-98e8-e733d5ffe638"}}]
15:22:31 INFO - 1500934951834 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"23e8d85f-85b8-46c4-b66e-76753aed8a10"}]
15:22:32 INFO - 1500934952071 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:22:32 INFO - 1500934952072 Marionette TRACE 394 <- [1,5,null,{}]
15:22:32 INFO - 1500934952073 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"ac877ace-8e5b-4690-98e8-e733d5ffe638"}]
15:22:32 INFO - 1500934952087 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,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]
15:22:32 INFO - 1500934952089 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:22:32 INFO - 1500934952092 Marionette TRACE 394 <- [1,7,null,{"value":false}]
15:22:32 INFO - 1500934952093 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}]
15:22:32 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500934952096 Marionette TRACE 394 <- [1,8,null,{"value":null}]
15:22:32 INFO - 1500934952097 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
15:22:32 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 361ms
15:22:32 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:22:32 INFO - 1500934952098 Marionette TRACE 394 <- [1,9,null,{}]
15:22:32 INFO - 1500934952100 Marionette DEBUG Closed connection 394
15:22:32 INFO - 1500934952101 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49834
15:22:32 INFO - 1500934952102 Marionette DEBUG Closed connection 395
15:22:32 INFO - 1500934952103 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49835
15:22:32 INFO - 1500934952104 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:32 INFO - 1500934952104 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:32 INFO - 1500934952107 Marionette DEBUG Register listener.js for window 12
15:22:32 INFO - 1500934952109 Marionette TRACE 396 <- [1,1,null,{"sessionId":"fc55aee5-b543-4f54-a3b8-b02e080fa147","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:32 INFO - 1500934952111 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
15:22:32 INFO - 1500934952112 Marionette TRACE 396 <- [1,2,null,{}]
15:22:32 INFO - 1500934952112 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:22:32 INFO - 1500934952113 Marionette TRACE 396 <- [1,3,null,{}]
15:22:32 INFO - 1500934952114 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
15:22:32 INFO - 1500934952114 Marionette TRACE 396 <- [1,4,null,{}]
15:22:32 INFO - 1500934952116 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}]
15:22:32 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500934952118 Marionette TRACE 396 <- [1,5,null,{"value":null}]
15:22:32 INFO - 1500934952119 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
15:22:32 INFO - 1500934952120 Marionette TRACE 396 <- [1,6,null,{}]
15:22:32 INFO - 1500934952122 Marionette DEBUG Closed connection 396
15:22:32 INFO - 1500934952123 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49836
15:22:32 INFO - 1500934952124 Marionette DEBUG Closed connection 397
15:22:32 INFO - 1500934952125 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49837
15:22:32 INFO - 1500934952126 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:22:32 INFO - 1500934952126 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:32 INFO - 1500934952128 Marionette DEBUG Register listener.js for window 12
15:22:32 INFO - 1500934952139 Marionette TRACE 398 <- [1,1,null,{"sessionId":"eb546f69-8df4-49d6-9694-93ac51c17c87","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:32 INFO - 1500934952141 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
15:22:32 INFO - 1500934952143 Marionette TRACE 398 <- [1,2,null,{}]
15:22:32 INFO - 1500934952144 Marionette DEBUG Closed connection 398
15:22:32 INFO - 1500934952145 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49838
15:22:32 INFO - 1500934952146 Marionette DEBUG Closed connection 399
15:22:32 INFO - 1500934952147 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49839
15:22:32 INFO - 1500934952148 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:22:32 INFO - 1500934952149 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}
15:22:32 INFO - 1500934952151 Marionette DEBUG Register listener.js for window 12
15:22:32 INFO - 1500934952154 Marionette TRACE 400 <- [1,1,null,{"sessionId":"45f86d67-d7f5-4471-addb-d5f2f392d63d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5indhn.mozrunner","moz:accessibilityChecks":false}}]
15:22:32 INFO - 1500934952156 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"}]
15:22:32 INFO - 1500934952158 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"
15:22:32 INFO - 1500934952160 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"
15:22:32 INFO - 1500934952160 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"
15:22:32 INFO - 1500934952177 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"
15:22:32 INFO - 1500934952179 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"
15:22:32 INFO - 1500934952182 Marionette TRACE 400 <- [1,2,null,{}]
15:22:32 INFO - 1500934952183 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:22:32 INFO - 1500934952185 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4e0dae94-f477-4d7f-a64c-bfcb246c8c0b","ELEMENT":"4e0dae94-f477-4d7f-a64c-bfcb246c8c0b"}}]
15:22:32 INFO - 1500934952186 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"4e0dae94-f477-4d7f-a64c-bfcb246c8c0b"}]
15:22:32 INFO - 1500934952214 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
15:22:32 INFO - 1500934952215 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"4e0dae94-f477-4d7f-a64c-bfcb246c8c0b"}]
15:22:32 INFO - 1500934952229 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element