could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:34:31 INFO - 1500147271819 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
12:34:31 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500147271821 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:34:31 INFO - 1500147271822 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:34:31 INFO - 1500147271823 Marionette TRACE 352 <- [1,6,null,{}]
12:34:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 140ms
12:34:31 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:34:31 INFO - 1500147271825 Marionette DEBUG Closed connection 352
12:34:31 INFO - 1500147271826 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49797
12:34:31 INFO - 1500147271827 Marionette DEBUG Closed connection 353
12:34:31 INFO - 1500147271828 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49798
12:34:31 INFO - 1500147271829 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:31 INFO - 1500147271829 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:31 INFO - 1500147271831 Marionette DEBUG Register listener.js for window 15
12:34:31 INFO - 1500147271834 Marionette TRACE 354 <- [1,1,null,{"sessionId":"e0fb05b7-a955-4dcd-8bf1-9b9159e22e5f","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:31 INFO - 1500147271836 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:34:31 INFO - 1500147271836 Marionette TRACE 354 <- [1,2,null,{}]
12:34:31 INFO - 1500147271837 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:34:31 INFO - 1500147271838 Marionette TRACE 354 <- [1,3,null,{}]
12:34:31 INFO - 1500147271839 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:34:31 INFO - 1500147271839 Marionette TRACE 354 <- [1,4,null,{}]
12:34:31 INFO - 1500147271841 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
12:34:31 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500147271843 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:34:31 INFO - 1500147271844 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:34:31 INFO - 1500147271845 Marionette TRACE 354 <- [1,6,null,{}]
12:34:31 INFO - 1500147271847 Marionette DEBUG Closed connection 354
12:34:31 INFO - 1500147271847 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49799
12:34:31 INFO - 1500147271848 Marionette DEBUG Closed connection 355
12:34:31 INFO - 1500147271849 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49800
12:34:31 INFO - 1500147271850 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:31 INFO - 1500147271850 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:31 INFO - 1500147271852 Marionette DEBUG Register listener.js for window 15
12:34:31 INFO - 1500147271854 Marionette TRACE 356 <- [1,1,null,{"sessionId":"865c5f89-9ad1-4bde-a703-a7d315449509","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:31 INFO - 1500147271856 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:34:31 INFO - 1500147271857 Marionette TRACE 356 <- [1,2,null,{}]
12:34:31 INFO - 1500147271859 Marionette DEBUG Closed connection 356
12:34:31 INFO - 1500147271859 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49801
12:34:31 INFO - 1500147271860 Marionette DEBUG Closed connection 357
12:34:31 INFO - 1500147271861 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49802
12:34:31 INFO - 1500147271862 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:34:31 INFO - 1500147271862 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:31 INFO - 1500147271864 Marionette DEBUG Register listener.js for window 15
12:34:31 INFO - 1500147271867 Marionette TRACE 358 <- [1,1,null,{"sessionId":"cb1655c8-0737-41b6-a10e-6df411dcf235","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:31 INFO - 1500147271869 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49361/clicks.html"}]
12:34:31 INFO - 1500147271871 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/hidden.html"
12:34:31 INFO - 1500147271876 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/hidden.html"
12:34:31 INFO - 1500147271876 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/hidden.html"
12:34:31 INFO - 1500147271892 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49361/clicks.html"
12:34:31 INFO - 1500147271897 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49361/clicks.html"
12:34:31 INFO - 1500147271907 Marionette TRACE 358 <- [1,2,null,{}]
12:34:31 INFO - 1500147271908 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:34:31 INFO - 1500147271910 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4286389b-ca95-4bfc-a701-b2720f4e3e60","ELEMENT":"4286389b-ca95-4bfc-a701-b2720f4e3e60"}}]
12:34:31 INFO - 1500147271911 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"4286389b-ca95-4bfc-a701-b2720f4e3e60"}]
12:34:31 INFO - 1500147271948 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/clicks.html"
12:34:31 INFO - 1500147271953 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/clicks.html"
12:34:31 INFO - 1500147271954 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/clicks.html"
12:34:31 INFO - 1500147271974 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49361/test.html"
12:34:31 INFO - 1500147271976 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49361/test.html"
12:34:31 INFO - 1500147271978 Marionette TRACE 358 <- [1,4,null,{}]
12:34:31 INFO - 1500147271986 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:34:31 INFO - 1500147271989 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9779b2bf-65b9-4cc3-a6b2-59570c48a98b","ELEMENT":"9779b2bf-65b9-4cc3-a6b2-59570c48a98b"}}]
12:34:31 INFO - 1500147271989 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:34:31 INFO - 1500147271990 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:34:31 INFO - 1500147271991 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
12:34:31 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500147271994 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:34:32 INFO - 1500147271995 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:34:32 INFO - 1500147271996 Marionette TRACE 358 <- [1,8,null,{}]
12:34:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 172ms
12:34:32 INFO - TEST-START | test_click.py TestClick.test_container_element
12:34:32 INFO - 1500147271998 Marionette DEBUG Closed connection 358
12:34:32 INFO - 1500147271999 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49805
12:34:32 INFO - 1500147272000 Marionette DEBUG Closed connection 359
12:34:32 INFO - 1500147272001 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49806
12:34:32 INFO - 1500147272002 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:32 INFO - 1500147272003 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272005 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272008 Marionette TRACE 360 <- [1,1,null,{"sessionId":"96e4e9e8-d43b-4ed2-946a-d5613d579af6","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272010 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:34:32 INFO - 1500147272010 Marionette TRACE 360 <- [1,2,null,{}]
12:34:32 INFO - 1500147272014 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:34:32 INFO - 1500147272014 Marionette TRACE 360 <- [1,3,null,{}]
12:34:32 INFO - 1500147272015 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:34:32 INFO - 1500147272016 Marionette TRACE 360 <- [1,4,null,{}]
12:34:32 INFO - 1500147272017 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
12:34:32 INFO - TEST-START: test_click.py TestClick.test_container_element1500147272027 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:34:32 INFO - 1500147272028 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:34:32 INFO - 1500147272029 Marionette TRACE 360 <- [1,6,null,{}]
12:34:32 INFO - 1500147272031 Marionette DEBUG Closed connection 360
12:34:32 INFO - 1500147272031 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49807
12:34:32 INFO - 1500147272032 Marionette DEBUG Closed connection 361
12:34:32 INFO - 1500147272033 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49808
12:34:32 INFO - 1500147272034 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:32 INFO - 1500147272034 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272036 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272039 Marionette TRACE 362 <- [1,1,null,{"sessionId":"387450ef-ac71-4437-887e-1701ac7ba3da","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272040 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:34:32 INFO - 1500147272042 Marionette TRACE 362 <- [1,2,null,{}]
12:34:32 INFO - 1500147272043 Marionette DEBUG Closed connection 362
12:34:32 INFO - 1500147272044 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49809
12:34:32 INFO - 1500147272046 Marionette DEBUG Closed connection 363
12:34:32 INFO - 1500147272047 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49810
12:34:32 INFO - 1500147272048 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:34:32 INFO - 1500147272048 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272050 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272053 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a0970a85-2155-4926-9a84-bd2ea2b1f173","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272055 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:34:32 INFO - 1500147272056 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/test.html"
12:34:32 INFO - 1500147272058 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/test.html"
12:34:32 INFO - 1500147272058 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/test.html"
12:34:32 INFO - 1500147272074 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272078 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272087 Marionette TRACE 364 <- [1,2,null,{}]
12:34:32 INFO - 1500147272088 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:34:32 INFO - 1500147272091 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"568f09fc-015c-4a69-ae9e-8d9010b287f4","ELEMENT":"568f09fc-015c-4a69-ae9e-8d9010b287f4"}}]
12:34:32 INFO - 1500147272092 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"568f09fc-015c-4a69-ae9e-8d9010b287f4"}]
12:34:32 INFO - 1500147272330 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:34:32 INFO - 1500147272330 Marionette TRACE 364 <- [1,4,null,{}]
12:34:32 INFO - 1500147272331 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"568f09fc-015c-4a69-ae9e-8d9010b287f4","name":"selected"}]
12:34:32 INFO - 1500147272335 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:34:32 INFO - 1500147272337 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
12:34:32 INFO - TEST-END: test_click.py TestClick.test_container_element1500147272339 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:34:32 INFO - 1500147272340 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:34:32 INFO - 1500147272341 Marionette TRACE 364 <- [1,7,null,{}]
12:34:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 344ms
12:34:32 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:34:32 INFO - 1500147272343 Marionette DEBUG Closed connection 364
12:34:32 INFO - 1500147272344 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49811
12:34:32 INFO - 1500147272345 Marionette DEBUG Closed connection 365
12:34:32 INFO - 1500147272346 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49812
12:34:32 INFO - 1500147272347 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:32 INFO - 1500147272347 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272349 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272352 Marionette TRACE 366 <- [1,1,null,{"sessionId":"f9a65cf2-cc29-4d30-b123-839fdf5b53fe","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272353 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:34:32 INFO - 1500147272354 Marionette TRACE 366 <- [1,2,null,{}]
12:34:32 INFO - 1500147272355 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:34:32 INFO - 1500147272355 Marionette TRACE 366 <- [1,3,null,{}]
12:34:32 INFO - 1500147272356 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:34:32 INFO - 1500147272356 Marionette TRACE 366 <- [1,4,null,{}]
12:34:32 INFO - 1500147272358 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
12:34:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500147272360 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:34:32 INFO - 1500147272361 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:34:32 INFO - 1500147272362 Marionette TRACE 366 <- [1,6,null,{}]
12:34:32 INFO - 1500147272363 Marionette DEBUG Closed connection 366
12:34:32 INFO - 1500147272364 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49813
12:34:32 INFO - 1500147272365 Marionette DEBUG Closed connection 367
12:34:32 INFO - 1500147272366 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49814
12:34:32 INFO - 1500147272366 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:32 INFO - 1500147272367 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272369 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272371 Marionette TRACE 368 <- [1,1,null,{"sessionId":"6a3f53b0-3809-444a-b446-46d0868b2900","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272373 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:34:32 INFO - 1500147272374 Marionette TRACE 368 <- [1,2,null,{}]
12:34:32 INFO - 1500147272375 Marionette DEBUG Closed connection 368
12:34:32 INFO - 1500147272376 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49815
12:34:32 INFO - 1500147272377 Marionette DEBUG Closed connection 369
12:34:32 INFO - 1500147272378 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49816
12:34:32 INFO - 1500147272378 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:34:32 INFO - 1500147272379 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272381 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272383 Marionette TRACE 370 <- [1,1,null,{"sessionId":"9e90626a-625a-4d14-9438-abae88e8eb8b","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272385 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:34:32 INFO - 1500147272387 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272389 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272389 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272405 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272407 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272411 Marionette TRACE 370 <- [1,2,null,{}]
12:34:32 INFO - 1500147272417 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:34:32 INFO - 1500147272420 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dd9c81ee-e85f-4f3a-a5f1-3bc40d5a0ebd","ELEMENT":"dd9c81ee-e85f-4f3a-a5f1-3bc40d5a0ebd"}}]
12:34:32 INFO - 1500147272421 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"dd9c81ee-e85f-4f3a-a5f1-3bc40d5a0ebd"}]
12:34:32 INFO - 1500147272661 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:34:32 INFO - 1500147272661 Marionette TRACE 370 <- [1,4,null,{}]
12:34:32 INFO - 1500147272663 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"dd9c81ee-e85f-4f3a-a5f1-3bc40d5a0ebd","name":"selected"}]
12:34:32 INFO - 1500147272666 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:34:32 INFO - 1500147272668 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
12:34:32 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500147272671 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:34:32 INFO - 1500147272671 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:34:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 331ms
12:34:32 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:34:32 INFO - 1500147272673 Marionette TRACE 370 <- [1,7,null,{}]
12:34:32 INFO - 1500147272674 Marionette DEBUG Closed connection 370
12:34:32 INFO - 1500147272675 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49817
12:34:32 INFO - 1500147272676 Marionette DEBUG Closed connection 371
12:34:32 INFO - 1500147272677 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49818
12:34:32 INFO - 1500147272678 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:32 INFO - 1500147272678 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272680 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272683 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9849ed38-a132-409b-8c7b-29a1b022934b","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272685 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:34:32 INFO - 1500147272685 Marionette TRACE 372 <- [1,2,null,{}]
12:34:32 INFO - 1500147272686 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:34:32 INFO - 1500147272686 Marionette TRACE 372 <- [1,3,null,{}]
12:34:32 INFO - 1500147272687 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:34:32 INFO - 1500147272688 Marionette TRACE 372 <- [1,4,null,{}]
12:34:32 INFO - 1500147272689 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
12:34:32 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500147272692 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:34:32 INFO - 1500147272692 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:34:32 INFO - 1500147272693 Marionette TRACE 372 <- [1,6,null,{}]
12:34:32 INFO - 1500147272695 Marionette DEBUG Closed connection 372
12:34:32 INFO - 1500147272696 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49819
12:34:32 INFO - 1500147272697 Marionette DEBUG Closed connection 373
12:34:32 INFO - 1500147272698 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49820
12:34:32 INFO - 1500147272699 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:32 INFO - 1500147272699 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272701 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272711 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2053c187-7802-4ac4-abe2-a637a940430c","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272712 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:34:32 INFO - 1500147272714 Marionette TRACE 374 <- [1,2,null,{}]
12:34:32 INFO - 1500147272716 Marionette DEBUG Closed connection 374
12:34:32 INFO - 1500147272716 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49821
12:34:32 INFO - 1500147272717 Marionette DEBUG Closed connection 375
12:34:32 INFO - 1500147272718 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49822
12:34:32 INFO - 1500147272719 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:34:32 INFO - 1500147272719 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:32 INFO - 1500147272721 Marionette DEBUG Register listener.js for window 15
12:34:32 INFO - 1500147272724 Marionette TRACE 376 <- [1,1,null,{"sessionId":"4be6ddc5-9e1c-4cff-aba7-27a9cd20edda","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:32 INFO - 1500147272725 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
12:34:32 INFO - 1500147272727 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272730 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272731 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:32 INFO - 1500147272757 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:34:32 INFO - 1500147272762 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:34:32 INFO - 1500147272772 Marionette TRACE 376 <- [1,2,null,{}]
12:34:32 INFO - 1500147272773 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:34:32 INFO - 1500147272776 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5feae4d5-2f68-4465-9fc3-f889ea04aeaa","ELEMENT":"5feae4d5-2f68-4465-9fc3-f889ea04aeaa"}}]
12:34:32 INFO - 1500147272776 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"5feae4d5-2f68-4465-9fc3-f889ea04aeaa"}]
12:34:33 INFO - 1500147273012 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:34:33 INFO - 1500147273012 Marionette TRACE 376 <- [1,4,null,{}]
12:34:33 INFO - 1500147273014 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
12:34:33 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500147273016 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:34:33 INFO - 1500147273017 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:34:33 INFO - 1500147273018 Marionette TRACE 376 <- [1,6,null,{}]
12:34:33 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
12:34:33 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:34:33 INFO - 1500147273020 Marionette DEBUG Closed connection 376
12:34:33 INFO - 1500147273021 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49823
12:34:33 INFO - 1500147273022 Marionette DEBUG Closed connection 377
12:34:33 INFO - 1500147273023 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49824
12:34:33 INFO - 1500147273024 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:33 INFO - 1500147273024 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273026 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273029 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d3f242da-1607-45cd-b56e-ff9cf65c5769","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273031 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:34:33 INFO - 1500147273031 Marionette TRACE 378 <- [1,2,null,{}]
12:34:33 INFO - 1500147273032 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:34:33 INFO - 1500147273032 Marionette TRACE 378 <- [1,3,null,{}]
12:34:33 INFO - 1500147273033 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:34:33 INFO - 1500147273034 Marionette TRACE 378 <- [1,4,null,{}]
12:34:33 INFO - 1500147273035 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
12:34:33 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500147273038 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:34:33 INFO - 1500147273038 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:34:33 INFO - 1500147273039 Marionette TRACE 378 <- [1,6,null,{}]
12:34:33 INFO - 1500147273041 Marionette DEBUG Closed connection 378
12:34:33 INFO - 1500147273042 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49825
12:34:33 INFO - 1500147273042 Marionette DEBUG Closed connection 379
12:34:33 INFO - 1500147273043 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49826
12:34:33 INFO - 1500147273044 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:33 INFO - 1500147273045 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273046 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273049 Marionette TRACE 380 <- [1,1,null,{"sessionId":"19b7fc0d-be1b-4599-bb67-4c28cce547b2","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273051 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:34:33 INFO - 1500147273052 Marionette TRACE 380 <- [1,2,null,{}]
12:34:33 INFO - 1500147273053 Marionette DEBUG Closed connection 380
12:34:33 INFO - 1500147273054 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49827
12:34:33 INFO - 1500147273055 Marionette DEBUG Closed connection 381
12:34:33 INFO - 1500147273056 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49828
12:34:33 INFO - 1500147273056 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:34:33 INFO - 1500147273057 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273059 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273061 Marionette TRACE 382 <- [1,1,null,{"sessionId":"41da5959-5bf6-4f8b-b03c-bf85f20ed8cd","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273063 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:34:33 INFO - 1500147273064 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:34:33 INFO - 1500147273066 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:34:33 INFO - 1500147273067 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:34:33 INFO - 1500147273078 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:33 INFO - 1500147273085 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:33 INFO - 1500147273089 Marionette TRACE 382 <- [1,2,null,{}]
12:34:33 INFO - 1500147273090 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:34:33 INFO - 1500147273092 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"708dd5c5-640e-4dde-820e-ccee0c352d8f","ELEMENT":"708dd5c5-640e-4dde-820e-ccee0c352d8f"}}]
12:34:33 INFO - 1500147273093 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"708dd5c5-640e-4dde-820e-ccee0c352d8f"}]
12:34:33 INFO - 1500147273334 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:34:33 INFO - 1500147273334 Marionette TRACE 382 <- [1,4,null,{}]
12:34:33 INFO - 1500147273336 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
12:34:33 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500147273339 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:34:33 INFO - 1500147273339 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:34:33 INFO - 1500147273341 Marionette TRACE 382 <- [1,6,null,{}]
12:34:33 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 322ms
12:34:33 INFO - TEST-START | test_click.py TestClick.test_input_file
12:34:33 INFO - 1500147273342 Marionette DEBUG Closed connection 382
12:34:33 INFO - 1500147273344 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49829
12:34:33 INFO - 1500147273344 Marionette DEBUG Closed connection 383
12:34:33 INFO - 1500147273345 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49830
12:34:33 INFO - 1500147273346 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:33 INFO - 1500147273347 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273349 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273351 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f3eddc84-aa20-4b69-9786-7f635ae1c25f","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273353 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:34:33 INFO - 1500147273353 Marionette TRACE 384 <- [1,2,null,{}]
12:34:33 INFO - 1500147273354 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:34:33 INFO - 1500147273355 Marionette TRACE 384 <- [1,3,null,{}]
12:34:33 INFO - 1500147273355 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:34:33 INFO - 1500147273356 Marionette TRACE 384 <- [1,4,null,{}]
12:34:33 INFO - 1500147273357 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
12:34:33 INFO - TEST-START: test_click.py TestClick.test_input_file1500147273360 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:34:33 INFO - 1500147273361 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:34:33 INFO - 1500147273362 Marionette TRACE 384 <- [1,6,null,{}]
12:34:33 INFO - 1500147273363 Marionette DEBUG Closed connection 384
12:34:33 INFO - 1500147273364 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49831
12:34:33 INFO - 1500147273365 Marionette DEBUG Closed connection 385
12:34:33 INFO - 1500147273366 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49832
12:34:33 INFO - 1500147273366 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:33 INFO - 1500147273367 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273369 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273371 Marionette TRACE 386 <- [1,1,null,{"sessionId":"823532d4-553a-4f15-a457-6cd897b70d3c","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273380 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:34:33 INFO - 1500147273381 Marionette TRACE 386 <- [1,2,null,{}]
12:34:33 INFO - 1500147273383 Marionette DEBUG Closed connection 386
12:34:33 INFO - 1500147273383 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49833
12:34:33 INFO - 1500147273384 Marionette DEBUG Closed connection 387
12:34:33 INFO - 1500147273385 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49834
12:34:33 INFO - 1500147273386 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:34:33 INFO - 1500147273386 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273388 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273390 Marionette TRACE 388 <- [1,1,null,{"sessionId":"341f23e7-8b36-4672-9179-710fe0d44c2f","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273392 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:34:33 INFO - 1500147273394 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:33 INFO - 1500147273396 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:33 INFO - 1500147273397 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:34:33 INFO - 1500147273407 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:34:33 INFO - 1500147273411 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:34:33 INFO - 1500147273420 Marionette TRACE 388 <- [1,2,null,{}]
12:34:33 INFO - 1500147273421 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:34:33 INFO - 1500147273424 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"598f35b3-0b25-4503-9f72-a36db498fccf","ELEMENT":"598f35b3-0b25-4503-9f72-a36db498fccf"}}]
12:34:33 INFO - 1500147273425 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"598f35b3-0b25-4503-9f72-a36db498fccf"}]
12:34:33 INFO - 1500147273448 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:34:33 INFO - 1500147273450 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
12:34:33 INFO - TEST-END: test_click.py TestClick.test_input_file1500147273452 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:34:33 INFO - 1500147273453 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:34:33 INFO - 1500147273454 Marionette TRACE 388 <- [1,6,null,{}]
12:34:33 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 113ms
12:34:33 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:34:33 INFO - 1500147273456 Marionette DEBUG Closed connection 388
12:34:33 INFO - 1500147273457 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49835
12:34:33 INFO - 1500147273457 Marionette DEBUG Closed connection 389
12:34:33 INFO - 1500147273458 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49836
12:34:33 INFO - 1500147273459 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:33 INFO - 1500147273460 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273462 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273464 Marionette TRACE 390 <- [1,1,null,{"sessionId":"12bf8aa1-2601-40fd-995e-078318780ad5","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273466 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:34:33 INFO - 1500147273466 Marionette TRACE 390 <- [1,2,null,{}]
12:34:33 INFO - 1500147273467 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:34:33 INFO - 1500147273468 Marionette TRACE 390 <- [1,3,null,{}]
12:34:33 INFO - 1500147273468 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:34:33 INFO - 1500147273469 Marionette TRACE 390 <- [1,4,null,{}]
12:34:33 INFO - 1500147273470 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
12:34:33 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500147273472 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:34:33 INFO - 1500147273473 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:34:33 INFO - 1500147273474 Marionette TRACE 390 <- [1,6,null,{}]
12:34:33 INFO - 1500147273476 Marionette DEBUG Closed connection 390
12:34:33 INFO - 1500147273477 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49837
12:34:33 INFO - 1500147273477 Marionette DEBUG Closed connection 391
12:34:33 INFO - 1500147273478 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49838
12:34:33 INFO - 1500147273479 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:33 INFO - 1500147273479 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273481 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273484 Marionette TRACE 392 <- [1,1,null,{"sessionId":"555602ea-8f68-4d7d-8bd7-8f32a5475707","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273485 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:34:33 INFO - 1500147273487 Marionette TRACE 392 <- [1,2,null,{}]
12:34:33 INFO - 1500147273488 Marionette DEBUG Closed connection 392
12:34:33 INFO - 1500147273489 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49839
12:34:33 INFO - 1500147273490 Marionette DEBUG Closed connection 393
12:34:33 INFO - 1500147273490 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49840
12:34:33 INFO - 1500147273491 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:34:33 INFO - 1500147273492 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273493 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273496 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c0ff8ebf-5368-4724-9586-8ebb0d59f83a","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273498 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
12:34:33 INFO - 1500147273499 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:34:33 INFO - 1500147273501 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:34:33 INFO - 1500147273501 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:34:33 INFO - 1500147273511 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:34:33 INFO - 1500147273515 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:34:33 INFO - 1500147273524 Marionette TRACE 394 <- [1,2,null,{}]
12:34:33 INFO - 1500147273525 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:34:33 INFO - 1500147273530 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"56b5f12b-c23b-47cb-b878-af83f5991b08","ELEMENT":"56b5f12b-c23b-47cb-b878-af83f5991b08"}}]
12:34:33 INFO - 1500147273531 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:34:33 INFO - 1500147273533 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"62814f5c-63e0-49d4-af2a-c171f36441fc","ELEMENT":"62814f5c-63e0-49d4-af2a-c171f36441fc"}}]
12:34:33 INFO - 1500147273534 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"56b5f12b-c23b-47cb-b878-af83f5991b08"}]
12:34:33 INFO - 1500147273767 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:34:33 INFO - 1500147273767 Marionette TRACE 394 <- [1,5,null,{}]
12:34:33 INFO - 1500147273768 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"62814f5c-63e0-49d4-af2a-c171f36441fc"}]
12:34:33 INFO - 1500147273782 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:34:33 INFO - 1500147273783 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:34:33 INFO - 1500147273786 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:34:33 INFO - 1500147273787 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
12:34:33 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500147273789 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:34:33 INFO - 1500147273790 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:34:33 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
12:34:33 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:34:33 INFO - 1500147273791 Marionette TRACE 394 <- [1,9,null,{}]
12:34:33 INFO - 1500147273793 Marionette DEBUG Closed connection 394
12:34:33 INFO - 1500147273794 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49841
12:34:33 INFO - 1500147273795 Marionette DEBUG Closed connection 395
12:34:33 INFO - 1500147273796 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49842
12:34:33 INFO - 1500147273797 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:33 INFO - 1500147273797 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273800 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273802 Marionette TRACE 396 <- [1,1,null,{"sessionId":"7694368c-73e6-437a-a293-97228f5d2f4f","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273804 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:34:33 INFO - 1500147273804 Marionette TRACE 396 <- [1,2,null,{}]
12:34:33 INFO - 1500147273805 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:34:33 INFO - 1500147273806 Marionette TRACE 396 <- [1,3,null,{}]
12:34:33 INFO - 1500147273806 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:34:33 INFO - 1500147273807 Marionette TRACE 396 <- [1,4,null,{}]
12:34:33 INFO - 1500147273808 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
12:34:33 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500147273811 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:34:33 INFO - 1500147273811 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:34:33 INFO - 1500147273812 Marionette TRACE 396 <- [1,6,null,{}]
12:34:33 INFO - 1500147273814 Marionette DEBUG Closed connection 396
12:34:33 INFO - 1500147273815 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49843
12:34:33 INFO - 1500147273815 Marionette DEBUG Closed connection 397
12:34:33 INFO - 1500147273816 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49844
12:34:33 INFO - 1500147273817 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:34:33 INFO - 1500147273818 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273819 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273822 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b46c0596-422e-4550-be9a-2c88473c6972","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273824 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:34:33 INFO - 1500147273825 Marionette TRACE 398 <- [1,2,null,{}]
12:34:33 INFO - 1500147273826 Marionette DEBUG Closed connection 398
12:34:33 INFO - 1500147273827 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49845
12:34:33 INFO - 1500147273827 Marionette DEBUG Closed connection 399
12:34:33 INFO - 1500147273828 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49846
12:34:33 INFO - 1500147273829 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:34:33 INFO - 1500147273830 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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}
12:34:33 INFO - 1500147273832 Marionette DEBUG Register listener.js for window 15
12:34:33 INFO - 1500147273841 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c4884a8b-5413-4f2c-a2d6-1a7c21a88250","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":1524,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpod3ylh.mozrunner","moz:accessibilityChecks":false}}]
12:34:33 INFO - 1500147273843 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
12:34:33 INFO - 1500147273844 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:34:33 INFO - 1500147273847 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:34:33 INFO - 1500147273847 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:34:33 INFO - 1500147273857 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
12:34:33 INFO - 1500147273860 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
12:34:33 INFO - 1500147273863 Marionette TRACE 400 <- [1,2,null,{}]
12:34:33 INFO - 1500147273871 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:34:33 INFO - 1500147273874 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"20da7f46-ea6c-4606-b694-d5de7fec82c6","ELEMENT":"20da7f46-ea6c-4606-b694-d5de7fec82c6"}}]
12:34:33 INFO - 1500147273875 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"20da7f46-ea6c-4606-b694-d5de7fec82c6"}]
12:34:33 INFO - 1500147273894 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:34:33 INFO - 1500147273895 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"20da7f46-ea6c-4606-b694-d5de7fec82c6"}]
12:34:33 INFO - 1500147273908 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element