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]
10:05:19 INFO - 1499015119891 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}]
10:05:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499015119895 Marionette TRACE 353 <- [1,5,null,{"value":null}]
10:05:19 INFO - 1499015119896 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
10:05:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 161ms
10:05:19 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:05:19 INFO - 1499015119898 Marionette TRACE 353 <- [1,6,null,{}]
10:05:19 INFO - 1499015119899 Marionette DEBUG Closed connection 353
10:05:19 INFO - 1499015119900 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49802
10:05:19 INFO - 1499015119901 Marionette DEBUG Closed connection 354
10:05:19 INFO - 1499015119902 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49803
10:05:19 INFO - 1499015119903 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:19 INFO - 1499015119904 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:19 INFO - 1499015119907 Marionette DEBUG Register listener.js for window 4294967297
10:05:19 INFO - 1499015119911 Marionette TRACE 355 <- [1,1,null,{"sessionId":"60f7caa8-93bb-4f4a-bf7e-346c678974a0","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:19 INFO - 1499015119912 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
10:05:19 INFO - 1499015119912 Marionette TRACE 355 <- [1,2,null,{}]
10:05:19 INFO - 1499015119914 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:19 INFO - 1499015119914 Marionette TRACE 355 <- [1,3,null,{}]
10:05:19 INFO - 1499015119915 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:19 INFO - 1499015119916 Marionette TRACE 355 <- [1,4,null,{}]
10:05:19 INFO - 1499015119917 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}]
10:05:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499015119921 Marionette TRACE 355 <- [1,5,null,{"value":null}]
10:05:19 INFO - 1499015119922 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
10:05:19 INFO - 1499015119924 Marionette TRACE 355 <- [1,6,null,{}]
10:05:19 INFO - 1499015119926 Marionette DEBUG Closed connection 355
10:05:19 INFO - 1499015119926 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49804
10:05:19 INFO - 1499015119927 Marionette DEBUG Closed connection 356
10:05:19 INFO - 1499015119928 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49805
10:05:19 INFO - 1499015119929 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:19 INFO - 1499015119930 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:19 INFO - 1499015119933 Marionette DEBUG Register listener.js for window 4294967297
10:05:19 INFO - 1499015119937 Marionette TRACE 357 <- [1,1,null,{"sessionId":"1c4c2056-8f3e-4c23-8419-425ad05ebee2","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:19 INFO - 1499015119938 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
10:05:19 INFO - 1499015119939 Marionette TRACE 357 <- [1,2,null,{}]
10:05:19 INFO - 1499015119941 Marionette DEBUG Closed connection 357
10:05:19 INFO - 1499015119942 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49806
10:05:19 INFO - 1499015119943 Marionette DEBUG Closed connection 358
10:05:19 INFO - 1499015119944 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49807
10:05:19 INFO - 1499015119945 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:19 INFO - 1499015119946 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:19 INFO - 1499015119949 Marionette DEBUG Register listener.js for window 4294967297
10:05:19 INFO - 1499015119952 Marionette TRACE 359 <- [1,1,null,{"sessionId":"4015090a-5524-4c3b-bdc6-43ae0e1e8352","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:19 INFO - 1499015119954 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49358/clicks.html"}]
10:05:19 INFO - 1499015119956 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49358/hidden.html"
10:05:19 INFO - 1499015119965 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49358/hidden.html"
10:05:19 INFO - 1499015119965 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49358/hidden.html"
10:05:19 INFO - 1499015119979 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49358/clicks.html"
10:05:19 INFO - 1499015119981 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49358/clicks.html"
10:05:19 INFO - 1499015119986 Marionette TRACE 359 <- [1,2,null,{}]
10:05:20 INFO - 1499015119996 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:05:20 INFO - 1499015120004 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1b01e361-b87d-446d-a7e8-87d846198708","ELEMENT":"1b01e361-b87d-446d-a7e8-87d846198708"}}]
10:05:20 INFO - 1499015120006 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"1b01e361-b87d-446d-a7e8-87d846198708"}]
10:05:20 INFO - 1499015120049 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49358/clicks.html"
10:05:20 INFO - 1499015120063 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49358/clicks.html"
10:05:20 INFO - 1499015120063 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49358/clicks.html"
10:05:20 INFO - 1499015120077 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49358/test.html"
10:05:20 INFO - 1499015120081 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49358/test.html"
10:05:20 INFO - 1499015120082 Marionette TRACE 359 <- [1,4,null,{}]
10:05:20 INFO - 1499015120087 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:05:20 INFO - 1499015120093 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a534b304-8e9d-4d9a-bf91-7bdd78571223","ELEMENT":"a534b304-8e9d-4d9a-bf91-7bdd78571223"}}]
10:05:20 INFO - 1499015120095 Marionette TRACE 359 -> [0,6,"getTitle",{}]
10:05:20 INFO - 1499015120098 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
10:05:20 INFO - 1499015120101 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}]
10:05:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499015120105 Marionette TRACE 359 <- [1,7,null,{"value":null}]
10:05:20 INFO - 1499015120107 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
10:05:20 INFO - 1499015120109 Marionette TRACE 359 <- [1,8,null,{}]
10:05:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 211ms
10:05:20 INFO - TEST-START | test_click.py TestClick.test_container_element
10:05:20 INFO - 1499015120111 Marionette DEBUG Closed connection 359
10:05:20 INFO - 1499015120112 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49810
10:05:20 INFO - 1499015120113 Marionette DEBUG Closed connection 360
10:05:20 INFO - 1499015120113 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49811
10:05:20 INFO - 1499015120115 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:20 INFO - 1499015120115 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120119 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120123 Marionette TRACE 361 <- [1,1,null,{"sessionId":"3c585c3c-8246-42e4-aa6a-2f56fb1f21f0","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120124 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
10:05:20 INFO - 1499015120125 Marionette TRACE 361 <- [1,2,null,{}]
10:05:20 INFO - 1499015120126 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:20 INFO - 1499015120127 Marionette TRACE 361 <- [1,3,null,{}]
10:05:20 INFO - 1499015120128 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:20 INFO - 1499015120128 Marionette TRACE 361 <- [1,4,null,{}]
10:05:20 INFO - 1499015120130 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}]
10:05:20 INFO - TEST-START: test_click.py TestClick.test_container_element1499015120133 Marionette TRACE 361 <- [1,5,null,{"value":null}]
10:05:20 INFO - 1499015120134 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
10:05:20 INFO - 1499015120136 Marionette TRACE 361 <- [1,6,null,{}]
10:05:20 INFO - 1499015120138 Marionette DEBUG Closed connection 361
10:05:20 INFO - 1499015120138 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49812
10:05:20 INFO - 1499015120139 Marionette DEBUG Closed connection 362
10:05:20 INFO - 1499015120140 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49813
10:05:20 INFO - 1499015120141 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:20 INFO - 1499015120142 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120145 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120148 Marionette TRACE 363 <- [1,1,null,{"sessionId":"24f18a32-d7ee-409e-9746-13bd2a81419e","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120150 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
10:05:20 INFO - 1499015120151 Marionette TRACE 363 <- [1,2,null,{}]
10:05:20 INFO - 1499015120153 Marionette DEBUG Closed connection 363
10:05:20 INFO - 1499015120154 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49814
10:05:20 INFO - 1499015120155 Marionette DEBUG Closed connection 364
10:05:20 INFO - 1499015120156 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49815
10:05:20 INFO - 1499015120157 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:20 INFO - 1499015120158 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120161 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120164 Marionette TRACE 365 <- [1,1,null,{"sessionId":"e1d3da5e-a692-484c-8b34-fcff42b7eb0b","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120165 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"}]
10:05:20 INFO - 1499015120167 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49358/test.html"
10:05:20 INFO - 1499015120170 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49358/test.html"
10:05:20 INFO - 1499015120170 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49358/test.html"
10:05:20 INFO - 1499015120178 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"
10:05:20 INFO - 1499015120182 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"
10:05:20 INFO - 1499015120196 Marionette TRACE 365 <- [1,2,null,{}]
10:05:20 INFO - 1499015120203 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:05:20 INFO - 1499015120211 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4dcbcd2a-d1f2-49d5-9fd2-2b301b700835","ELEMENT":"4dcbcd2a-d1f2-49d5-9fd2-2b301b700835"}}]
10:05:20 INFO - 1499015120212 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"4dcbcd2a-d1f2-49d5-9fd2-2b301b700835"}]
10:05:20 INFO - 1499015120462 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:20 INFO - 1499015120463 Marionette TRACE 365 <- [1,4,null,{}]
10:05:20 INFO - 1499015120464 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"4dcbcd2a-d1f2-49d5-9fd2-2b301b700835","name":"selected"}]
10:05:20 INFO - 1499015120470 Marionette TRACE 365 <- [1,5,null,{"value":true}]
10:05:20 INFO - 1499015120472 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}]
10:05:20 INFO - TEST-END: test_click.py TestClick.test_container_element1499015120475 Marionette TRACE 365 <- [1,6,null,{"value":null}]
10:05:20 INFO - 1499015120476 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
10:05:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 368ms
10:05:20 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:05:20 INFO - 1499015120478 Marionette TRACE 365 <- [1,7,null,{}]
10:05:20 INFO - 1499015120480 Marionette DEBUG Closed connection 365
10:05:20 INFO - 1499015120481 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49816
10:05:20 INFO - 1499015120482 Marionette DEBUG Closed connection 366
10:05:20 INFO - 1499015120483 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49817
10:05:20 INFO - 1499015120484 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:20 INFO - 1499015120485 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120488 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120491 Marionette TRACE 367 <- [1,1,null,{"sessionId":"a120ec8e-f6f8-4f39-876d-ca4df85ea207","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120493 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
10:05:20 INFO - 1499015120493 Marionette TRACE 367 <- [1,2,null,{}]
10:05:20 INFO - 1499015120494 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:20 INFO - 1499015120495 Marionette TRACE 367 <- [1,3,null,{}]
10:05:20 INFO - 1499015120496 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:20 INFO - 1499015120496 Marionette TRACE 367 <- [1,4,null,{}]
10:05:20 INFO - 1499015120498 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}]
10:05:20 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499015120502 Marionette TRACE 367 <- [1,5,null,{"value":null}]
10:05:20 INFO - 1499015120503 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
10:05:20 INFO - 1499015120505 Marionette TRACE 367 <- [1,6,null,{}]
10:05:20 INFO - 1499015120507 Marionette DEBUG Closed connection 367
10:05:20 INFO - 1499015120508 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49818
10:05:20 INFO - 1499015120508 Marionette DEBUG Closed connection 368
10:05:20 INFO - 1499015120509 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49819
10:05:20 INFO - 1499015120510 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:20 INFO - 1499015120511 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120514 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120517 Marionette TRACE 369 <- [1,1,null,{"sessionId":"aaa96372-d46f-47f0-bb50-818c9e7a1934","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120519 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
10:05:20 INFO - 1499015120520 Marionette TRACE 369 <- [1,2,null,{}]
10:05:20 INFO - 1499015120522 Marionette DEBUG Closed connection 369
10:05:20 INFO - 1499015120523 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49820
10:05:20 INFO - 1499015120524 Marionette DEBUG Closed connection 370
10:05:20 INFO - 1499015120525 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49821
10:05:20 INFO - 1499015120526 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:20 INFO - 1499015120527 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120530 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120533 Marionette TRACE 371 <- [1,1,null,{"sessionId":"41183ea5-95d6-43cf-aa96-4c2756df21af","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120535 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"}]
10:05:20 INFO - 1499015120537 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"
10:05:20 INFO - 1499015120539 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"
10:05:20 INFO - 1499015120540 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"
10:05:20 INFO - 1499015120547 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"
10:05:20 INFO - 1499015120551 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"
10:05:20 INFO - 1499015120554 Marionette TRACE 371 <- [1,2,null,{}]
10:05:20 INFO - 1499015120566 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:05:20 INFO - 1499015120577 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dc8094a0-306c-48d6-bb82-8b4b1b66f3e4","ELEMENT":"dc8094a0-306c-48d6-bb82-8b4b1b66f3e4"}}]
10:05:20 INFO - 1499015120579 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"dc8094a0-306c-48d6-bb82-8b4b1b66f3e4"}]
10:05:20 INFO - 1499015120823 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:20 INFO - 1499015120824 Marionette TRACE 371 <- [1,4,null,{}]
10:05:20 INFO - 1499015120825 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"dc8094a0-306c-48d6-bb82-8b4b1b66f3e4","name":"selected"}]
10:05:20 INFO - 1499015120831 Marionette TRACE 371 <- [1,5,null,{"value":true}]
10:05:20 INFO - 1499015120833 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}]
10:05:20 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499015120836 Marionette TRACE 371 <- [1,6,null,{"value":null}]
10:05:20 INFO - 1499015120837 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
10:05:20 INFO - 1499015120839 Marionette TRACE 371 <- [1,7,null,{}]
10:05:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 360ms
10:05:20 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:05:20 INFO - 1499015120840 Marionette DEBUG Closed connection 371
10:05:20 INFO - 1499015120841 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49822
10:05:20 INFO - 1499015120842 Marionette DEBUG Closed connection 372
10:05:20 INFO - 1499015120843 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49823
10:05:20 INFO - 1499015120844 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:20 INFO - 1499015120845 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120848 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120851 Marionette TRACE 373 <- [1,1,null,{"sessionId":"1664b7db-397e-4734-9fea-5f98b057b020","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120853 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
10:05:20 INFO - 1499015120853 Marionette TRACE 373 <- [1,2,null,{}]
10:05:20 INFO - 1499015120854 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:20 INFO - 1499015120855 Marionette TRACE 373 <- [1,3,null,{}]
10:05:20 INFO - 1499015120856 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:20 INFO - 1499015120856 Marionette TRACE 373 <- [1,4,null,{}]
10:05:20 INFO - 1499015120858 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}]
10:05:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499015120862 Marionette TRACE 373 <- [1,5,null,{"value":null}]
10:05:20 INFO - 1499015120863 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
10:05:20 INFO - 1499015120864 Marionette TRACE 373 <- [1,6,null,{}]
10:05:20 INFO - 1499015120866 Marionette DEBUG Closed connection 373
10:05:20 INFO - 1499015120867 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49824
10:05:20 INFO - 1499015120868 Marionette DEBUG Closed connection 374
10:05:20 INFO - 1499015120869 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49825
10:05:20 INFO - 1499015120870 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:20 INFO - 1499015120871 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120874 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120878 Marionette TRACE 375 <- [1,1,null,{"sessionId":"ed1b1602-2519-4959-8935-a55f3f8455d6","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120880 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
10:05:20 INFO - 1499015120881 Marionette TRACE 375 <- [1,2,null,{}]
10:05:20 INFO - 1499015120883 Marionette DEBUG Closed connection 375
10:05:20 INFO - 1499015120884 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49826
10:05:20 INFO - 1499015120885 Marionette DEBUG Closed connection 376
10:05:20 INFO - 1499015120886 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49827
10:05:20 INFO - 1499015120887 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:20 INFO - 1499015120888 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:20 INFO - 1499015120891 Marionette DEBUG Register listener.js for window 4294967297
10:05:20 INFO - 1499015120894 Marionette TRACE 377 <- [1,1,null,{"sessionId":"6b97b7c0-1f27-46d0-8e8a-92a0e59ada6b","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:20 INFO - 1499015120896 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"}]
10:05:20 INFO - 1499015120898 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"
10:05:20 INFO - 1499015120900 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"
10:05:20 INFO - 1499015120900 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"
10:05:20 INFO - 1499015120910 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"
10:05:20 INFO - 1499015120912 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"
10:05:20 INFO - 1499015120921 Marionette TRACE 377 <- [1,2,null,{}]
10:05:20 INFO - 1499015120929 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:05:20 INFO - 1499015120935 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c217ecc8-bddf-4f5b-b278-ac8d8eccf499","ELEMENT":"c217ecc8-bddf-4f5b-b278-ac8d8eccf499"}}]
10:05:20 INFO - 1499015120936 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"c217ecc8-bddf-4f5b-b278-ac8d8eccf499"}]
10:05:21 INFO - 1499015121178 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:21 INFO - 1499015121179 Marionette TRACE 377 <- [1,4,null,{}]
10:05:21 INFO - 1499015121181 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}]
10:05:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499015121185 Marionette TRACE 377 <- [1,5,null,{"value":null}]
10:05:21 INFO - 1499015121186 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
10:05:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 349ms
10:05:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:05:21 INFO - 1499015121188 Marionette TRACE 377 <- [1,6,null,{}]
10:05:21 INFO - 1499015121189 Marionette DEBUG Closed connection 377
10:05:21 INFO - 1499015121190 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49828
10:05:21 INFO - 1499015121191 Marionette DEBUG Closed connection 378
10:05:21 INFO - 1499015121192 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49829
10:05:21 INFO - 1499015121193 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:21 INFO - 1499015121194 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121197 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121200 Marionette TRACE 379 <- [1,1,null,{"sessionId":"5d3ca72d-cdbd-48d3-92ed-e2c4983e7cf8","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121202 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
10:05:21 INFO - 1499015121202 Marionette TRACE 379 <- [1,2,null,{}]
10:05:21 INFO - 1499015121203 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:21 INFO - 1499015121204 Marionette TRACE 379 <- [1,3,null,{}]
10:05:21 INFO - 1499015121205 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:21 INFO - 1499015121205 Marionette TRACE 379 <- [1,4,null,{}]
10:05:21 INFO - 1499015121207 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}]
10:05:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499015121211 Marionette TRACE 379 <- [1,5,null,{"value":null}]
10:05:21 INFO - 1499015121212 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
10:05:21 INFO - 1499015121213 Marionette TRACE 379 <- [1,6,null,{}]
10:05:21 INFO - 1499015121215 Marionette DEBUG Closed connection 379
10:05:21 INFO - 1499015121216 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49830
10:05:21 INFO - 1499015121217 Marionette DEBUG Closed connection 380
10:05:21 INFO - 1499015121217 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49831
10:05:21 INFO - 1499015121218 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:21 INFO - 1499015121219 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121222 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121225 Marionette TRACE 381 <- [1,1,null,{"sessionId":"d4111905-c82d-409b-9a9f-e790cf2a46f1","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121227 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
10:05:21 INFO - 1499015121228 Marionette TRACE 381 <- [1,2,null,{}]
10:05:21 INFO - 1499015121230 Marionette DEBUG Closed connection 381
10:05:21 INFO - 1499015121231 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49832
10:05:21 INFO - 1499015121232 Marionette DEBUG Closed connection 382
10:05:21 INFO - 1499015121233 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49833
10:05:21 INFO - 1499015121234 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:21 INFO - 1499015121235 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121238 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121251 Marionette TRACE 383 <- [1,1,null,{"sessionId":"bf8dcb93-ee23-44cf-946b-7ade243e8d16","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121252 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"}]
10:05:21 INFO - 1499015121254 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"
10:05:21 INFO - 1499015121257 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"
10:05:21 INFO - 1499015121257 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"
10:05:21 INFO - 1499015121264 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"
10:05:21 INFO - 1499015121268 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"
10:05:21 INFO - 1499015121270 Marionette TRACE 383 <- [1,2,null,{}]
10:05:21 INFO - 1499015121281 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:05:21 INFO - 1499015121285 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb1a42b1-316d-47cc-a625-6d35f328c6d5","ELEMENT":"fb1a42b1-316d-47cc-a625-6d35f328c6d5"}}]
10:05:21 INFO - 1499015121286 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"fb1a42b1-316d-47cc-a625-6d35f328c6d5"}]
10:05:21 INFO - 1499015121536 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:21 INFO - 1499015121537 Marionette TRACE 383 <- [1,4,null,{}]
10:05:21 INFO - 1499015121539 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}]
10:05:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499015121542 Marionette TRACE 383 <- [1,5,null,{"value":null}]
10:05:21 INFO - 1499015121544 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
10:05:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 358ms
10:05:21 INFO - TEST-START | test_click.py TestClick.test_input_file
10:05:21 INFO - 1499015121545 Marionette TRACE 383 <- [1,6,null,{}]
10:05:21 INFO - 1499015121547 Marionette DEBUG Closed connection 383
10:05:21 INFO - 1499015121548 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49834
10:05:21 INFO - 1499015121549 Marionette DEBUG Closed connection 384
10:05:21 INFO - 1499015121550 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49835
10:05:21 INFO - 1499015121551 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:21 INFO - 1499015121552 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121555 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121559 Marionette TRACE 385 <- [1,1,null,{"sessionId":"573ff617-06b5-4a45-8943-51073ec43ef1","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121560 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
10:05:21 INFO - 1499015121561 Marionette TRACE 385 <- [1,2,null,{}]
10:05:21 INFO - 1499015121561 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:21 INFO - 1499015121562 Marionette TRACE 385 <- [1,3,null,{}]
10:05:21 INFO - 1499015121563 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:21 INFO - 1499015121563 Marionette TRACE 385 <- [1,4,null,{}]
10:05:21 INFO - 1499015121566 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}]
10:05:21 INFO - TEST-START: test_click.py TestClick.test_input_file1499015121569 Marionette TRACE 385 <- [1,5,null,{"value":null}]
10:05:21 INFO - 1499015121570 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
10:05:21 INFO - 1499015121572 Marionette TRACE 385 <- [1,6,null,{}]
10:05:21 INFO - 1499015121574 Marionette DEBUG Closed connection 385
10:05:21 INFO - 1499015121574 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49836
10:05:21 INFO - 1499015121575 Marionette DEBUG Closed connection 386
10:05:21 INFO - 1499015121576 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49837
10:05:21 INFO - 1499015121577 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:21 INFO - 1499015121578 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121581 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121584 Marionette TRACE 387 <- [1,1,null,{"sessionId":"6421af70-d565-4849-a26b-e03075663bfa","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121585 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
10:05:21 INFO - 1499015121587 Marionette TRACE 387 <- [1,2,null,{}]
10:05:21 INFO - 1499015121589 Marionette DEBUG Closed connection 387
10:05:21 INFO - 1499015121590 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49838
10:05:21 INFO - 1499015121590 Marionette DEBUG Closed connection 388
10:05:21 INFO - 1499015121591 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49839
10:05:21 INFO - 1499015121592 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:21 INFO - 1499015121593 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121596 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121600 Marionette TRACE 389 <- [1,1,null,{"sessionId":"0734ced2-1f8b-4285-8b62-37225bca7c19","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121601 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:05:21 INFO - 1499015121603 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"
10:05:21 INFO - 1499015121605 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"
10:05:21 INFO - 1499015121606 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"
10:05:21 INFO - 1499015121613 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:21 INFO - 1499015121619 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:21 INFO - 1499015121633 Marionette TRACE 389 <- [1,2,null,{}]
10:05:21 INFO - 1499015121644 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:05:21 INFO - 1499015121647 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"edc81bb0-5520-49ac-9377-dba4ad79e532","ELEMENT":"edc81bb0-5520-49ac-9377-dba4ad79e532"}}]
10:05:21 INFO - 1499015121648 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"edc81bb0-5520-49ac-9377-dba4ad79e532"}]
10:05:21 INFO - 1499015121676 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:1330:5\n"},null]
10:05:21 INFO - 1499015121678 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}]
10:05:21 INFO - TEST-END: test_click.py TestClick.test_input_file1499015121682 Marionette TRACE 389 <- [1,5,null,{"value":null}]
10:05:21 INFO - 1499015121683 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
10:05:21 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 139ms
10:05:21 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:05:21 INFO - 1499015121684 Marionette TRACE 389 <- [1,6,null,{}]
10:05:21 INFO - 1499015121686 Marionette DEBUG Closed connection 389
10:05:21 INFO - 1499015121687 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49840
10:05:21 INFO - 1499015121688 Marionette DEBUG Closed connection 390
10:05:21 INFO - 1499015121689 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49841
10:05:21 INFO - 1499015121690 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:21 INFO - 1499015121690 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121694 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121697 Marionette TRACE 391 <- [1,1,null,{"sessionId":"60936879-824f-4b58-8bf9-bb67f3f5df9e","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121698 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
10:05:21 INFO - 1499015121699 Marionette TRACE 391 <- [1,2,null,{}]
10:05:21 INFO - 1499015121700 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:21 INFO - 1499015121700 Marionette TRACE 391 <- [1,3,null,{}]
10:05:21 INFO - 1499015121701 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:21 INFO - 1499015121702 Marionette TRACE 391 <- [1,4,null,{}]
10:05:21 INFO - 1499015121704 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}]
10:05:21 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499015121707 Marionette TRACE 391 <- [1,5,null,{"value":null}]
10:05:21 INFO - 1499015121708 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
10:05:21 INFO - 1499015121709 Marionette TRACE 391 <- [1,6,null,{}]
10:05:21 INFO - 1499015121711 Marionette DEBUG Closed connection 391
10:05:21 INFO - 1499015121712 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49842
10:05:21 INFO - 1499015121713 Marionette DEBUG Closed connection 392
10:05:21 INFO - 1499015121714 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49843
10:05:21 INFO - 1499015121715 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:21 INFO - 1499015121716 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121719 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121722 Marionette TRACE 393 <- [1,1,null,{"sessionId":"60bb60b4-31b0-4049-84e5-2a13101e6e75","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121723 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
10:05:21 INFO - 1499015121725 Marionette TRACE 393 <- [1,2,null,{}]
10:05:21 INFO - 1499015121727 Marionette DEBUG Closed connection 393
10:05:21 INFO - 1499015121728 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49844
10:05:21 INFO - 1499015121729 Marionette DEBUG Closed connection 394
10:05:21 INFO - 1499015121729 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49845
10:05:21 INFO - 1499015121796 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:21 INFO - 1499015121801 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:21 INFO - 1499015121805 Marionette DEBUG Register listener.js for window 4294967297
10:05:21 INFO - 1499015121817 Marionette TRACE 395 <- [1,1,null,{"sessionId":"9f9eac88-f623-4411-b11b-875f055b98a5","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:21 INFO - 1499015121846 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"}]
10:05:21 INFO - 1499015121854 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:21 INFO - 1499015121856 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:21 INFO - 1499015121857 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:21 INFO - 1499015121864 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"
10:05:21 INFO - 1499015121868 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"
10:05:21 INFO - 1499015121886 Marionette TRACE 395 <- [1,2,null,{}]
10:05:21 INFO - 1499015121900 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:05:21 INFO - 1499015121916 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"33120ef7-1122-4ca1-88c9-3cd0a3786ed1","ELEMENT":"33120ef7-1122-4ca1-88c9-3cd0a3786ed1"}}]
10:05:21 INFO - 1499015121918 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:05:21 INFO - 1499015121922 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93d47b56-8ef5-41fe-a3fe-2f16803886ee","ELEMENT":"93d47b56-8ef5-41fe-a3fe-2f16803886ee"}}]
10:05:21 INFO - 1499015121923 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"33120ef7-1122-4ca1-88c9-3cd0a3786ed1"}]
10:05:22 INFO - 1499015122165 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:22 INFO - 1499015122167 Marionette TRACE 395 <- [1,5,null,{}]
10:05:22 INFO - 1499015122170 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"93d47b56-8ef5-41fe-a3fe-2f16803886ee"}]
10:05:22 INFO - 1499015122190 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:1330:5\n"},null]
10:05:22 INFO - 1499015122193 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:05:22 INFO - 1499015122198 Marionette TRACE 395 <- [1,7,null,{"value":false}]
10:05:22 INFO - 1499015122200 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}]
10:05:22 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499015122204 Marionette TRACE 395 <- [1,8,null,{"value":null}]
10:05:22 INFO - 1499015122205 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
10:05:22 INFO - 1499015122208 Marionette TRACE 395 <- [1,9,null,{}]
10:05:22 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 523ms
10:05:22 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:05:22 INFO - 1499015122211 Marionette DEBUG Closed connection 395
10:05:22 INFO - 1499015122212 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49846
10:05:22 INFO - 1499015122213 Marionette DEBUG Closed connection 396
10:05:22 INFO - 1499015122214 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49847
10:05:22 INFO - 1499015122216 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:22 INFO - 1499015122217 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:22 INFO - 1499015122221 Marionette DEBUG Register listener.js for window 4294967297
10:05:22 INFO - 1499015122226 Marionette TRACE 397 <- [1,1,null,{"sessionId":"980a451d-153c-459d-8b37-ada55d368eeb","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:22 INFO - 1499015122229 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
10:05:22 INFO - 1499015122231 Marionette TRACE 397 <- [1,2,null,{}]
10:05:22 INFO - 1499015122232 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:22 INFO - 1499015122234 Marionette TRACE 397 <- [1,3,null,{}]
10:05:22 INFO - 1499015122235 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:22 INFO - 1499015122236 Marionette TRACE 397 <- [1,4,null,{}]
10:05:22 INFO - 1499015122238 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}]
10:05:22 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499015122242 Marionette TRACE 397 <- [1,5,null,{"value":null}]
10:05:22 INFO - 1499015122243 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
10:05:22 INFO - 1499015122245 Marionette TRACE 397 <- [1,6,null,{}]
10:05:22 INFO - 1499015122247 Marionette DEBUG Closed connection 397
10:05:22 INFO - 1499015122248 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49848
10:05:22 INFO - 1499015122250 Marionette DEBUG Closed connection 398
10:05:22 INFO - 1499015122250 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49849
10:05:22 INFO - 1499015122252 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:22 INFO - 1499015122253 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:22 INFO - 1499015122256 Marionette DEBUG Register listener.js for window 4294967297
10:05:22 INFO - 1499015122261 Marionette TRACE 399 <- [1,1,null,{"sessionId":"dca04eec-44cd-4d64-8c5d-a4de19d0bf16","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:22 INFO - 1499015122263 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
10:05:22 INFO - 1499015122264 Marionette TRACE 399 <- [1,2,null,{}]
10:05:22 INFO - 1499015122266 Marionette DEBUG Closed connection 399
10:05:22 INFO - 1499015122267 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49850
10:05:22 INFO - 1499015122269 Marionette DEBUG Closed connection 400
10:05:22 INFO - 1499015122269 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49851
10:05:22 INFO - 1499015122271 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:22 INFO - 1499015122272 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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}
10:05:22 INFO - 1499015122275 Marionette DEBUG Register listener.js for window 4294967297
10:05:22 INFO - 1499015122280 Marionette TRACE 401 <- [1,1,null,{"sessionId":"4cbf5760-7312-4e4d-9486-591cf3c8a77d","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":2956,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbok_h0.mozrunner","moz:accessibilityChecks":false}}]
10:05:22 INFO - 1499015122281 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"}]
10:05:22 INFO - 1499015122284 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"
10:05:22 INFO - 1499015122286 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"
10:05:22 INFO - 1499015122286 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"
10:05:22 INFO - 1499015122296 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"
10:05:22 INFO - 1499015122298 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"
10:05:22 INFO - 1499015122313 Marionette TRACE 401 <- [1,2,null,{}]
10:05:22 INFO - 1499015122320 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:05:22 INFO - 1499015122328 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb92acd1-639a-4e34-80a1-61db0295ce9d","ELEMENT":"fb92acd1-639a-4e34-80a1-61db0295ce9d"}}]
10:05:22 INFO - 1499015122330 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"fb92acd1-639a-4e34-80a1-61db0295ce9d"}]
10:05:22 INFO - 1499015122361 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
10:05:22 INFO - 1499015122362 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"fb92acd1-639a-4e34-80a1-61db0295ce9d"}]
10:05:22 INFO - 1499015122382 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element