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:1330:5\n"},null]
13:51:23 INFO - 1498855883056 Marionette TRACE 358 -> [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}]
13:51:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 152ms
13:51:23 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:51:23 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498855883067 Marionette TRACE 358 <- [1,5,null,{"value":null}]
13:51:23 INFO - 1498855883069 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
13:51:23 INFO - 1498855883070 Marionette TRACE 358 <- [1,6,null,{}]
13:51:23 INFO - 1498855883072 Marionette DEBUG Closed connection 358
13:51:23 INFO - 1498855883073 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50748
13:51:23 INFO - 1498855883074 Marionette DEBUG Closed connection 359
13:51:23 INFO - 1498855883075 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50749
13:51:23 INFO - 1498855883076 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:23 INFO - 1498855883077 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883080 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883086 Marionette TRACE 360 <- [1,1,null,{"sessionId":"5ed5f6c3-4a74-483a-8ca3-c059f3bcbf16","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883088 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:51:23 INFO - 1498855883088 Marionette TRACE 360 <- [1,2,null,{}]
13:51:23 INFO - 1498855883089 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:51:23 INFO - 1498855883090 Marionette TRACE 360 <- [1,3,null,{}]
13:51:23 INFO - 1498855883091 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:51:23 INFO - 1498855883091 Marionette TRACE 360 <- [1,4,null,{}]
13:51:23 INFO - 1498855883094 Marionette TRACE 360 -> [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}]
13:51:23 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498855883097 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:51:23 INFO - 1498855883098 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:51:23 INFO - 1498855883100 Marionette TRACE 360 <- [1,6,null,{}]
13:51:23 INFO - 1498855883102 Marionette DEBUG Closed connection 360
13:51:23 INFO - 1498855883103 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50750
13:51:23 INFO - 1498855883104 Marionette DEBUG Closed connection 361
13:51:23 INFO - 1498855883104 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50751
13:51:23 INFO - 1498855883106 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:23 INFO - 1498855883106 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883110 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883114 Marionette TRACE 362 <- [1,1,null,{"sessionId":"53e87e26-1da7-4914-ac3b-ba43d80dba12","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883116 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
13:51:23 INFO - 1498855883117 Marionette TRACE 362 <- [1,2,null,{}]
13:51:23 INFO - 1498855883119 Marionette DEBUG Closed connection 362
13:51:23 INFO - 1498855883120 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50752
13:51:23 INFO - 1498855883121 Marionette DEBUG Closed connection 363
13:51:23 INFO - 1498855883122 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50753
13:51:23 INFO - 1498855883123 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:51:23 INFO - 1498855883124 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883128 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883132 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a277567f-1410-4c76-85b0-324819859d5f","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883133 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:50346/clicks.html"}]
13:51:23 INFO - 1498855883135 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50346/hidden.html"
13:51:23 INFO - 1498855883144 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50346/hidden.html"
13:51:23 INFO - 1498855883145 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50346/hidden.html"
13:51:23 INFO - 1498855883153 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50346/clicks.html"
13:51:23 INFO - 1498855883161 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50346/clicks.html"
13:51:23 INFO - 1498855883166 Marionette TRACE 364 <- [1,2,null,{}]
13:51:23 INFO - 1498855883176 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:51:23 INFO - 1498855883187 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4b447eb0-701b-43e2-a348-d24489b0a231","ELEMENT":"4b447eb0-701b-43e2-a348-d24489b0a231"}}]
13:51:23 INFO - 1498855883188 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"4b447eb0-701b-43e2-a348-d24489b0a231"}]
13:51:23 INFO - 1498855883242 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50346/clicks.html"
13:51:23 INFO - 1498855883254 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50346/clicks.html"
13:51:23 INFO - 1498855883254 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50346/clicks.html"
13:51:23 INFO - 1498855883264 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50346/test.html"
13:51:23 INFO - 1498855883268 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50346/test.html"
13:51:23 INFO - 1498855883285 Marionette TRACE 364 <- [1,4,null,{}]
13:51:23 INFO - 1498855883297 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:51:23 INFO - 1498855883302 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"591a6c36-d929-4fdd-abec-125de44c4512","ELEMENT":"591a6c36-d929-4fdd-abec-125de44c4512"}}]
13:51:23 INFO - 1498855883309 Marionette TRACE 364 -> [0,6,"getTitle",{}]
13:51:23 INFO - 1498855883312 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
13:51:23 INFO - 1498855883320 Marionette TRACE 364 -> [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}]
13:51:23 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498855883326 Marionette TRACE 364 <- [1,7,null,{"value":null}]
13:51:23 INFO - 1498855883328 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
13:51:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 260ms
13:51:23 INFO - 1498855883331 Marionette TRACE 364 <- [1,8,null,{}]
13:51:23 INFO - TEST-START | test_click.py TestClick.test_container_element
13:51:23 INFO - 1498855883334 Marionette DEBUG Closed connection 364
13:51:23 INFO - 1498855883335 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50756
13:51:23 INFO - 1498855883337 Marionette DEBUG Closed connection 365
13:51:23 INFO - 1498855883338 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50757
13:51:23 INFO - 1498855883340 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:23 INFO - 1498855883342 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883346 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883352 Marionette TRACE 366 <- [1,1,null,{"sessionId":"77f036c1-e1fd-4bde-b0c9-93175e86d918","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883354 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
13:51:23 INFO - 1498855883355 Marionette TRACE 366 <- [1,2,null,{}]
13:51:23 INFO - 1498855883356 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:51:23 INFO - 1498855883357 Marionette TRACE 366 <- [1,3,null,{}]
13:51:23 INFO - 1498855883359 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
13:51:23 INFO - 1498855883360 Marionette TRACE 366 <- [1,4,null,{}]
13:51:23 INFO - 1498855883363 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')","sandbox":"simpletest","line":316}]
13:51:23 INFO - TEST-START: test_click.py TestClick.test_container_element1498855883368 Marionette TRACE 366 <- [1,5,null,{"value":null}]
13:51:23 INFO - 1498855883369 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
13:51:23 INFO - 1498855883372 Marionette TRACE 366 <- [1,6,null,{}]
13:51:23 INFO - 1498855883374 Marionette DEBUG Closed connection 366
13:51:23 INFO - 1498855883376 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50758
13:51:23 INFO - 1498855883377 Marionette DEBUG Closed connection 367
13:51:23 INFO - 1498855883378 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50759
13:51:23 INFO - 1498855883382 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:23 INFO - 1498855883383 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883429 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883438 Marionette TRACE 368 <- [1,1,null,{"sessionId":"29d2da2b-0f7d-42fe-bf0d-fb81611e8f99","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883439 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
13:51:23 INFO - 1498855883442 Marionette TRACE 368 <- [1,2,null,{}]
13:51:23 INFO - 1498855883444 Marionette DEBUG Closed connection 368
13:51:23 INFO - 1498855883445 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50760
13:51:23 INFO - 1498855883478 Marionette DEBUG Closed connection 369
13:51:23 INFO - 1498855883479 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50761
13:51:23 INFO - 1498855883481 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:51:23 INFO - 1498855883482 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883485 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883491 Marionette TRACE 370 <- [1,1,null,{"sessionId":"465ff351-e97d-4cf9-a272-80c1902ee852","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883493 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%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"}]
13:51:23 INFO - 1498855883547 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50346/test.html"
13:51:23 INFO - 1498855883551 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50346/test.html"
13:51:23 INFO - 1498855883552 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50346/test.html"
13:51:23 INFO - 1498855883560 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"
13:51:23 INFO - 1498855883565 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"
13:51:23 INFO - 1498855883580 Marionette TRACE 370 <- [1,2,null,{}]
13:51:23 INFO - 1498855883594 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:51:23 INFO - 1498855883608 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b467b6a-4b96-409e-b5cf-8a7f08a5ea01","ELEMENT":"0b467b6a-4b96-409e-b5cf-8a7f08a5ea01"}}]
13:51:23 INFO - 1498855883610 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"0b467b6a-4b96-409e-b5cf-8a7f08a5ea01"}]
13:51:23 INFO - 1498855883862 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:51:23 INFO - 1498855883864 Marionette TRACE 370 <- [1,4,null,{}]
13:51:23 INFO - 1498855883866 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"0b467b6a-4b96-409e-b5cf-8a7f08a5ea01","name":"selected"}]
13:51:23 INFO - 1498855883873 Marionette TRACE 370 <- [1,5,null,{"value":true}]
13:51:23 INFO - 1498855883876 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')","sandbox":"simpletest","line":328}]
13:51:23 INFO - TEST-END: test_click.py TestClick.test_container_element1498855883880 Marionette TRACE 370 <- [1,6,null,{"value":null}]
13:51:23 INFO - 1498855883882 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
13:51:23 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 555ms
13:51:23 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:51:23 INFO - 1498855883886 Marionette TRACE 370 <- [1,7,null,{}]
13:51:23 INFO - 1498855883889 Marionette DEBUG Closed connection 370
13:51:23 INFO - 1498855883890 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50762
13:51:23 INFO - 1498855883891 Marionette DEBUG Closed connection 371
13:51:23 INFO - 1498855883892 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50763
13:51:23 INFO - 1498855883893 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:23 INFO - 1498855883896 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883900 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883906 Marionette TRACE 372 <- [1,1,null,{"sessionId":"a32699b9-e85b-4093-8c42-24c4c38bad7b","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883907 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
13:51:23 INFO - 1498855883908 Marionette TRACE 372 <- [1,2,null,{}]
13:51:23 INFO - 1498855883910 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:51:23 INFO - 1498855883911 Marionette TRACE 372 <- [1,3,null,{}]
13:51:23 INFO - 1498855883912 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
13:51:23 INFO - 1498855883913 Marionette TRACE 372 <- [1,4,null,{}]
13:51:23 INFO - 1498855883915 Marionette TRACE 372 -> [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}]
13:51:23 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498855883921 Marionette TRACE 372 <- [1,5,null,{"value":null}]
13:51:23 INFO - 1498855883924 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
13:51:23 INFO - 1498855883927 Marionette TRACE 372 <- [1,6,null,{}]
13:51:23 INFO - 1498855883929 Marionette DEBUG Closed connection 372
13:51:23 INFO - 1498855883930 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50764
13:51:23 INFO - 1498855883931 Marionette DEBUG Closed connection 373
13:51:23 INFO - 1498855883933 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50765
13:51:23 INFO - 1498855883934 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:23 INFO - 1498855883935 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883938 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883944 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2127c550-7817-40b2-bba5-807a605e304d","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883946 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
13:51:23 INFO - 1498855883948 Marionette TRACE 374 <- [1,2,null,{}]
13:51:23 INFO - 1498855883950 Marionette DEBUG Closed connection 374
13:51:23 INFO - 1498855883951 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50766
13:51:23 INFO - 1498855883952 Marionette DEBUG Closed connection 375
13:51:23 INFO - 1498855883953 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50767
13:51:23 INFO - 1498855883954 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:51:23 INFO - 1498855883956 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:23 INFO - 1498855883959 Marionette DEBUG Register listener.js for window 2147483649
13:51:23 INFO - 1498855883964 Marionette TRACE 376 <- [1,1,null,{"sessionId":"ea4c7864-5624-4b44-8447-d216ca7682ba","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:23 INFO - 1498855883965 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%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"}]
13:51:23 INFO - 1498855883968 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"
13:51:23 INFO - 1498855883971 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"
13:51:23 INFO - 1498855883971 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"
13:51:23 INFO - 1498855883979 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"
13:51:23 INFO - 1498855883984 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"
13:51:24 INFO - 1498855883999 Marionette TRACE 376 <- [1,2,null,{}]
13:51:24 INFO - 1498855884010 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:51:24 INFO - 1498855884014 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a796020-8342-480a-bee6-c498da906a51","ELEMENT":"5a796020-8342-480a-bee6-c498da906a51"}}]
13:51:24 INFO - 1498855884015 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"5a796020-8342-480a-bee6-c498da906a51"}]
13:51:24 INFO - 1498855884266 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:51:24 INFO - 1498855884267 Marionette TRACE 376 <- [1,4,null,{}]
13:51:24 INFO - 1498855884269 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"5a796020-8342-480a-bee6-c498da906a51","name":"selected"}]
13:51:24 INFO - 1498855884275 Marionette TRACE 376 <- [1,5,null,{"value":true}]
13:51:24 INFO - 1498855884277 Marionette TRACE 376 -> [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}]
13:51:24 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498855884282 Marionette TRACE 376 <- [1,6,null,{"value":null}]
13:51:24 INFO - 1498855884283 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
13:51:24 INFO - 1498855884285 Marionette TRACE 376 <- [1,7,null,{}]
13:51:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 398ms
13:51:24 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:51:24 INFO - 1498855884287 Marionette DEBUG Closed connection 376
13:51:24 INFO - 1498855884288 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50768
13:51:24 INFO - 1498855884289 Marionette DEBUG Closed connection 377
13:51:24 INFO - 1498855884290 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50769
13:51:24 INFO - 1498855884291 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:24 INFO - 1498855884292 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:24 INFO - 1498855884296 Marionette DEBUG Register listener.js for window 2147483649
13:51:24 INFO - 1498855884301 Marionette TRACE 378 <- [1,1,null,{"sessionId":"655009d8-83e7-4972-95b2-62de69c62a59","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:24 INFO - 1498855884303 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
13:51:24 INFO - 1498855884304 Marionette TRACE 378 <- [1,2,null,{}]
13:51:24 INFO - 1498855884305 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:51:24 INFO - 1498855884306 Marionette TRACE 378 <- [1,3,null,{}]
13:51:24 INFO - 1498855884307 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
13:51:24 INFO - 1498855884307 Marionette TRACE 378 <- [1,4,null,{}]
13:51:24 INFO - 1498855884309 Marionette TRACE 378 -> [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}]
13:51:24 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498855884314 Marionette TRACE 378 <- [1,5,null,{"value":null}]
13:51:24 INFO - 1498855884315 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
13:51:24 INFO - 1498855884317 Marionette TRACE 378 <- [1,6,null,{}]
13:51:24 INFO - 1498855884319 Marionette DEBUG Closed connection 378
13:51:24 INFO - 1498855884320 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50770
13:51:24 INFO - 1498855884321 Marionette DEBUG Closed connection 379
13:51:24 INFO - 1498855884322 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50771
13:51:24 INFO - 1498855884323 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:24 INFO - 1498855884325 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:24 INFO - 1498855884328 Marionette DEBUG Register listener.js for window 2147483649
13:51:24 INFO - 1498855884333 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ddc01581-805a-4b2f-b8ff-e80b36d66724","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:24 INFO - 1498855884335 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
13:51:24 INFO - 1498855884337 Marionette TRACE 380 <- [1,2,null,{}]
13:51:24 INFO - 1498855884340 Marionette DEBUG Closed connection 380
13:51:24 INFO - 1498855884343 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50772
13:51:24 INFO - 1498855884344 Marionette DEBUG Closed connection 381
13:51:24 INFO - 1498855884345 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50773
13:51:24 INFO - 1498855884346 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:51:24 INFO - 1498855884349 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:24 INFO - 1498855884352 Marionette DEBUG Register listener.js for window 2147483649
13:51:24 INFO - 1498855884357 Marionette TRACE 382 <- [1,1,null,{"sessionId":"5c327c11-3e42-4dd4-8b57-17363c13f8c9","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:24 INFO - 1498855884359 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%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"}]
13:51:24 INFO - 1498855884361 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"
13:51:24 INFO - 1498855884364 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"
13:51:24 INFO - 1498855884364 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"
13:51:24 INFO - 1498855884376 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"
13:51:24 INFO - 1498855884379 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"
13:51:24 INFO - 1498855884390 Marionette TRACE 382 <- [1,2,null,{}]
13:51:24 INFO - 1498855884395 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:51:24 INFO - 1498855884399 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"689fbe37-041a-4115-9b7c-4b576959b2f5","ELEMENT":"689fbe37-041a-4115-9b7c-4b576959b2f5"}}]
13:51:24 INFO - 1498855884401 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"689fbe37-041a-4115-9b7c-4b576959b2f5"}]
13:51:24 INFO - 1498855884646 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:51:24 INFO - 1498855884647 Marionette TRACE 382 <- [1,4,null,{}]
13:51:24 INFO - 1498855884649 Marionette TRACE 382 -> [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}]
13:51:24 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498855884653 Marionette TRACE 382 <- [1,5,null,{"value":null}]
13:51:24 INFO - 1498855884655 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
13:51:24 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 371ms
13:51:24 INFO - 1498855884657 Marionette TRACE 382 <- [1,6,null,{}]
13:51:24 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:51:24 INFO - 1498855884659 Marionette DEBUG Closed connection 382
13:51:24 INFO - 1498855884660 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50774
13:51:24 INFO - 1498855884661 Marionette DEBUG Closed connection 383
13:51:24 INFO - 1498855884662 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50775
13:51:24 INFO - 1498855884663 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:24 INFO - 1498855884664 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:24 INFO - 1498855884667 Marionette DEBUG Register listener.js for window 2147483649
13:51:24 INFO - 1498855884671 Marionette TRACE 384 <- [1,1,null,{"sessionId":"7fc5edeb-12ed-473e-957b-72b84fc5854a","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:24 INFO - 1498855884673 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
13:51:24 INFO - 1498855884674 Marionette TRACE 384 <- [1,2,null,{}]
13:51:24 INFO - 1498855884675 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:51:24 INFO - 1498855884676 Marionette TRACE 384 <- [1,3,null,{}]
13:51:24 INFO - 1498855884677 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
13:51:24 INFO - 1498855884677 Marionette TRACE 384 <- [1,4,null,{}]
13:51:24 INFO - 1498855884679 Marionette TRACE 384 -> [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}]
13:51:24 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498855884684 Marionette TRACE 384 <- [1,5,null,{"value":null}]
13:51:24 INFO - 1498855884685 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
13:51:24 INFO - 1498855884687 Marionette TRACE 384 <- [1,6,null,{}]
13:51:24 INFO - 1498855884689 Marionette DEBUG Closed connection 384
13:51:24 INFO - 1498855884690 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50776
13:51:24 INFO - 1498855884691 Marionette DEBUG Closed connection 385
13:51:24 INFO - 1498855884691 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50777
13:51:24 INFO - 1498855884693 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:24 INFO - 1498855884694 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:24 INFO - 1498855884697 Marionette DEBUG Register listener.js for window 2147483649
13:51:24 INFO - 1498855884701 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c1b07ed1-0d12-4afd-8d08-c0166ea1bc96","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:24 INFO - 1498855884703 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
13:51:24 INFO - 1498855884705 Marionette TRACE 386 <- [1,2,null,{}]
13:51:24 INFO - 1498855884706 Marionette DEBUG Closed connection 386
13:51:24 INFO - 1498855884707 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50778
13:51:24 INFO - 1498855884708 Marionette DEBUG Closed connection 387
13:51:24 INFO - 1498855884709 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50779
13:51:24 INFO - 1498855884710 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:51:24 INFO - 1498855884711 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:24 INFO - 1498855884715 Marionette DEBUG Register listener.js for window 2147483649
13:51:24 INFO - 1498855884719 Marionette TRACE 388 <- [1,1,null,{"sessionId":"19e35f3d-b70c-4b79-81cd-72ae844b76cd","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:24 INFO - 1498855884721 Marionette TRACE 388 -> [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"}]
13:51:24 INFO - 1498855884723 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"
13:51:24 INFO - 1498855884726 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"
13:51:24 INFO - 1498855884726 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"
13:51:24 INFO - 1498855884734 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"
13:51:24 INFO - 1498855884739 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"
13:51:24 INFO - 1498855884749 Marionette TRACE 388 <- [1,2,null,{}]
13:51:24 INFO - 1498855884760 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:51:24 INFO - 1498855884772 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"575438eb-f986-4cfa-955b-6a26b1252ff4","ELEMENT":"575438eb-f986-4cfa-955b-6a26b1252ff4"}}]
13:51:24 INFO - 1498855884774 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"575438eb-f986-4cfa-955b-6a26b1252ff4"}]
13:51:25 INFO - 1498855885020 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:51:25 INFO - 1498855885021 Marionette TRACE 388 <- [1,4,null,{}]
13:51:25 INFO - 1498855885024 Marionette TRACE 388 -> [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}]
13:51:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498855885028 Marionette TRACE 388 <- [1,5,null,{"value":null}]
13:51:25 INFO - 1498855885029 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
13:51:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 373ms
13:51:25 INFO - 1498855885031 Marionette TRACE 388 <- [1,6,null,{}]
13:51:25 INFO - TEST-START | test_click.py TestClick.test_input_file
13:51:25 INFO - 1498855885033 Marionette DEBUG Closed connection 388
13:51:25 INFO - 1498855885034 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50780
13:51:25 INFO - 1498855885035 Marionette DEBUG Closed connection 389
13:51:25 INFO - 1498855885036 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50781
13:51:25 INFO - 1498855885037 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:25 INFO - 1498855885038 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885041 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885045 Marionette TRACE 390 <- [1,1,null,{"sessionId":"4b435897-c657-44e7-9b45-82b15ca48dd4","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885047 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
13:51:25 INFO - 1498855885047 Marionette TRACE 390 <- [1,2,null,{}]
13:51:25 INFO - 1498855885048 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:51:25 INFO - 1498855885049 Marionette TRACE 390 <- [1,3,null,{}]
13:51:25 INFO - 1498855885050 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
13:51:25 INFO - 1498855885051 Marionette TRACE 390 <- [1,4,null,{}]
13:51:25 INFO - 1498855885053 Marionette TRACE 390 -> [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}]
13:51:25 INFO - TEST-START: test_click.py TestClick.test_input_file1498855885056 Marionette TRACE 390 <- [1,5,null,{"value":null}]
13:51:25 INFO - 1498855885058 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
13:51:25 INFO - 1498855885060 Marionette TRACE 390 <- [1,6,null,{}]
13:51:25 INFO - 1498855885061 Marionette DEBUG Closed connection 390
13:51:25 INFO - 1498855885062 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50782
13:51:25 INFO - 1498855885063 Marionette DEBUG Closed connection 391
13:51:25 INFO - 1498855885064 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50783
13:51:25 INFO - 1498855885065 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:25 INFO - 1498855885067 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885072 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885077 Marionette TRACE 392 <- [1,1,null,{"sessionId":"185d304b-1fa6-492c-a31a-1df84191f229","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885078 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
13:51:25 INFO - 1498855885080 Marionette TRACE 392 <- [1,2,null,{}]
13:51:25 INFO - 1498855885082 Marionette DEBUG Closed connection 392
13:51:25 INFO - 1498855885083 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50784
13:51:25 INFO - 1498855885084 Marionette DEBUG Closed connection 393
13:51:25 INFO - 1498855885085 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50785
13:51:25 INFO - 1498855885086 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:51:25 INFO - 1498855885087 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885090 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885095 Marionette TRACE 394 <- [1,1,null,{"sessionId":"0e1f9647-86da-411d-bba3-83b87e1cd7d6","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885096 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:51:25 INFO - 1498855885098 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"
13:51:25 INFO - 1498855885101 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"
13:51:25 INFO - 1498855885102 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"
13:51:25 INFO - 1498855885110 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:51:25 INFO - 1498855885116 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:51:25 INFO - 1498855885121 Marionette TRACE 394 <- [1,2,null,{}]
13:51:25 INFO - 1498855885142 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:51:25 INFO - 1498855885145 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eae2726e-94d6-4d7c-a7a4-16214a260700","ELEMENT":"eae2726e-94d6-4d7c-a7a4-16214a260700"}}]
13:51:25 INFO - 1498855885147 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"eae2726e-94d6-4d7c-a7a4-16214a260700"}]
13:51:25 INFO - 1498855885175 Marionette TRACE 394 <- [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:1330:5\n"},null]
13:51:25 INFO - 1498855885178 Marionette TRACE 394 -> [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}]
13:51:25 INFO - TEST-END: test_click.py TestClick.test_input_file1498855885181 Marionette TRACE 394 <- [1,5,null,{"value":null}]
13:51:25 INFO - 1498855885182 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
13:51:25 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 152ms
13:51:25 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:51:25 INFO - 1498855885184 Marionette TRACE 394 <- [1,6,null,{}]
13:51:25 INFO - 1498855885186 Marionette DEBUG Closed connection 394
13:51:25 INFO - 1498855885187 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50786
13:51:25 INFO - 1498855885188 Marionette DEBUG Closed connection 395
13:51:25 INFO - 1498855885188 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50787
13:51:25 INFO - 1498855885189 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:25 INFO - 1498855885190 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885193 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885197 Marionette TRACE 396 <- [1,1,null,{"sessionId":"5de8776f-660f-487d-8107-f399794e0a5e","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885199 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
13:51:25 INFO - 1498855885199 Marionette TRACE 396 <- [1,2,null,{}]
13:51:25 INFO - 1498855885200 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:51:25 INFO - 1498855885201 Marionette TRACE 396 <- [1,3,null,{}]
13:51:25 INFO - 1498855885202 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
13:51:25 INFO - 1498855885203 Marionette TRACE 396 <- [1,4,null,{}]
13:51:25 INFO - 1498855885205 Marionette TRACE 396 -> [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}]
13:51:25 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498855885209 Marionette TRACE 396 <- [1,5,null,{"value":null}]
13:51:25 INFO - 1498855885210 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
13:51:25 INFO - 1498855885212 Marionette TRACE 396 <- [1,6,null,{}]
13:51:25 INFO - 1498855885213 Marionette DEBUG Closed connection 396
13:51:25 INFO - 1498855885214 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50788
13:51:25 INFO - 1498855885215 Marionette DEBUG Closed connection 397
13:51:25 INFO - 1498855885216 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50789
13:51:25 INFO - 1498855885217 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:25 INFO - 1498855885218 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885221 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885225 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4e7443c9-b7b4-4d5a-9491-515f2ed0e781","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885226 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
13:51:25 INFO - 1498855885228 Marionette TRACE 398 <- [1,2,null,{}]
13:51:25 INFO - 1498855885231 Marionette DEBUG Closed connection 398
13:51:25 INFO - 1498855885231 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50790
13:51:25 INFO - 1498855885233 Marionette DEBUG Closed connection 399
13:51:25 INFO - 1498855885233 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50791
13:51:25 INFO - 1498855885234 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:51:25 INFO - 1498855885235 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885238 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885242 Marionette TRACE 400 <- [1,1,null,{"sessionId":"2fbfe88a-fbe5-404b-a40a-571462c684bd","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885244 Marionette TRACE 400 -> [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"}]
13:51:25 INFO - 1498855885264 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:51:25 INFO - 1498855885267 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:51:25 INFO - 1498855885268 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:51:25 INFO - 1498855885276 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"
13:51:25 INFO - 1498855885281 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"
13:51:25 INFO - 1498855885295 Marionette TRACE 400 <- [1,2,null,{}]
13:51:25 INFO - 1498855885301 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:51:25 INFO - 1498855885309 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d2178236-daa3-421f-b9e7-f1318d4a5a77","ELEMENT":"d2178236-daa3-421f-b9e7-f1318d4a5a77"}}]
13:51:25 INFO - 1498855885320 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:51:25 INFO - 1498855885324 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf7bcaa5-0b08-46ca-b8e7-095b885d523b","ELEMENT":"bf7bcaa5-0b08-46ca-b8e7-095b885d523b"}}]
13:51:25 INFO - 1498855885325 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"d2178236-daa3-421f-b9e7-f1318d4a5a77"}]
13:51:25 INFO - 1498855885567 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:51:25 INFO - 1498855885568 Marionette TRACE 400 <- [1,5,null,{}]
13:51:25 INFO - 1498855885570 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"bf7bcaa5-0b08-46ca-b8e7-095b885d523b"}]
13:51:25 INFO - 1498855885589 Marionette TRACE 400 <- [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:1330:5\n"},null]
13:51:25 INFO - 1498855885591 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:51:25 INFO - 1498855885596 Marionette TRACE 400 <- [1,7,null,{"value":false}]
13:51:25 INFO - 1498855885598 Marionette TRACE 400 -> [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}]
13:51:25 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 420ms
13:51:25 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:51:25 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498855885602 Marionette TRACE 400 <- [1,8,null,{"value":null}]
13:51:25 INFO - 1498855885603 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
13:51:25 INFO - 1498855885605 Marionette TRACE 400 <- [1,9,null,{}]
13:51:25 INFO - 1498855885606 Marionette DEBUG Closed connection 400
13:51:25 INFO - 1498855885608 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50792
13:51:25 INFO - 1498855885608 Marionette DEBUG Closed connection 401
13:51:25 INFO - 1498855885609 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50793
13:51:25 INFO - 1498855885611 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:25 INFO - 1498855885611 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885614 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885618 Marionette TRACE 402 <- [1,1,null,{"sessionId":"ca8067d3-6761-4c91-a4ee-18504d83d6eb","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885619 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
13:51:25 INFO - 1498855885620 Marionette TRACE 402 <- [1,2,null,{}]
13:51:25 INFO - 1498855885622 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:51:25 INFO - 1498855885623 Marionette TRACE 402 <- [1,3,null,{}]
13:51:25 INFO - 1498855885624 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
13:51:25 INFO - 1498855885624 Marionette TRACE 402 <- [1,4,null,{}]
13:51:25 INFO - 1498855885626 Marionette TRACE 402 -> [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}]
13:51:25 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498855885630 Marionette TRACE 402 <- [1,5,null,{"value":null}]
13:51:25 INFO - 1498855885631 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
13:51:25 INFO - 1498855885632 Marionette TRACE 402 <- [1,6,null,{}]
13:51:25 INFO - 1498855885634 Marionette DEBUG Closed connection 402
13:51:25 INFO - 1498855885635 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50794
13:51:25 INFO - 1498855885636 Marionette DEBUG Closed connection 403
13:51:25 INFO - 1498855885636 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50795
13:51:25 INFO - 1498855885638 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:51:25 INFO - 1498855885638 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885641 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885645 Marionette TRACE 404 <- [1,1,null,{"sessionId":"5106338a-cba7-42f7-b835-7de18cbad62e","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885646 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
13:51:25 INFO - 1498855885647 Marionette TRACE 404 <- [1,2,null,{}]
13:51:25 INFO - 1498855885649 Marionette DEBUG Closed connection 404
13:51:25 INFO - 1498855885650 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50796
13:51:25 INFO - 1498855885651 Marionette DEBUG Closed connection 405
13:51:25 INFO - 1498855885651 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50797
13:51:25 INFO - 1498855885653 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:51:25 INFO - 1498855885653 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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}
13:51:25 INFO - 1498855885656 Marionette DEBUG Register listener.js for window 2147483649
13:51:25 INFO - 1498855885660 Marionette TRACE 406 <- [1,1,null,{"sessionId":"951ecba4-e3d7-4a90-876e-f4f88aac0e84","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":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnbeugz.mozrunner","moz:accessibilityChecks":false}}]
13:51:25 INFO - 1498855885661 Marionette TRACE 406 -> [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"}]
13:51:25 INFO - 1498855885663 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"
13:51:25 INFO - 1498855885666 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"
13:51:25 INFO - 1498855885666 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"
13:51:25 INFO - 1498855885676 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"
13:51:25 INFO - 1498855885677 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"
13:51:25 INFO - 1498855885690 Marionette TRACE 406 <- [1,2,null,{}]
13:51:25 INFO - 1498855885694 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:51:25 INFO - 1498855885700 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5118d582-8bd8-4ab6-8cc8-26ab8500d09e","ELEMENT":"5118d582-8bd8-4ab6-8cc8-26ab8500d09e"}}]
13:51:25 INFO - 1498855885701 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5118d582-8bd8-4ab6-8cc8-26ab8500d09e"}]
13:51:25 INFO - 1498855885731 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
13:51:25 INFO - 1498855885733 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"5118d582-8bd8-4ab6-8cc8-26ab8500d09e"}]
13:51:25 INFO - 1498855885752 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element