could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
03:37:17 INFO - 1499164637927 Marionette TRACE 353 -> [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}]
03:37:17 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499164637930 Marionette TRACE 353 <- [1,5,null,{"value":null}]
03:37:17 INFO - 1499164637931 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
03:37:17 INFO - 1499164637933 Marionette TRACE 353 <- [1,6,null,{}]
03:37:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 133ms
03:37:17 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:37:17 INFO - 1499164637934 Marionette DEBUG Closed connection 353
03:37:17 INFO - 1499164637935 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50072
03:37:17 INFO - 1499164637936 Marionette DEBUG Closed connection 354
03:37:17 INFO - 1499164637937 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50073
03:37:17 INFO - 1499164637938 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:17 INFO - 1499164637938 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:17 INFO - 1499164637940 Marionette DEBUG Register listener.js for window 4294967297
03:37:17 INFO - 1499164637944 Marionette TRACE 355 <- [1,1,null,{"sessionId":"d987869d-3324-403a-ad18-6bd5c617f1cf","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:17 INFO - 1499164637945 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
03:37:17 INFO - 1499164637945 Marionette TRACE 355 <- [1,2,null,{}]
03:37:17 INFO - 1499164637946 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:37:17 INFO - 1499164637946 Marionette TRACE 355 <- [1,3,null,{}]
03:37:17 INFO - 1499164637947 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
03:37:17 INFO - 1499164637948 Marionette TRACE 355 <- [1,4,null,{}]
03:37:17 INFO - 1499164637950 Marionette TRACE 355 -> [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}]
03:37:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499164637953 Marionette TRACE 355 <- [1,5,null,{"value":null}]
03:37:17 INFO - 1499164637954 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
03:37:17 INFO - 1499164637956 Marionette TRACE 355 <- [1,6,null,{}]
03:37:17 INFO - 1499164637958 Marionette DEBUG Closed connection 355
03:37:17 INFO - 1499164637959 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50074
03:37:17 INFO - 1499164637960 Marionette DEBUG Closed connection 356
03:37:17 INFO - 1499164637961 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50075
03:37:17 INFO - 1499164637962 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:17 INFO - 1499164637962 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:17 INFO - 1499164637965 Marionette DEBUG Register listener.js for window 4294967297
03:37:17 INFO - 1499164637968 Marionette TRACE 357 <- [1,1,null,{"sessionId":"094f0594-3795-4041-a288-fdc1727ce396","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:17 INFO - 1499164637969 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
03:37:17 INFO - 1499164637971 Marionette TRACE 357 <- [1,2,null,{}]
03:37:17 INFO - 1499164637972 Marionette DEBUG Closed connection 357
03:37:17 INFO - 1499164637973 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50076
03:37:17 INFO - 1499164637973 Marionette DEBUG Closed connection 358
03:37:17 INFO - 1499164637974 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50077
03:37:17 INFO - 1499164637975 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:37:17 INFO - 1499164637976 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:17 INFO - 1499164637984 Marionette DEBUG Register listener.js for window 4294967297
03:37:17 INFO - 1499164637987 Marionette TRACE 359 <- [1,1,null,{"sessionId":"771ac410-3bde-4961-917e-fe143d785921","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:17 INFO - 1499164637988 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49635/clicks.html"}]
03:37:17 INFO - 1499164637990 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49635/hidden.html"
03:37:18 INFO - 1499164637997 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49635/hidden.html"
03:37:18 INFO - 1499164637997 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49635/hidden.html"
03:37:18 INFO - 1499164638004 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49635/clicks.html"
03:37:18 INFO - 1499164638009 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49635/clicks.html"
03:37:18 INFO - 1499164638012 Marionette TRACE 359 <- [1,2,null,{}]
03:37:18 INFO - 1499164638020 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:37:18 INFO - 1499164638024 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"026cd2bc-28b5-4d8f-b296-47606b8db840","ELEMENT":"026cd2bc-28b5-4d8f-b296-47606b8db840"}}]
03:37:18 INFO - 1499164638025 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"026cd2bc-28b5-4d8f-b296-47606b8db840"}]
03:37:18 INFO - 1499164638064 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49635/clicks.html"
03:37:18 INFO - 1499164638073 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49635/clicks.html"
03:37:18 INFO - 1499164638073 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49635/clicks.html"
03:37:18 INFO - 1499164638080 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49635/test.html"
03:37:18 INFO - 1499164638084 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49635/test.html"
03:37:18 INFO - 1499164638095 Marionette TRACE 359 <- [1,4,null,{}]
03:37:18 INFO - 1499164638107 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:37:18 INFO - 1499164638109 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"edf69b8d-a5f8-452b-ab7f-60728d24199d","ELEMENT":"edf69b8d-a5f8-452b-ab7f-60728d24199d"}}]
03:37:18 INFO - 1499164638111 Marionette TRACE 359 -> [0,6,"getTitle",{}]
03:37:18 INFO - 1499164638111 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
03:37:18 INFO - 1499164638113 Marionette TRACE 359 -> [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}]
03:37:18 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499164638116 Marionette TRACE 359 <- [1,7,null,{"value":null}]
03:37:18 INFO - 1499164638117 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
03:37:18 INFO - 1499164638118 Marionette TRACE 359 <- [1,8,null,{}]
03:37:18 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 186ms
03:37:18 INFO - TEST-START | test_click.py TestClick.test_container_element
03:37:18 INFO - 1499164638120 Marionette DEBUG Closed connection 359
03:37:18 INFO - 1499164638121 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50080
03:37:18 INFO - 1499164638122 Marionette DEBUG Closed connection 360
03:37:18 INFO - 1499164638123 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50081
03:37:18 INFO - 1499164638124 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:18 INFO - 1499164638125 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638127 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638130 Marionette TRACE 361 <- [1,1,null,{"sessionId":"0f5ab907-d0b8-4264-bccc-b0735365f32d","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638131 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
03:37:18 INFO - 1499164638132 Marionette TRACE 361 <- [1,2,null,{}]
03:37:18 INFO - 1499164638133 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:37:18 INFO - 1499164638133 Marionette TRACE 361 <- [1,3,null,{}]
03:37:18 INFO - 1499164638134 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
03:37:18 INFO - 1499164638134 Marionette TRACE 361 <- [1,4,null,{}]
03:37:18 INFO - 1499164638136 Marionette TRACE 361 -> [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}]
03:37:18 INFO - TEST-START: test_click.py TestClick.test_container_element1499164638140 Marionette TRACE 361 <- [1,5,null,{"value":null}]
03:37:18 INFO - 1499164638141 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
03:37:18 INFO - 1499164638142 Marionette TRACE 361 <- [1,6,null,{}]
03:37:18 INFO - 1499164638144 Marionette DEBUG Closed connection 361
03:37:18 INFO - 1499164638145 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50082
03:37:18 INFO - 1499164638146 Marionette DEBUG Closed connection 362
03:37:18 INFO - 1499164638146 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50083
03:37:18 INFO - 1499164638147 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:18 INFO - 1499164638148 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638150 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638153 Marionette TRACE 363 <- [1,1,null,{"sessionId":"a21d2ee6-42e7-49a5-a38d-8db6538ef0e8","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638154 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
03:37:18 INFO - 1499164638156 Marionette TRACE 363 <- [1,2,null,{}]
03:37:18 INFO - 1499164638157 Marionette DEBUG Closed connection 363
03:37:18 INFO - 1499164638158 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50084
03:37:18 INFO - 1499164638158 Marionette DEBUG Closed connection 364
03:37:18 INFO - 1499164638159 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50085
03:37:18 INFO - 1499164638160 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:37:18 INFO - 1499164638161 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638163 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638166 Marionette TRACE 365 <- [1,1,null,{"sessionId":"0376a4d3-10cb-4d87-a74d-82562d9ec4e4","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638168 Marionette TRACE 365 -> [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"}]
03:37:18 INFO - 1499164638170 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49635/test.html"
03:37:18 INFO - 1499164638172 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49635/test.html"
03:37:18 INFO - 1499164638172 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49635/test.html"
03:37:18 INFO - 1499164638178 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"
03:37:18 INFO - 1499164638181 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"
03:37:18 INFO - 1499164638192 Marionette TRACE 365 <- [1,2,null,{}]
03:37:18 INFO - 1499164638196 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:37:18 INFO - 1499164638202 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"99d6c15b-a888-42ae-88ef-90ccde74c04b","ELEMENT":"99d6c15b-a888-42ae-88ef-90ccde74c04b"}}]
03:37:18 INFO - 1499164638204 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"99d6c15b-a888-42ae-88ef-90ccde74c04b"}]
03:37:18 INFO - 1499164638443 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:37:18 INFO - 1499164638444 Marionette TRACE 365 <- [1,4,null,{}]
03:37:18 INFO - 1499164638445 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"99d6c15b-a888-42ae-88ef-90ccde74c04b","name":"selected"}]
03:37:18 INFO - 1499164638449 Marionette TRACE 365 <- [1,5,null,{"value":true}]
03:37:18 INFO - 1499164638451 Marionette TRACE 365 -> [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}]
03:37:18 INFO - TEST-END: test_click.py TestClick.test_container_element1499164638454 Marionette TRACE 365 <- [1,6,null,{"value":null}]
03:37:18 INFO - 1499164638455 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
03:37:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 337ms
03:37:18 INFO - 1499164638456 Marionette TRACE 365 <- [1,7,null,{}]
03:37:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:37:18 INFO - 1499164638457 Marionette DEBUG Closed connection 365
03:37:18 INFO - 1499164638459 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50086
03:37:18 INFO - 1499164638459 Marionette DEBUG Closed connection 366
03:37:18 INFO - 1499164638460 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50087
03:37:18 INFO - 1499164638461 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:18 INFO - 1499164638462 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638464 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638467 Marionette TRACE 367 <- [1,1,null,{"sessionId":"18dc444f-3f63-431d-8022-1c36531fd7bd","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638468 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
03:37:18 INFO - 1499164638468 Marionette TRACE 367 <- [1,2,null,{}]
03:37:18 INFO - 1499164638469 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:37:18 INFO - 1499164638470 Marionette TRACE 367 <- [1,3,null,{}]
03:37:18 INFO - 1499164638471 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
03:37:18 INFO - 1499164638471 Marionette TRACE 367 <- [1,4,null,{}]
03:37:18 INFO - 1499164638473 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
03:37:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499164638476 Marionette TRACE 367 <- [1,5,null,{"value":null}]
03:37:18 INFO - 1499164638477 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
03:37:18 INFO - 1499164638478 Marionette TRACE 367 <- [1,6,null,{}]
03:37:18 INFO - 1499164638479 Marionette DEBUG Closed connection 367
03:37:18 INFO - 1499164638480 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50088
03:37:18 INFO - 1499164638481 Marionette DEBUG Closed connection 368
03:37:18 INFO - 1499164638483 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50089
03:37:18 INFO - 1499164638484 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:18 INFO - 1499164638484 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638486 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638489 Marionette TRACE 369 <- [1,1,null,{"sessionId":"c9235da2-23d8-486f-af4d-932ab8d2e3c5","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638490 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
03:37:18 INFO - 1499164638491 Marionette TRACE 369 <- [1,2,null,{}]
03:37:18 INFO - 1499164638493 Marionette DEBUG Closed connection 369
03:37:18 INFO - 1499164638493 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50090
03:37:18 INFO - 1499164638494 Marionette DEBUG Closed connection 370
03:37:18 INFO - 1499164638495 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50091
03:37:18 INFO - 1499164638496 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:37:18 INFO - 1499164638496 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638498 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638501 Marionette TRACE 371 <- [1,1,null,{"sessionId":"315ff064-15f2-45ef-a419-16da722b3d4a","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638502 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%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"}]
03:37:18 INFO - 1499164638504 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"
03:37:18 INFO - 1499164638506 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"
03:37:18 INFO - 1499164638506 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"
03:37:18 INFO - 1499164638512 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"
03:37:18 INFO - 1499164638515 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"
03:37:18 INFO - 1499164638521 Marionette TRACE 371 <- [1,2,null,{}]
03:37:18 INFO - 1499164638525 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:37:18 INFO - 1499164638528 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ff3b379b-131c-4d3e-88bf-4fb36b7e9ef2","ELEMENT":"ff3b379b-131c-4d3e-88bf-4fb36b7e9ef2"}}]
03:37:18 INFO - 1499164638529 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"ff3b379b-131c-4d3e-88bf-4fb36b7e9ef2"}]
03:37:18 INFO - 1499164638769 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:37:18 INFO - 1499164638770 Marionette TRACE 371 <- [1,4,null,{}]
03:37:18 INFO - 1499164638771 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"ff3b379b-131c-4d3e-88bf-4fb36b7e9ef2","name":"selected"}]
03:37:18 INFO - 1499164638775 Marionette TRACE 371 <- [1,5,null,{"value":true}]
03:37:18 INFO - 1499164638777 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
03:37:18 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499164638779 Marionette TRACE 371 <- [1,6,null,{"value":null}]
03:37:18 INFO - 1499164638780 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
03:37:18 INFO - 1499164638781 Marionette TRACE 371 <- [1,7,null,{}]
03:37:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 325ms
03:37:18 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:37:18 INFO - 1499164638783 Marionette DEBUG Closed connection 371
03:37:18 INFO - 1499164638784 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50092
03:37:18 INFO - 1499164638785 Marionette DEBUG Closed connection 372
03:37:18 INFO - 1499164638785 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50093
03:37:18 INFO - 1499164638787 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:18 INFO - 1499164638787 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638789 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638792 Marionette TRACE 373 <- [1,1,null,{"sessionId":"64507830-1d3d-49c9-833b-f0043ee49104","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638793 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
03:37:18 INFO - 1499164638794 Marionette TRACE 373 <- [1,2,null,{}]
03:37:18 INFO - 1499164638794 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:37:18 INFO - 1499164638795 Marionette TRACE 373 <- [1,3,null,{}]
03:37:18 INFO - 1499164638796 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
03:37:18 INFO - 1499164638796 Marionette TRACE 373 <- [1,4,null,{}]
03:37:18 INFO - 1499164638798 Marionette TRACE 373 -> [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}]
03:37:18 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499164638800 Marionette TRACE 373 <- [1,5,null,{"value":null}]
03:37:18 INFO - 1499164638801 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
03:37:18 INFO - 1499164638802 Marionette TRACE 373 <- [1,6,null,{}]
03:37:18 INFO - 1499164638804 Marionette DEBUG Closed connection 373
03:37:18 INFO - 1499164638805 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50094
03:37:18 INFO - 1499164638805 Marionette DEBUG Closed connection 374
03:37:18 INFO - 1499164638806 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50095
03:37:18 INFO - 1499164638807 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:18 INFO - 1499164638807 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638810 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638812 Marionette TRACE 375 <- [1,1,null,{"sessionId":"991ad75c-1547-4a10-9e41-e6c87ef1ea22","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638814 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
03:37:18 INFO - 1499164638815 Marionette TRACE 375 <- [1,2,null,{}]
03:37:18 INFO - 1499164638816 Marionette DEBUG Closed connection 375
03:37:18 INFO - 1499164638817 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50096
03:37:18 INFO - 1499164638817 Marionette DEBUG Closed connection 376
03:37:18 INFO - 1499164638818 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50097
03:37:18 INFO - 1499164638819 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:37:18 INFO - 1499164638820 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:18 INFO - 1499164638822 Marionette DEBUG Register listener.js for window 4294967297
03:37:18 INFO - 1499164638825 Marionette TRACE 377 <- [1,1,null,{"sessionId":"29aee9b1-33d5-4735-9ea5-86a3af262607","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:18 INFO - 1499164638826 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%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"}]
03:37:18 INFO - 1499164638827 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"
03:37:18 INFO - 1499164638830 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"
03:37:18 INFO - 1499164638830 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"
03:37:18 INFO - 1499164638836 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"
03:37:18 INFO - 1499164638838 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"
03:37:18 INFO - 1499164638842 Marionette TRACE 377 <- [1,2,null,{}]
03:37:18 INFO - 1499164638845 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:37:18 INFO - 1499164638853 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d664b003-af9a-4b74-a73c-3115bb031d7a","ELEMENT":"d664b003-af9a-4b74-a73c-3115bb031d7a"}}]
03:37:18 INFO - 1499164638855 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"d664b003-af9a-4b74-a73c-3115bb031d7a"}]
03:37:19 INFO - 1499164639093 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:37:19 INFO - 1499164639094 Marionette TRACE 377 <- [1,4,null,{}]
03:37:19 INFO - 1499164639096 Marionette TRACE 377 -> [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}]
03:37:19 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499164639099 Marionette TRACE 377 <- [1,5,null,{"value":null}]
03:37:19 INFO - 1499164639100 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
03:37:19 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 319ms
03:37:19 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:37:19 INFO - 1499164639101 Marionette TRACE 377 <- [1,6,null,{}]
03:37:19 INFO - 1499164639102 Marionette DEBUG Closed connection 377
03:37:19 INFO - 1499164639103 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50098
03:37:19 INFO - 1499164639104 Marionette DEBUG Closed connection 378
03:37:19 INFO - 1499164639105 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50099
03:37:19 INFO - 1499164639106 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:19 INFO - 1499164639107 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639109 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639112 Marionette TRACE 379 <- [1,1,null,{"sessionId":"a9706ec5-3ee0-45b8-8ce9-d59c10525f25","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639113 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
03:37:19 INFO - 1499164639114 Marionette TRACE 379 <- [1,2,null,{}]
03:37:19 INFO - 1499164639115 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:37:19 INFO - 1499164639115 Marionette TRACE 379 <- [1,3,null,{}]
03:37:19 INFO - 1499164639116 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
03:37:19 INFO - 1499164639116 Marionette TRACE 379 <- [1,4,null,{}]
03:37:19 INFO - 1499164639118 Marionette TRACE 379 -> [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}]
03:37:19 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499164639121 Marionette TRACE 379 <- [1,5,null,{"value":null}]
03:37:19 INFO - 1499164639122 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
03:37:19 INFO - 1499164639123 Marionette TRACE 379 <- [1,6,null,{}]
03:37:19 INFO - 1499164639125 Marionette DEBUG Closed connection 379
03:37:19 INFO - 1499164639125 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50100
03:37:19 INFO - 1499164639126 Marionette DEBUG Closed connection 380
03:37:19 INFO - 1499164639127 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50101
03:37:19 INFO - 1499164639128 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:19 INFO - 1499164639129 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639131 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639134 Marionette TRACE 381 <- [1,1,null,{"sessionId":"aa246140-f6b8-411c-9c1b-18f8ad9f72c6","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639135 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
03:37:19 INFO - 1499164639136 Marionette TRACE 381 <- [1,2,null,{}]
03:37:19 INFO - 1499164639137 Marionette DEBUG Closed connection 381
03:37:19 INFO - 1499164639138 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50102
03:37:19 INFO - 1499164639139 Marionette DEBUG Closed connection 382
03:37:19 INFO - 1499164639140 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50103
03:37:19 INFO - 1499164639141 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:37:19 INFO - 1499164639141 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639144 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639147 Marionette TRACE 383 <- [1,1,null,{"sessionId":"920ce889-d1f9-415b-a353-7bfea32ac897","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639148 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%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"}]
03:37:19 INFO - 1499164639150 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"
03:37:19 INFO - 1499164639152 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"
03:37:19 INFO - 1499164639152 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"
03:37:19 INFO - 1499164639158 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"
03:37:19 INFO - 1499164639161 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"
03:37:19 INFO - 1499164639163 Marionette TRACE 383 <- [1,2,null,{}]
03:37:19 INFO - 1499164639170 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:37:19 INFO - 1499164639177 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f29e4d06-25e2-4114-8ba9-a6da5eaa13ab","ELEMENT":"f29e4d06-25e2-4114-8ba9-a6da5eaa13ab"}}]
03:37:19 INFO - 1499164639178 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"f29e4d06-25e2-4114-8ba9-a6da5eaa13ab"}]
03:37:19 INFO - 1499164639419 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:37:19 INFO - 1499164639420 Marionette TRACE 383 <- [1,4,null,{}]
03:37:19 INFO - 1499164639422 Marionette TRACE 383 -> [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}]
03:37:19 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499164639425 Marionette TRACE 383 <- [1,5,null,{"value":null}]
03:37:19 INFO - 1499164639426 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
03:37:19 INFO - 1499164639427 Marionette TRACE 383 <- [1,6,null,{}]
03:37:19 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 326ms
03:37:19 INFO - TEST-START | test_click.py TestClick.test_input_file
03:37:19 INFO - 1499164639429 Marionette DEBUG Closed connection 383
03:37:19 INFO - 1499164639430 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50104
03:37:19 INFO - 1499164639431 Marionette DEBUG Closed connection 384
03:37:19 INFO - 1499164639432 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50105
03:37:19 INFO - 1499164639433 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:19 INFO - 1499164639433 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639436 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639439 Marionette TRACE 385 <- [1,1,null,{"sessionId":"5f88691c-bcef-4d7f-ae43-dbc11b5f7de9","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639440 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
03:37:19 INFO - 1499164639440 Marionette TRACE 385 <- [1,2,null,{}]
03:37:19 INFO - 1499164639441 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:37:19 INFO - 1499164639442 Marionette TRACE 385 <- [1,3,null,{}]
03:37:19 INFO - 1499164639443 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
03:37:19 INFO - 1499164639443 Marionette TRACE 385 <- [1,4,null,{}]
03:37:19 INFO - 1499164639445 Marionette TRACE 385 -> [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}]
03:37:19 INFO - TEST-START: test_click.py TestClick.test_input_file1499164639448 Marionette TRACE 385 <- [1,5,null,{"value":null}]
03:37:19 INFO - 1499164639449 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
03:37:19 INFO - 1499164639450 Marionette TRACE 385 <- [1,6,null,{}]
03:37:19 INFO - 1499164639451 Marionette DEBUG Closed connection 385
03:37:19 INFO - 1499164639452 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50106
03:37:19 INFO - 1499164639453 Marionette DEBUG Closed connection 386
03:37:19 INFO - 1499164639454 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50107
03:37:19 INFO - 1499164639455 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:19 INFO - 1499164639455 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639458 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639460 Marionette TRACE 387 <- [1,1,null,{"sessionId":"4471cf4d-2bd8-487a-97a1-5ab3ad0619a7","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639462 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
03:37:19 INFO - 1499164639463 Marionette TRACE 387 <- [1,2,null,{}]
03:37:19 INFO - 1499164639464 Marionette DEBUG Closed connection 387
03:37:19 INFO - 1499164639465 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50108
03:37:19 INFO - 1499164639466 Marionette DEBUG Closed connection 388
03:37:19 INFO - 1499164639467 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50109
03:37:19 INFO - 1499164639468 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:37:19 INFO - 1499164639468 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639471 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639474 Marionette TRACE 389 <- [1,1,null,{"sessionId":"40356764-3eb7-49ba-b9d2-54bfb5c36413","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639475 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:37:19 INFO - 1499164639476 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"
03:37:19 INFO - 1499164639479 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"
03:37:19 INFO - 1499164639480 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"
03:37:19 INFO - 1499164639489 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:37:19 INFO - 1499164639495 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:37:19 INFO - 1499164639496 Marionette TRACE 389 <- [1,2,null,{}]
03:37:19 INFO - 1499164639509 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:37:19 INFO - 1499164639512 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4dba71c9-9099-4b6c-949d-82ec3fa7d536","ELEMENT":"4dba71c9-9099-4b6c-949d-82ec3fa7d536"}}]
03:37:19 INFO - 1499164639513 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"4dba71c9-9099-4b6c-949d-82ec3fa7d536"}]
03:37:19 INFO - 1499164639539 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
03:37:19 INFO - 1499164639541 Marionette TRACE 389 -> [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}]
03:37:19 INFO - TEST-END: test_click.py TestClick.test_input_file1499164639544 Marionette TRACE 389 <- [1,5,null,{"value":null}]
03:37:19 INFO - 1499164639545 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
03:37:19 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 119ms
03:37:19 INFO - 1499164639546 Marionette TRACE 389 <- [1,6,null,{}]
03:37:19 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:37:19 INFO - 1499164639548 Marionette DEBUG Closed connection 389
03:37:19 INFO - 1499164639549 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50110
03:37:19 INFO - 1499164639549 Marionette DEBUG Closed connection 390
03:37:19 INFO - 1499164639550 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50111
03:37:19 INFO - 1499164639551 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:19 INFO - 1499164639552 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639554 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639557 Marionette TRACE 391 <- [1,1,null,{"sessionId":"938e2060-774f-454c-bcea-2e99185fe31f","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639567 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
03:37:19 INFO - 1499164639567 Marionette TRACE 391 <- [1,2,null,{}]
03:37:19 INFO - 1499164639568 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:37:19 INFO - 1499164639569 Marionette TRACE 391 <- [1,3,null,{}]
03:37:19 INFO - 1499164639569 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
03:37:19 INFO - 1499164639570 Marionette TRACE 391 <- [1,4,null,{}]
03:37:19 INFO - 1499164639572 Marionette TRACE 391 -> [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}]
03:37:19 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499164639575 Marionette TRACE 391 <- [1,5,null,{"value":null}]
03:37:19 INFO - 1499164639576 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
03:37:19 INFO - 1499164639577 Marionette TRACE 391 <- [1,6,null,{}]
03:37:19 INFO - 1499164639578 Marionette DEBUG Closed connection 391
03:37:19 INFO - 1499164639579 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50112
03:37:19 INFO - 1499164639580 Marionette DEBUG Closed connection 392
03:37:19 INFO - 1499164639580 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50113
03:37:19 INFO - 1499164639582 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:19 INFO - 1499164639582 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639585 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639587 Marionette TRACE 393 <- [1,1,null,{"sessionId":"9ad70d3a-5717-4922-a863-dc99e5eaca59","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639589 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
03:37:19 INFO - 1499164639590 Marionette TRACE 393 <- [1,2,null,{}]
03:37:19 INFO - 1499164639591 Marionette DEBUG Closed connection 393
03:37:19 INFO - 1499164639592 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50114
03:37:19 INFO - 1499164639593 Marionette DEBUG Closed connection 394
03:37:19 INFO - 1499164639593 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50115
03:37:19 INFO - 1499164639594 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:37:19 INFO - 1499164639595 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639597 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639600 Marionette TRACE 395 <- [1,1,null,{"sessionId":"4766c686-d3e2-498c-a9cc-39a176b2f60e","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639601 Marionette TRACE 395 -> [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"}]
03:37:19 INFO - 1499164639603 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:37:19 INFO - 1499164639605 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:37:19 INFO - 1499164639605 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:37:19 INFO - 1499164639612 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"
03:37:19 INFO - 1499164639615 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"
03:37:19 INFO - 1499164639621 Marionette TRACE 395 <- [1,2,null,{}]
03:37:19 INFO - 1499164639625 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:37:19 INFO - 1499164639628 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a3a49cf7-c2b3-4d61-a093-fb58416b1734","ELEMENT":"a3a49cf7-c2b3-4d61-a093-fb58416b1734"}}]
03:37:19 INFO - 1499164639629 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:37:19 INFO - 1499164639631 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7426f60c-96e8-4764-8449-e99457aa94e8","ELEMENT":"7426f60c-96e8-4764-8449-e99457aa94e8"}}]
03:37:19 INFO - 1499164639637 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"a3a49cf7-c2b3-4d61-a093-fb58416b1734"}]
03:37:19 INFO - 1499164639885 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:37:19 INFO - 1499164639886 Marionette TRACE 395 <- [1,5,null,{}]
03:37:19 INFO - 1499164639887 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"7426f60c-96e8-4764-8449-e99457aa94e8"}]
03:37:19 INFO - 1499164639902 Marionette TRACE 395 <- [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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
03:37:19 INFO - 1499164639904 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:37:19 INFO - 1499164639907 Marionette TRACE 395 <- [1,7,null,{"value":false}]
03:37:19 INFO - 1499164639909 Marionette TRACE 395 -> [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}]
03:37:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499164639911 Marionette TRACE 395 <- [1,8,null,{"value":null}]
03:37:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 367ms
03:37:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:37:19 INFO - 1499164639913 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
03:37:19 INFO - 1499164639914 Marionette TRACE 395 <- [1,9,null,{}]
03:37:19 INFO - 1499164639915 Marionette DEBUG Closed connection 395
03:37:19 INFO - 1499164639917 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50116
03:37:19 INFO - 1499164639917 Marionette DEBUG Closed connection 396
03:37:19 INFO - 1499164639918 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50117
03:37:19 INFO - 1499164639919 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:19 INFO - 1499164639920 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639922 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639925 Marionette TRACE 397 <- [1,1,null,{"sessionId":"12add19c-7bec-4e25-ab14-72378b5cc37c","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639926 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
03:37:19 INFO - 1499164639927 Marionette TRACE 397 <- [1,2,null,{}]
03:37:19 INFO - 1499164639927 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:37:19 INFO - 1499164639928 Marionette TRACE 397 <- [1,3,null,{}]
03:37:19 INFO - 1499164639929 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
03:37:19 INFO - 1499164639929 Marionette TRACE 397 <- [1,4,null,{}]
03:37:19 INFO - 1499164639931 Marionette TRACE 397 -> [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}]
03:37:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499164639934 Marionette TRACE 397 <- [1,5,null,{"value":null}]
03:37:19 INFO - 1499164639935 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
03:37:19 INFO - 1499164639936 Marionette TRACE 397 <- [1,6,null,{}]
03:37:19 INFO - 1499164639937 Marionette DEBUG Closed connection 397
03:37:19 INFO - 1499164639938 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50118
03:37:19 INFO - 1499164639939 Marionette DEBUG Closed connection 398
03:37:19 INFO - 1499164639940 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50119
03:37:19 INFO - 1499164639941 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:37:19 INFO - 1499164639941 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639944 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639946 Marionette TRACE 399 <- [1,1,null,{"sessionId":"f0c91a29-3520-4662-b27e-33bd0d8cd20e","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639948 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
03:37:19 INFO - 1499164639949 Marionette TRACE 399 <- [1,2,null,{}]
03:37:19 INFO - 1499164639950 Marionette DEBUG Closed connection 399
03:37:19 INFO - 1499164639951 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50120
03:37:19 INFO - 1499164639952 Marionette DEBUG Closed connection 400
03:37:19 INFO - 1499164639952 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50121
03:37:19 INFO - 1499164639953 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:37:19 INFO - 1499164639954 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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}
03:37:19 INFO - 1499164639956 Marionette DEBUG Register listener.js for window 4294967297
03:37:19 INFO - 1499164639959 Marionette TRACE 401 <- [1,1,null,{"sessionId":"b87f6b68-e50b-40f5-be59-b07b2fb6c377","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":3912,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0dthf5.mozrunner","moz:accessibilityChecks":false}}]
03:37:19 INFO - 1499164639960 Marionette TRACE 401 -> [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"}]
03:37:19 INFO - 1499164639962 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"
03:37:19 INFO - 1499164639964 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"
03:37:19 INFO - 1499164639964 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"
03:37:19 INFO - 1499164639971 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"
03:37:19 INFO - 1499164639973 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"
03:37:19 INFO - 1499164639978 Marionette TRACE 401 <- [1,2,null,{}]
03:37:19 INFO - 1499164639981 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:37:19 INFO - 1499164639988 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c06cd56e-33fe-43e3-ae1e-b8c40f2f403e","ELEMENT":"c06cd56e-33fe-43e3-ae1e-b8c40f2f403e"}}]
03:37:19 INFO - 1499164639990 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c06cd56e-33fe-43e3-ae1e-b8c40f2f403e"}]
03:37:20 INFO - 1499164640013 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
03:37:20 INFO - 1499164640015 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"c06cd56e-33fe-43e3-ae1e-b8c40f2f403e"}]
03:37:20 INFO - 1499164640030 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element