could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:13:35 INFO - 1499854415028 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
03:13:35 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499854415031 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:13:35 INFO - 1499854415032 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:13:35 INFO - 1499854415034 Marionette TRACE 352 <- [1,6,null,{}]
03:13:35 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 140ms
03:13:35 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:13:35 INFO - 1499854415036 Marionette DEBUG Closed connection 352
03:13:35 INFO - 1499854415036 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49903
03:13:35 INFO - 1499854415038 Marionette DEBUG Closed connection 353
03:13:35 INFO - 1499854415039 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49904
03:13:35 INFO - 1499854415040 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:35 INFO - 1499854415040 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415042 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415046 Marionette TRACE 354 <- [1,1,null,{"sessionId":"599cd457-8737-48d4-b4bd-57887a7b8bf9","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415047 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:13:35 INFO - 1499854415048 Marionette TRACE 354 <- [1,2,null,{}]
03:13:35 INFO - 1499854415049 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:13:35 INFO - 1499854415049 Marionette TRACE 354 <- [1,3,null,{}]
03:13:35 INFO - 1499854415050 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:13:35 INFO - 1499854415051 Marionette TRACE 354 <- [1,4,null,{}]
03:13:35 INFO - 1499854415053 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
03:13:35 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499854415058 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:13:35 INFO - 1499854415059 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:13:35 INFO - 1499854415060 Marionette TRACE 354 <- [1,6,null,{}]
03:13:35 INFO - 1499854415062 Marionette DEBUG Closed connection 354
03:13:35 INFO - 1499854415062 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49905
03:13:35 INFO - 1499854415063 Marionette DEBUG Closed connection 355
03:13:35 INFO - 1499854415064 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49906
03:13:35 INFO - 1499854415065 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:35 INFO - 1499854415065 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415067 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415070 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4d510c20-f433-4dbb-a652-5d34e2e3c32f","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415072 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:13:35 INFO - 1499854415073 Marionette TRACE 356 <- [1,2,null,{}]
03:13:35 INFO - 1499854415074 Marionette DEBUG Closed connection 356
03:13:35 INFO - 1499854415075 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49907
03:13:35 INFO - 1499854415075 Marionette DEBUG Closed connection 357
03:13:35 INFO - 1499854415076 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49908
03:13:35 INFO - 1499854415077 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:13:35 INFO - 1499854415078 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415080 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415083 Marionette TRACE 358 <- [1,1,null,{"sessionId":"23e10b3d-23b1-44f7-85e7-e01b32466880","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415084 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49462/clicks.html"}]
03:13:35 INFO - 1499854415086 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49462/hidden.html"
03:13:35 INFO - 1499854415094 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49462/hidden.html"
03:13:35 INFO - 1499854415094 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49462/hidden.html"
03:13:35 INFO - 1499854415104 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49462/clicks.html"
03:13:35 INFO - 1499854415106 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49462/clicks.html"
03:13:35 INFO - 1499854415110 Marionette TRACE 358 <- [1,2,null,{}]
03:13:35 INFO - 1499854415120 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:13:35 INFO - 1499854415123 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f809317-89ee-4e7c-9299-551689e9bbfc","ELEMENT":"7f809317-89ee-4e7c-9299-551689e9bbfc"}}]
03:13:35 INFO - 1499854415124 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"7f809317-89ee-4e7c-9299-551689e9bbfc"}]
03:13:35 INFO - 1499854415164 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49462/clicks.html"
03:13:35 INFO - 1499854415172 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49462/clicks.html"
03:13:35 INFO - 1499854415173 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49462/clicks.html"
03:13:35 INFO - 1499854415179 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49462/test.html"
03:13:35 INFO - 1499854415182 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49462/test.html"
03:13:35 INFO - 1499854415194 Marionette TRACE 358 <- [1,4,null,{}]
03:13:35 INFO - 1499854415206 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:13:35 INFO - 1499854415209 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1cfcead7-a863-4029-bc92-b81775eb9357","ELEMENT":"1cfcead7-a863-4029-bc92-b81775eb9357"}}]
03:13:35 INFO - 1499854415210 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:13:35 INFO - 1499854415210 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:13:35 INFO - 1499854415212 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
03:13:35 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499854415215 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:13:35 INFO - 1499854415216 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:13:35 INFO - 1499854415217 Marionette TRACE 358 <- [1,8,null,{}]
03:13:35 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 184ms
03:13:35 INFO - TEST-START | test_click.py TestClick.test_container_element
03:13:35 INFO - 1499854415219 Marionette DEBUG Closed connection 358
03:13:35 INFO - 1499854415220 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49911
03:13:35 INFO - 1499854415221 Marionette DEBUG Closed connection 359
03:13:35 INFO - 1499854415222 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49912
03:13:35 INFO - 1499854415223 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:35 INFO - 1499854415223 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415226 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415229 Marionette TRACE 360 <- [1,1,null,{"sessionId":"33a7b327-37f1-4c57-ac6b-d7bf95398ce0","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415230 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:13:35 INFO - 1499854415230 Marionette TRACE 360 <- [1,2,null,{}]
03:13:35 INFO - 1499854415231 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:13:35 INFO - 1499854415232 Marionette TRACE 360 <- [1,3,null,{}]
03:13:35 INFO - 1499854415232 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:13:35 INFO - 1499854415233 Marionette TRACE 360 <- [1,4,null,{}]
03:13:35 INFO - 1499854415235 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
03:13:35 INFO - TEST-START: test_click.py TestClick.test_container_element1499854415237 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:13:35 INFO - 1499854415238 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:13:35 INFO - 1499854415239 Marionette TRACE 360 <- [1,6,null,{}]
03:13:35 INFO - 1499854415241 Marionette DEBUG Closed connection 360
03:13:35 INFO - 1499854415241 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49913
03:13:35 INFO - 1499854415243 Marionette DEBUG Closed connection 361
03:13:35 INFO - 1499854415243 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49914
03:13:35 INFO - 1499854415244 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:35 INFO - 1499854415245 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415247 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415250 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5c42722b-62f1-4753-affa-b7efc034898a","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415251 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:13:35 INFO - 1499854415253 Marionette TRACE 362 <- [1,2,null,{}]
03:13:35 INFO - 1499854415254 Marionette DEBUG Closed connection 362
03:13:35 INFO - 1499854415255 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49915
03:13:35 INFO - 1499854415256 Marionette DEBUG Closed connection 363
03:13:35 INFO - 1499854415256 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49916
03:13:35 INFO - 1499854415258 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:13:35 INFO - 1499854415258 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415260 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415263 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0ba6ae93-f351-48ab-9677-4b6a1a125a35","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415265 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:13:35 INFO - 1499854415267 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49462/test.html"
03:13:35 INFO - 1499854415269 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49462/test.html"
03:13:35 INFO - 1499854415269 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49462/test.html"
03:13:35 INFO - 1499854415275 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415278 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415290 Marionette TRACE 364 <- [1,2,null,{}]
03:13:35 INFO - 1499854415294 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:13:35 INFO - 1499854415297 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c53397f0-cb82-4401-a90d-b2fc549c81f4","ELEMENT":"c53397f0-cb82-4401-a90d-b2fc549c81f4"}}]
03:13:35 INFO - 1499854415303 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c53397f0-cb82-4401-a90d-b2fc549c81f4"}]
03:13:35 INFO - 1499854415542 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:13:35 INFO - 1499854415543 Marionette TRACE 364 <- [1,4,null,{}]
03:13:35 INFO - 1499854415544 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"c53397f0-cb82-4401-a90d-b2fc549c81f4","name":"selected"}]
03:13:35 INFO - 1499854415548 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:13:35 INFO - 1499854415550 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
03:13:35 INFO - TEST-END: test_click.py TestClick.test_container_element1499854415553 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:13:35 INFO - 1499854415554 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:13:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 338ms
03:13:35 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:13:35 INFO - 1499854415556 Marionette TRACE 364 <- [1,7,null,{}]
03:13:35 INFO - 1499854415557 Marionette DEBUG Closed connection 364
03:13:35 INFO - 1499854415558 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49917
03:13:35 INFO - 1499854415559 Marionette DEBUG Closed connection 365
03:13:35 INFO - 1499854415560 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49918
03:13:35 INFO - 1499854415561 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:35 INFO - 1499854415561 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415564 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415567 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e6c9e5aa-76f5-4cf4-b394-f079dd1d54aa","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415568 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:13:35 INFO - 1499854415568 Marionette TRACE 366 <- [1,2,null,{}]
03:13:35 INFO - 1499854415569 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:13:35 INFO - 1499854415570 Marionette TRACE 366 <- [1,3,null,{}]
03:13:35 INFO - 1499854415570 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:13:35 INFO - 1499854415571 Marionette TRACE 366 <- [1,4,null,{}]
03:13:35 INFO - 1499854415573 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
03:13:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499854415575 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:13:35 INFO - 1499854415577 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:13:35 INFO - 1499854415578 Marionette TRACE 366 <- [1,6,null,{}]
03:13:35 INFO - 1499854415579 Marionette DEBUG Closed connection 366
03:13:35 INFO - 1499854415580 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49919
03:13:35 INFO - 1499854415580 Marionette DEBUG Closed connection 367
03:13:35 INFO - 1499854415581 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49920
03:13:35 INFO - 1499854415583 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:35 INFO - 1499854415583 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415585 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415589 Marionette TRACE 368 <- [1,1,null,{"sessionId":"0a989bb7-ed60-4c11-b1b5-0d5ff31f7e98","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415590 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:13:35 INFO - 1499854415591 Marionette TRACE 368 <- [1,2,null,{}]
03:13:35 INFO - 1499854415592 Marionette DEBUG Closed connection 368
03:13:35 INFO - 1499854415593 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49921
03:13:35 INFO - 1499854415593 Marionette DEBUG Closed connection 369
03:13:35 INFO - 1499854415594 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49922
03:13:35 INFO - 1499854415595 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:13:35 INFO - 1499854415596 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415598 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415601 Marionette TRACE 370 <- [1,1,null,{"sessionId":"1ef99f15-1712-438b-a31b-9157bf275f9e","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415602 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:13:35 INFO - 1499854415604 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415606 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415607 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415618 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415623 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415624 Marionette TRACE 370 <- [1,2,null,{}]
03:13:35 INFO - 1499854415626 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:13:35 INFO - 1499854415629 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d9007708-d88b-4e3c-8997-4bf628ec3447","ELEMENT":"d9007708-d88b-4e3c-8997-4bf628ec3447"}}]
03:13:35 INFO - 1499854415630 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d9007708-d88b-4e3c-8997-4bf628ec3447"}]
03:13:35 INFO - 1499854415877 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:13:35 INFO - 1499854415878 Marionette TRACE 370 <- [1,4,null,{}]
03:13:35 INFO - 1499854415879 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d9007708-d88b-4e3c-8997-4bf628ec3447","name":"selected"}]
03:13:35 INFO - 1499854415883 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:13:35 INFO - 1499854415885 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
03:13:35 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499854415887 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:13:35 INFO - 1499854415888 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:13:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 333ms
03:13:35 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:13:35 INFO - 1499854415890 Marionette TRACE 370 <- [1,7,null,{}]
03:13:35 INFO - 1499854415891 Marionette DEBUG Closed connection 370
03:13:35 INFO - 1499854415892 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49923
03:13:35 INFO - 1499854415893 Marionette DEBUG Closed connection 371
03:13:35 INFO - 1499854415894 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49924
03:13:35 INFO - 1499854415895 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:35 INFO - 1499854415895 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415897 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415900 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9a588e61-d06a-446c-9bbe-741683db7c5f","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415902 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:13:35 INFO - 1499854415902 Marionette TRACE 372 <- [1,2,null,{}]
03:13:35 INFO - 1499854415903 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:13:35 INFO - 1499854415903 Marionette TRACE 372 <- [1,3,null,{}]
03:13:35 INFO - 1499854415904 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:13:35 INFO - 1499854415905 Marionette TRACE 372 <- [1,4,null,{}]
03:13:35 INFO - 1499854415906 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
03:13:35 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499854415909 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:13:35 INFO - 1499854415910 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:13:35 INFO - 1499854415911 Marionette TRACE 372 <- [1,6,null,{}]
03:13:35 INFO - 1499854415912 Marionette DEBUG Closed connection 372
03:13:35 INFO - 1499854415913 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49925
03:13:35 INFO - 1499854415914 Marionette DEBUG Closed connection 373
03:13:35 INFO - 1499854415915 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49926
03:13:35 INFO - 1499854415916 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:35 INFO - 1499854415916 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415918 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415921 Marionette TRACE 374 <- [1,1,null,{"sessionId":"187a9f0d-faf6-4738-b312-9d0b940ad1ce","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415922 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:13:35 INFO - 1499854415924 Marionette TRACE 374 <- [1,2,null,{}]
03:13:35 INFO - 1499854415925 Marionette DEBUG Closed connection 374
03:13:35 INFO - 1499854415926 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49927
03:13:35 INFO - 1499854415927 Marionette DEBUG Closed connection 375
03:13:35 INFO - 1499854415927 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49928
03:13:35 INFO - 1499854415928 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:13:35 INFO - 1499854415929 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:35 INFO - 1499854415931 Marionette DEBUG Register listener.js for window 4294967297
03:13:35 INFO - 1499854415942 Marionette TRACE 376 <- [1,1,null,{"sessionId":"fa4a627f-6961-4266-a31f-afae59934b6f","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:35 INFO - 1499854415943 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
03:13:35 INFO - 1499854415945 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415947 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415947 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:35 INFO - 1499854415954 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:13:35 INFO - 1499854415956 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:13:35 INFO - 1499854415960 Marionette TRACE 376 <- [1,2,null,{}]
03:13:35 INFO - 1499854415965 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:13:35 INFO - 1499854415973 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"57fb5b6e-7dda-4a89-9550-f605afe209d6","ELEMENT":"57fb5b6e-7dda-4a89-9550-f605afe209d6"}}]
03:13:35 INFO - 1499854415974 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"57fb5b6e-7dda-4a89-9550-f605afe209d6"}]
03:13:36 INFO - 1499854416214 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:13:36 INFO - 1499854416215 Marionette TRACE 376 <- [1,4,null,{}]
03:13:36 INFO - 1499854416217 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
03:13:36 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499854416220 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:13:36 INFO - 1499854416221 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:13:36 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 332ms
03:13:36 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:13:36 INFO - 1499854416222 Marionette TRACE 376 <- [1,6,null,{}]
03:13:36 INFO - 1499854416223 Marionette DEBUG Closed connection 376
03:13:36 INFO - 1499854416224 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49929
03:13:36 INFO - 1499854416225 Marionette DEBUG Closed connection 377
03:13:36 INFO - 1499854416226 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49930
03:13:36 INFO - 1499854416227 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:36 INFO - 1499854416228 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416230 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416233 Marionette TRACE 378 <- [1,1,null,{"sessionId":"a526101c-d743-4c08-a686-2c631b2d27dd","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416234 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:13:36 INFO - 1499854416235 Marionette TRACE 378 <- [1,2,null,{}]
03:13:36 INFO - 1499854416235 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:13:36 INFO - 1499854416236 Marionette TRACE 378 <- [1,3,null,{}]
03:13:36 INFO - 1499854416237 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:13:36 INFO - 1499854416237 Marionette TRACE 378 <- [1,4,null,{}]
03:13:36 INFO - 1499854416239 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
03:13:36 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499854416242 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:13:36 INFO - 1499854416243 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:13:36 INFO - 1499854416244 Marionette TRACE 378 <- [1,6,null,{}]
03:13:36 INFO - 1499854416245 Marionette DEBUG Closed connection 378
03:13:36 INFO - 1499854416246 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49931
03:13:36 INFO - 1499854416247 Marionette DEBUG Closed connection 379
03:13:36 INFO - 1499854416247 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49932
03:13:36 INFO - 1499854416248 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:36 INFO - 1499854416249 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416251 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416254 Marionette TRACE 380 <- [1,1,null,{"sessionId":"bcfa0de2-7fc8-4da0-9042-b0bb1fa07b94","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416255 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:13:36 INFO - 1499854416257 Marionette TRACE 380 <- [1,2,null,{}]
03:13:36 INFO - 1499854416258 Marionette DEBUG Closed connection 380
03:13:36 INFO - 1499854416259 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49933
03:13:36 INFO - 1499854416259 Marionette DEBUG Closed connection 381
03:13:36 INFO - 1499854416260 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49934
03:13:36 INFO - 1499854416261 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:13:36 INFO - 1499854416262 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416264 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416267 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2af531b2-20b9-4f4b-a7dc-70cf888d7b02","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416268 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:13:36 INFO - 1499854416270 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:13:36 INFO - 1499854416273 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:13:36 INFO - 1499854416273 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:13:36 INFO - 1499854416281 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:36 INFO - 1499854416286 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:36 INFO - 1499854416290 Marionette TRACE 382 <- [1,2,null,{}]
03:13:36 INFO - 1499854416293 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:13:36 INFO - 1499854416296 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d5d2595f-78f5-469f-99e9-052c6f087bf8","ELEMENT":"d5d2595f-78f5-469f-99e9-052c6f087bf8"}}]
03:13:36 INFO - 1499854416297 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d5d2595f-78f5-469f-99e9-052c6f087bf8"}]
03:13:36 INFO - 1499854416534 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:13:36 INFO - 1499854416535 Marionette TRACE 382 <- [1,4,null,{}]
03:13:36 INFO - 1499854416537 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
03:13:36 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499854416540 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:13:36 INFO - 1499854416541 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:13:36 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 319ms
03:13:36 INFO - 1499854416542 Marionette TRACE 382 <- [1,6,null,{}]
03:13:36 INFO - TEST-START | test_click.py TestClick.test_input_file
03:13:36 INFO - 1499854416543 Marionette DEBUG Closed connection 382
03:13:36 INFO - 1499854416545 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49935
03:13:36 INFO - 1499854416545 Marionette DEBUG Closed connection 383
03:13:36 INFO - 1499854416546 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49936
03:13:36 INFO - 1499854416547 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:36 INFO - 1499854416548 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416550 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416553 Marionette TRACE 384 <- [1,1,null,{"sessionId":"762bca3e-b2a4-4cdd-9b61-d13a8e70c8dc","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416554 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:13:36 INFO - 1499854416555 Marionette TRACE 384 <- [1,2,null,{}]
03:13:36 INFO - 1499854416555 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:13:36 INFO - 1499854416556 Marionette TRACE 384 <- [1,3,null,{}]
03:13:36 INFO - 1499854416557 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:13:36 INFO - 1499854416557 Marionette TRACE 384 <- [1,4,null,{}]
03:13:36 INFO - 1499854416559 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
03:13:36 INFO - TEST-START: test_click.py TestClick.test_input_file1499854416561 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:13:36 INFO - 1499854416563 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:13:36 INFO - 1499854416564 Marionette TRACE 384 <- [1,6,null,{}]
03:13:36 INFO - 1499854416565 Marionette DEBUG Closed connection 384
03:13:36 INFO - 1499854416566 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49937
03:13:36 INFO - 1499854416567 Marionette DEBUG Closed connection 385
03:13:36 INFO - 1499854416567 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49938
03:13:36 INFO - 1499854416568 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:36 INFO - 1499854416569 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416571 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416574 Marionette TRACE 386 <- [1,1,null,{"sessionId":"899dedea-7427-4228-b857-c7a235d36b96","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416575 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:13:36 INFO - 1499854416576 Marionette TRACE 386 <- [1,2,null,{}]
03:13:36 INFO - 1499854416578 Marionette DEBUG Closed connection 386
03:13:36 INFO - 1499854416579 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49939
03:13:36 INFO - 1499854416579 Marionette DEBUG Closed connection 387
03:13:36 INFO - 1499854416580 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49940
03:13:36 INFO - 1499854416581 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:13:36 INFO - 1499854416582 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416584 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416587 Marionette TRACE 388 <- [1,1,null,{"sessionId":"53e8879a-7776-4837-9623-62180420d36e","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416588 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:13:36 INFO - 1499854416590 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:36 INFO - 1499854416591 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:36 INFO - 1499854416592 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:13:36 INFO - 1499854416598 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:13:36 INFO - 1499854416603 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:13:36 INFO - 1499854416612 Marionette TRACE 388 <- [1,2,null,{}]
03:13:36 INFO - 1499854416620 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:13:36 INFO - 1499854416626 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0f720fee-3f4c-4d04-a44a-b5f8a9e037b7","ELEMENT":"0f720fee-3f4c-4d04-a44a-b5f8a9e037b7"}}]
03:13:36 INFO - 1499854416627 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"0f720fee-3f4c-4d04-a44a-b5f8a9e037b7"}]
03:13:36 INFO - 1499854416659 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:13:36 INFO - 1499854416661 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
03:13:36 INFO - TEST-END: test_click.py TestClick.test_input_file1499854416664 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:13:36 INFO - 1499854416665 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:13:36 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 123ms
03:13:36 INFO - 1499854416666 Marionette TRACE 388 <- [1,6,null,{}]
03:13:36 INFO - 1499854416667 Marionette DEBUG Closed connection 388
03:13:36 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:13:36 INFO - 1499854416670 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49941
03:13:36 INFO - 1499854416671 Marionette DEBUG Closed connection 389
03:13:36 INFO - 1499854416671 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49942
03:13:36 INFO - 1499854416672 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:36 INFO - 1499854416673 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416675 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416678 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d0390cbb-d3a3-4bad-b718-b3665e83e612","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416679 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:13:36 INFO - 1499854416680 Marionette TRACE 390 <- [1,2,null,{}]
03:13:36 INFO - 1499854416681 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:13:36 INFO - 1499854416681 Marionette TRACE 390 <- [1,3,null,{}]
03:13:36 INFO - 1499854416682 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:13:36 INFO - 1499854416682 Marionette TRACE 390 <- [1,4,null,{}]
03:13:36 INFO - 1499854416684 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
03:13:36 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499854416687 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:13:36 INFO - 1499854416688 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:13:36 INFO - 1499854416689 Marionette TRACE 390 <- [1,6,null,{}]
03:13:36 INFO - 1499854416691 Marionette DEBUG Closed connection 390
03:13:36 INFO - 1499854416692 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49943
03:13:36 INFO - 1499854416692 Marionette DEBUG Closed connection 391
03:13:36 INFO - 1499854416693 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49944
03:13:36 INFO - 1499854416694 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:36 INFO - 1499854416695 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416697 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416700 Marionette TRACE 392 <- [1,1,null,{"sessionId":"3a22ddda-0321-4fb5-a6c6-da89362017ac","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416701 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:13:36 INFO - 1499854416702 Marionette TRACE 392 <- [1,2,null,{}]
03:13:36 INFO - 1499854416704 Marionette DEBUG Closed connection 392
03:13:36 INFO - 1499854416704 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49945
03:13:36 INFO - 1499854416705 Marionette DEBUG Closed connection 393
03:13:36 INFO - 1499854416706 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49946
03:13:36 INFO - 1499854416707 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:13:36 INFO - 1499854416707 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:36 INFO - 1499854416710 Marionette DEBUG Register listener.js for window 4294967297
03:13:36 INFO - 1499854416713 Marionette TRACE 394 <- [1,1,null,{"sessionId":"5cd2d8d9-8a0b-4768-8dc0-364207355eed","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:36 INFO - 1499854416714 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
03:13:36 INFO - 1499854416716 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:13:36 INFO - 1499854416717 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:13:36 INFO - 1499854416718 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:13:36 INFO - 1499854416724 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:13:36 INFO - 1499854416726 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:13:36 INFO - 1499854416728 Marionette TRACE 394 <- [1,2,null,{}]
03:13:36 INFO - 1499854416731 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:13:36 INFO - 1499854416739 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4be34b33-92ac-4425-a3c2-83da359115e7","ELEMENT":"4be34b33-92ac-4425-a3c2-83da359115e7"}}]
03:13:36 INFO - 1499854416741 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:13:36 INFO - 1499854416743 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ed89af5b-e43f-4cd9-afa3-e55c3e2fa380","ELEMENT":"ed89af5b-e43f-4cd9-afa3-e55c3e2fa380"}}]
03:13:36 INFO - 1499854416744 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"4be34b33-92ac-4425-a3c2-83da359115e7"}]
03:13:36 INFO - 1499854416980 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:13:36 INFO - 1499854416981 Marionette TRACE 394 <- [1,5,null,{}]
03:13:36 INFO - 1499854416982 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"ed89af5b-e43f-4cd9-afa3-e55c3e2fa380"}]
03:13:37 INFO - 1499854416996 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:13:37 INFO - 1499854416998 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:13:37 INFO - 1499854417001 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:13:37 INFO - 1499854417002 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
03:13:37 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499854417005 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:13:37 INFO - 1499854417006 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:13:37 INFO - 1499854417007 Marionette TRACE 394 <- [1,9,null,{}]
03:13:37 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 340ms
03:13:37 INFO - 1499854417008 Marionette DEBUG Closed connection 394
03:13:37 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:13:37 INFO - 1499854417011 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49947
03:13:37 INFO - 1499854417011 Marionette DEBUG Closed connection 395
03:13:37 INFO - 1499854417012 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49948
03:13:37 INFO - 1499854417013 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:37 INFO - 1499854417014 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:37 INFO - 1499854417016 Marionette DEBUG Register listener.js for window 4294967297
03:13:37 INFO - 1499854417019 Marionette TRACE 396 <- [1,1,null,{"sessionId":"5801a09e-8da8-47d2-8a7a-44362ad9f2b3","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:37 INFO - 1499854417021 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:13:37 INFO - 1499854417021 Marionette TRACE 396 <- [1,2,null,{}]
03:13:37 INFO - 1499854417022 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:13:37 INFO - 1499854417022 Marionette TRACE 396 <- [1,3,null,{}]
03:13:37 INFO - 1499854417023 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:13:37 INFO - 1499854417024 Marionette TRACE 396 <- [1,4,null,{}]
03:13:37 INFO - 1499854417025 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
03:13:37 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499854417028 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:13:37 INFO - 1499854417029 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:13:37 INFO - 1499854417030 Marionette TRACE 396 <- [1,6,null,{}]
03:13:37 INFO - 1499854417031 Marionette DEBUG Closed connection 396
03:13:37 INFO - 1499854417032 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49949
03:13:37 INFO - 1499854417033 Marionette DEBUG Closed connection 397
03:13:37 INFO - 1499854417034 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49950
03:13:37 INFO - 1499854417035 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:13:37 INFO - 1499854417035 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:37 INFO - 1499854417037 Marionette DEBUG Register listener.js for window 4294967297
03:13:37 INFO - 1499854417040 Marionette TRACE 398 <- [1,1,null,{"sessionId":"c1a2422c-8b4c-409b-9205-7b8a65def8fa","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:37 INFO - 1499854417041 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:13:37 INFO - 1499854417043 Marionette TRACE 398 <- [1,2,null,{}]
03:13:37 INFO - 1499854417044 Marionette DEBUG Closed connection 398
03:13:37 INFO - 1499854417045 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49951
03:13:37 INFO - 1499854417045 Marionette DEBUG Closed connection 399
03:13:37 INFO - 1499854417046 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49952
03:13:37 INFO - 1499854417047 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:13:37 INFO - 1499854417048 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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}
03:13:37 INFO - 1499854417050 Marionette DEBUG Register listener.js for window 4294967297
03:13:37 INFO - 1499854417054 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c564bfe6-2695-4dc4-995a-e347b244c8c9","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":1428,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2jvf3h.mozrunner","moz:accessibilityChecks":false}}]
03:13:37 INFO - 1499854417055 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
03:13:37 INFO - 1499854417057 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:13:37 INFO - 1499854417059 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:13:37 INFO - 1499854417059 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:13:37 INFO - 1499854417067 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
03:13:37 INFO - 1499854417068 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
03:13:37 INFO - 1499854417076 Marionette TRACE 400 <- [1,2,null,{}]
03:13:37 INFO - 1499854417079 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:13:37 INFO - 1499854417086 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c16c80f5-ee6d-4bc3-8b63-ce7377a259f0","ELEMENT":"c16c80f5-ee6d-4bc3-8b63-ce7377a259f0"}}]
03:13:37 INFO - 1499854417087 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c16c80f5-ee6d-4bc3-8b63-ce7377a259f0"}]
03:13:37 INFO - 1499854417109 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:13:37 INFO - 1499854417111 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c16c80f5-ee6d-4bc3-8b63-ce7377a259f0"}]
03:13:37 INFO - 1499854417125 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element