could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
09:59:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 149ms
09:59:38 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:59:38 INFO - 1499360378127 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}]
09:59:38 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499360378130 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:59:38 INFO - 1499360378131 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:59:38 INFO - 1499360378132 Marionette TRACE 352 <- [1,6,null,{}]
09:59:38 INFO - 1499360378134 Marionette DEBUG Closed connection 352
09:59:38 INFO - 1499360378135 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49777
09:59:38 INFO - 1499360378136 Marionette DEBUG Closed connection 353
09:59:38 INFO - 1499360378137 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49778
09:59:38 INFO - 1499360378137 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:38 INFO - 1499360378139 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378141 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378144 Marionette TRACE 354 <- [1,1,null,{"sessionId":"4a25bb60-c850-403d-b37e-701c2d4e186e","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378145 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:59:38 INFO - 1499360378145 Marionette TRACE 354 <- [1,2,null,{}]
09:59:38 INFO - 1499360378146 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:59:38 INFO - 1499360378146 Marionette TRACE 354 <- [1,3,null,{}]
09:59:38 INFO - 1499360378147 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:59:38 INFO - 1499360378148 Marionette TRACE 354 <- [1,4,null,{}]
09:59:38 INFO - 1499360378149 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}]
09:59:38 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499360378152 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:59:38 INFO - 1499360378153 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:59:38 INFO - 1499360378154 Marionette TRACE 354 <- [1,6,null,{}]
09:59:38 INFO - 1499360378156 Marionette DEBUG Closed connection 354
09:59:38 INFO - 1499360378156 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49779
09:59:38 INFO - 1499360378157 Marionette DEBUG Closed connection 355
09:59:38 INFO - 1499360378158 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49780
09:59:38 INFO - 1499360378159 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:38 INFO - 1499360378160 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378161 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378164 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f6dcc797-2783-4328-99cd-3a9aee6dfbd6","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378165 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:59:38 INFO - 1499360378166 Marionette TRACE 356 <- [1,2,null,{}]
09:59:38 INFO - 1499360378168 Marionette DEBUG Closed connection 356
09:59:38 INFO - 1499360378168 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49781
09:59:38 INFO - 1499360378169 Marionette DEBUG Closed connection 357
09:59:38 INFO - 1499360378170 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49782
09:59:38 INFO - 1499360378171 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:59:38 INFO - 1499360378172 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378173 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378176 Marionette TRACE 358 <- [1,1,null,{"sessionId":"bf801891-4120-421d-b093-ec52d6d1156a","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378178 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49344/clicks.html"}]
09:59:38 INFO - 1499360378179 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49344/hidden.html"
09:59:38 INFO - 1499360378185 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49344/hidden.html"
09:59:38 INFO - 1499360378185 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49344/hidden.html"
09:59:38 INFO - 1499360378223 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49344/clicks.html"
09:59:38 INFO - 1499360378224 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49344/clicks.html"
09:59:38 INFO - 1499360378227 Marionette TRACE 358 <- [1,2,null,{}]
09:59:38 INFO - 1499360378228 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:59:38 INFO - 1499360378236 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"25bccd80-54e2-4b10-8a15-4239616b44f1","ELEMENT":"25bccd80-54e2-4b10-8a15-4239616b44f1"}}]
09:59:38 INFO - 1499360378248 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"25bccd80-54e2-4b10-8a15-4239616b44f1"}]
09:59:38 INFO - 1499360378286 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49344/clicks.html"
09:59:38 INFO - 1499360378303 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49344/clicks.html"
09:59:38 INFO - 1499360378304 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49344/clicks.html"
09:59:38 INFO - 1499360378321 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49344/test.html"
09:59:38 INFO - 1499360378324 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49344/test.html"
09:59:38 INFO - 1499360378327 Marionette TRACE 358 <- [1,4,null,{}]
09:59:38 INFO - 1499360378335 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:59:38 INFO - 1499360378338 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fc196a39-966c-40c3-9bd2-746760d0df6b","ELEMENT":"fc196a39-966c-40c3-9bd2-746760d0df6b"}}]
09:59:38 INFO - 1499360378339 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:59:38 INFO - 1499360378340 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:59:38 INFO - 1499360378341 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}]
09:59:38 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499360378344 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:59:38 INFO - 1499360378352 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:59:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 221ms
09:59:38 INFO - TEST-START | test_click.py TestClick.test_container_element
09:59:38 INFO - 1499360378353 Marionette TRACE 358 <- [1,8,null,{}]
09:59:38 INFO - 1499360378355 Marionette DEBUG Closed connection 358
09:59:38 INFO - 1499360378356 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49785
09:59:38 INFO - 1499360378357 Marionette DEBUG Closed connection 359
09:59:38 INFO - 1499360378358 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49786
09:59:38 INFO - 1499360378359 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:38 INFO - 1499360378359 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378361 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378364 Marionette TRACE 360 <- [1,1,null,{"sessionId":"f7e95b10-65ac-4cb1-b2db-b418e5728fad","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378365 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:59:38 INFO - 1499360378366 Marionette TRACE 360 <- [1,2,null,{}]
09:59:38 INFO - 1499360378367 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:59:38 INFO - 1499360378367 Marionette TRACE 360 <- [1,3,null,{}]
09:59:38 INFO - 1499360378369 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:59:38 INFO - 1499360378369 Marionette TRACE 360 <- [1,4,null,{}]
09:59:38 INFO - 1499360378371 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}]
09:59:38 INFO - TEST-START: test_click.py TestClick.test_container_element1499360378374 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:59:38 INFO - 1499360378374 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:59:38 INFO - 1499360378375 Marionette TRACE 360 <- [1,6,null,{}]
09:59:38 INFO - 1499360378378 Marionette DEBUG Closed connection 360
09:59:38 INFO - 1499360378379 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49787
09:59:38 INFO - 1499360378379 Marionette DEBUG Closed connection 361
09:59:38 INFO - 1499360378380 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49788
09:59:38 INFO - 1499360378381 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:38 INFO - 1499360378382 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378384 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378386 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c3dd83b0-0f84-4bd3-ae40-c968838d13e8","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378387 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:59:38 INFO - 1499360378388 Marionette TRACE 362 <- [1,2,null,{}]
09:59:38 INFO - 1499360378390 Marionette DEBUG Closed connection 362
09:59:38 INFO - 1499360378391 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49789
09:59:38 INFO - 1499360378391 Marionette DEBUG Closed connection 363
09:59:38 INFO - 1499360378392 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49790
09:59:38 INFO - 1499360378393 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:59:38 INFO - 1499360378394 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378395 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378398 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8683f720-1f6c-4e72-a1dc-59913e37760b","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378399 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"}]
09:59:38 INFO - 1499360378400 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49344/test.html"
09:59:38 INFO - 1499360378404 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49344/test.html"
09:59:38 INFO - 1499360378404 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49344/test.html"
09:59:38 INFO - 1499360378434 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"
09:59:38 INFO - 1499360378440 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"
09:59:38 INFO - 1499360378444 Marionette TRACE 364 <- [1,2,null,{}]
09:59:38 INFO - 1499360378452 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:59:38 INFO - 1499360378455 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ea0f2576-85b3-42bf-86cc-5e6d99fbc5fc","ELEMENT":"ea0f2576-85b3-42bf-86cc-5e6d99fbc5fc"}}]
09:59:38 INFO - 1499360378456 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"ea0f2576-85b3-42bf-86cc-5e6d99fbc5fc"}]
09:59:38 INFO - 1499360378702 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:59:38 INFO - 1499360378704 Marionette TRACE 364 <- [1,4,null,{}]
09:59:38 INFO - 1499360378705 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"ea0f2576-85b3-42bf-86cc-5e6d99fbc5fc","name":"selected"}]
09:59:38 INFO - 1499360378710 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:59:38 INFO - 1499360378712 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}]
09:59:38 INFO - TEST-END: test_click.py TestClick.test_container_element1499360378714 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:59:38 INFO - 1499360378715 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:59:38 INFO - 1499360378717 Marionette TRACE 364 <- [1,7,null,{}]
09:59:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 363ms
09:59:38 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:59:38 INFO - 1499360378718 Marionette DEBUG Closed connection 364
09:59:38 INFO - 1499360378719 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49791
09:59:38 INFO - 1499360378720 Marionette DEBUG Closed connection 365
09:59:38 INFO - 1499360378721 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49792
09:59:38 INFO - 1499360378722 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:38 INFO - 1499360378723 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378725 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378728 Marionette TRACE 366 <- [1,1,null,{"sessionId":"49d7af1e-05f4-4521-851c-fdc741bea740","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378729 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:59:38 INFO - 1499360378730 Marionette TRACE 366 <- [1,2,null,{}]
09:59:38 INFO - 1499360378731 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:59:38 INFO - 1499360378731 Marionette TRACE 366 <- [1,3,null,{}]
09:59:38 INFO - 1499360378733 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:59:38 INFO - 1499360378733 Marionette TRACE 366 <- [1,4,null,{}]
09:59:38 INFO - 1499360378735 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}]
09:59:38 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499360378738 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:59:38 INFO - 1499360378739 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:59:38 INFO - 1499360378740 Marionette TRACE 366 <- [1,6,null,{}]
09:59:38 INFO - 1499360378742 Marionette DEBUG Closed connection 366
09:59:38 INFO - 1499360378742 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49793
09:59:38 INFO - 1499360378743 Marionette DEBUG Closed connection 367
09:59:38 INFO - 1499360378744 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49794
09:59:38 INFO - 1499360378745 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:38 INFO - 1499360378745 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378747 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378750 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b2950ab4-6fef-44f0-99de-0efac2e864e7","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378751 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:59:38 INFO - 1499360378752 Marionette TRACE 368 <- [1,2,null,{}]
09:59:38 INFO - 1499360378754 Marionette DEBUG Closed connection 368
09:59:38 INFO - 1499360378755 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49795
09:59:38 INFO - 1499360378755 Marionette DEBUG Closed connection 369
09:59:38 INFO - 1499360378756 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49796
09:59:38 INFO - 1499360378757 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:59:38 INFO - 1499360378757 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:38 INFO - 1499360378759 Marionette DEBUG Register listener.js for window 12
09:59:38 INFO - 1499360378762 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c79f999f-405a-401a-8012-bfcb327163de","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:38 INFO - 1499360378763 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"}]
09:59:38 INFO - 1499360378764 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"
09:59:38 INFO - 1499360378766 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"
09:59:38 INFO - 1499360378767 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"
09:59:38 INFO - 1499360378777 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"
09:59:38 INFO - 1499360378785 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"
09:59:38 INFO - 1499360378787 Marionette TRACE 370 <- [1,2,null,{}]
09:59:38 INFO - 1499360378789 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:59:38 INFO - 1499360378792 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"420c16f2-0be4-4987-94c9-fc8895a5b9ed","ELEMENT":"420c16f2-0be4-4987-94c9-fc8895a5b9ed"}}]
09:59:38 INFO - 1499360378793 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"420c16f2-0be4-4987-94c9-fc8895a5b9ed"}]
09:59:39 INFO - 1499360379035 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:59:39 INFO - 1499360379036 Marionette TRACE 370 <- [1,4,null,{}]
09:59:39 INFO - 1499360379037 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"420c16f2-0be4-4987-94c9-fc8895a5b9ed","name":"selected"}]
09:59:39 INFO - 1499360379041 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:59:39 INFO - 1499360379043 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}]
09:59:39 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499360379048 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:59:39 INFO - 1499360379050 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:59:39 INFO - 1499360379051 Marionette TRACE 370 <- [1,7,null,{}]
09:59:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
09:59:39 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:59:39 INFO - 1499360379053 Marionette DEBUG Closed connection 370
09:59:39 INFO - 1499360379054 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49797
09:59:39 INFO - 1499360379054 Marionette DEBUG Closed connection 371
09:59:39 INFO - 1499360379055 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49798
09:59:39 INFO - 1499360379056 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:39 INFO - 1499360379057 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379059 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379062 Marionette TRACE 372 <- [1,1,null,{"sessionId":"482a3902-66ab-400d-af97-ab1d7f82199f","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379063 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:59:39 INFO - 1499360379063 Marionette TRACE 372 <- [1,2,null,{}]
09:59:39 INFO - 1499360379064 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:59:39 INFO - 1499360379064 Marionette TRACE 372 <- [1,3,null,{}]
09:59:39 INFO - 1499360379065 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:59:39 INFO - 1499360379065 Marionette TRACE 372 <- [1,4,null,{}]
09:59:39 INFO - 1499360379067 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}]
09:59:39 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499360379070 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:59:39 INFO - 1499360379071 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:59:39 INFO - 1499360379072 Marionette TRACE 372 <- [1,6,null,{}]
09:59:39 INFO - 1499360379074 Marionette DEBUG Closed connection 372
09:59:39 INFO - 1499360379074 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49799
09:59:39 INFO - 1499360379075 Marionette DEBUG Closed connection 373
09:59:39 INFO - 1499360379076 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49800
09:59:39 INFO - 1499360379077 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:39 INFO - 1499360379077 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379079 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379082 Marionette TRACE 374 <- [1,1,null,{"sessionId":"9df5beb8-2c9b-489b-82a4-c933dd6dc590","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379083 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:59:39 INFO - 1499360379084 Marionette TRACE 374 <- [1,2,null,{}]
09:59:39 INFO - 1499360379085 Marionette DEBUG Closed connection 374
09:59:39 INFO - 1499360379086 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49801
09:59:39 INFO - 1499360379087 Marionette DEBUG Closed connection 375
09:59:39 INFO - 1499360379088 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49802
09:59:39 INFO - 1499360379089 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:59:39 INFO - 1499360379089 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379091 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379094 Marionette TRACE 376 <- [1,1,null,{"sessionId":"1fb7af05-c468-44af-a7ab-ee46a7180e91","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379095 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"}]
09:59:39 INFO - 1499360379096 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"
09:59:39 INFO - 1499360379099 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"
09:59:39 INFO - 1499360379099 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"
09:59:39 INFO - 1499360379116 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"
09:59:39 INFO - 1499360379118 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"
09:59:39 INFO - 1499360379122 Marionette TRACE 376 <- [1,2,null,{}]
09:59:39 INFO - 1499360379123 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:59:39 INFO - 1499360379125 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f553a2f-95df-4926-8b90-2bb56e366b56","ELEMENT":"6f553a2f-95df-4926-8b90-2bb56e366b56"}}]
09:59:39 INFO - 1499360379126 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6f553a2f-95df-4926-8b90-2bb56e366b56"}]
09:59:39 INFO - 1499360379369 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:59:39 INFO - 1499360379370 Marionette TRACE 376 <- [1,4,null,{}]
09:59:39 INFO - 1499360379372 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}]
09:59:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499360379374 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:59:39 INFO - 1499360379375 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:59:39 INFO - 1499360379377 Marionette TRACE 376 <- [1,6,null,{}]
09:59:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 325ms
09:59:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:59:39 INFO - 1499360379378 Marionette DEBUG Closed connection 376
09:59:39 INFO - 1499360379379 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49803
09:59:39 INFO - 1499360379380 Marionette DEBUG Closed connection 377
09:59:39 INFO - 1499360379381 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49804
09:59:39 INFO - 1499360379382 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:39 INFO - 1499360379382 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379391 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379394 Marionette TRACE 378 <- [1,1,null,{"sessionId":"a304e148-ab1d-404a-b716-069ddf842863","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379396 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:59:39 INFO - 1499360379396 Marionette TRACE 378 <- [1,2,null,{}]
09:59:39 INFO - 1499360379397 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:59:39 INFO - 1499360379397 Marionette TRACE 378 <- [1,3,null,{}]
09:59:39 INFO - 1499360379398 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:59:39 INFO - 1499360379398 Marionette TRACE 378 <- [1,4,null,{}]
09:59:39 INFO - 1499360379400 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}]
09:59:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499360379403 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:59:39 INFO - 1499360379403 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:59:39 INFO - 1499360379404 Marionette TRACE 378 <- [1,6,null,{}]
09:59:39 INFO - 1499360379406 Marionette DEBUG Closed connection 378
09:59:39 INFO - 1499360379407 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49805
09:59:39 INFO - 1499360379408 Marionette DEBUG Closed connection 379
09:59:39 INFO - 1499360379409 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49806
09:59:39 INFO - 1499360379409 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:39 INFO - 1499360379410 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379412 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379415 Marionette TRACE 380 <- [1,1,null,{"sessionId":"abb48def-f2fa-4026-a440-91bcc04015eb","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379416 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:59:39 INFO - 1499360379417 Marionette TRACE 380 <- [1,2,null,{}]
09:59:39 INFO - 1499360379418 Marionette DEBUG Closed connection 380
09:59:39 INFO - 1499360379419 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49807
09:59:39 INFO - 1499360379420 Marionette DEBUG Closed connection 381
09:59:39 INFO - 1499360379420 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49808
09:59:39 INFO - 1499360379421 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:59:39 INFO - 1499360379422 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379424 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379426 Marionette TRACE 382 <- [1,1,null,{"sessionId":"dfad1dbb-16f0-4f5e-a096-dd9134e7c3cb","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379427 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"}]
09:59:39 INFO - 1499360379428 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"
09:59:39 INFO - 1499360379430 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"
09:59:39 INFO - 1499360379431 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"
09:59:39 INFO - 1499360379441 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"
09:59:39 INFO - 1499360379445 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"
09:59:39 INFO - 1499360379455 Marionette TRACE 382 <- [1,2,null,{}]
09:59:39 INFO - 1499360379459 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:59:39 INFO - 1499360379461 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe68124f-c1f8-4ad7-aaaa-5f2e9b47d4e6","ELEMENT":"fe68124f-c1f8-4ad7-aaaa-5f2e9b47d4e6"}}]
09:59:39 INFO - 1499360379462 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"fe68124f-c1f8-4ad7-aaaa-5f2e9b47d4e6"}]
09:59:39 INFO - 1499360379701 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:59:39 INFO - 1499360379702 Marionette TRACE 382 <- [1,4,null,{}]
09:59:39 INFO - 1499360379704 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}]
09:59:39 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499360379707 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:59:39 INFO - 1499360379708 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:59:39 INFO - 1499360379709 Marionette TRACE 382 <- [1,6,null,{}]
09:59:39 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 333ms
09:59:39 INFO - TEST-START | test_click.py TestClick.test_input_file
09:59:39 INFO - 1499360379711 Marionette DEBUG Closed connection 382
09:59:39 INFO - 1499360379712 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49809
09:59:39 INFO - 1499360379713 Marionette DEBUG Closed connection 383
09:59:39 INFO - 1499360379713 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49810
09:59:39 INFO - 1499360379714 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:39 INFO - 1499360379715 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379717 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379720 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ddb35f27-7f1d-4883-9ea9-d38ea3a9fb20","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379720 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:59:39 INFO - 1499360379721 Marionette TRACE 384 <- [1,2,null,{}]
09:59:39 INFO - 1499360379722 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:59:39 INFO - 1499360379722 Marionette TRACE 384 <- [1,3,null,{}]
09:59:39 INFO - 1499360379723 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:59:39 INFO - 1499360379724 Marionette TRACE 384 <- [1,4,null,{}]
09:59:39 INFO - 1499360379725 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}]
09:59:39 INFO - TEST-START: test_click.py TestClick.test_input_file1499360379728 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:59:39 INFO - 1499360379729 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:59:39 INFO - 1499360379730 Marionette TRACE 384 <- [1,6,null,{}]
09:59:39 INFO - 1499360379732 Marionette DEBUG Closed connection 384
09:59:39 INFO - 1499360379732 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49811
09:59:39 INFO - 1499360379733 Marionette DEBUG Closed connection 385
09:59:39 INFO - 1499360379734 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49812
09:59:39 INFO - 1499360379735 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:39 INFO - 1499360379735 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379737 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379740 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1303ba9d-2cbc-4cc1-8774-eff18c086573","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379741 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:59:39 INFO - 1499360379742 Marionette TRACE 386 <- [1,2,null,{}]
09:59:39 INFO - 1499360379743 Marionette DEBUG Closed connection 386
09:59:39 INFO - 1499360379744 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49813
09:59:39 INFO - 1499360379745 Marionette DEBUG Closed connection 387
09:59:39 INFO - 1499360379745 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49814
09:59:39 INFO - 1499360379746 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:59:39 INFO - 1499360379747 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379749 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379751 Marionette TRACE 388 <- [1,1,null,{"sessionId":"add285ec-ab58-4438-949c-a9b65af00032","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379752 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:59:39 INFO - 1499360379754 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"
09:59:39 INFO - 1499360379756 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"
09:59:39 INFO - 1499360379756 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"
09:59:39 INFO - 1499360379771 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:59:39 INFO - 1499360379775 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:59:39 INFO - 1499360379777 Marionette TRACE 388 <- [1,2,null,{}]
09:59:39 INFO - 1499360379785 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:59:39 INFO - 1499360379787 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"65e4686c-5a12-478f-8adb-d5b11bf6dc3b","ELEMENT":"65e4686c-5a12-478f-8adb-d5b11bf6dc3b"}}]
09:59:39 INFO - 1499360379788 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"65e4686c-5a12-478f-8adb-d5b11bf6dc3b"}]
09:59:39 INFO - 1499360379813 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
09:59:39 INFO - 1499360379815 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}]
09:59:39 INFO - TEST-END: test_click.py TestClick.test_input_file1499360379818 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:59:39 INFO - 1499360379819 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:59:39 INFO - 1499360379820 Marionette TRACE 388 <- [1,6,null,{}]
09:59:39 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 110ms
09:59:39 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:59:39 INFO - 1499360379822 Marionette DEBUG Closed connection 388
09:59:39 INFO - 1499360379823 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49815
09:59:39 INFO - 1499360379823 Marionette DEBUG Closed connection 389
09:59:39 INFO - 1499360379824 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49816
09:59:39 INFO - 1499360379825 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:39 INFO - 1499360379826 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379828 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379831 Marionette TRACE 390 <- [1,1,null,{"sessionId":"eb70d550-9b53-4ff6-8a19-09f8f1dfcdd7","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379832 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:59:39 INFO - 1499360379832 Marionette TRACE 390 <- [1,2,null,{}]
09:59:39 INFO - 1499360379833 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:59:39 INFO - 1499360379833 Marionette TRACE 390 <- [1,3,null,{}]
09:59:39 INFO - 1499360379834 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:59:39 INFO - 1499360379835 Marionette TRACE 390 <- [1,4,null,{}]
09:59:39 INFO - 1499360379836 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}]
09:59:39 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499360379839 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:59:39 INFO - 1499360379840 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:59:39 INFO - 1499360379841 Marionette TRACE 390 <- [1,6,null,{}]
09:59:39 INFO - 1499360379842 Marionette DEBUG Closed connection 390
09:59:39 INFO - 1499360379843 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49817
09:59:39 INFO - 1499360379844 Marionette DEBUG Closed connection 391
09:59:39 INFO - 1499360379844 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49818
09:59:39 INFO - 1499360379845 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:39 INFO - 1499360379846 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379848 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379858 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0b5c006a-9538-4299-8764-f86eadc943c5","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379859 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:59:39 INFO - 1499360379860 Marionette TRACE 392 <- [1,2,null,{}]
09:59:39 INFO - 1499360379862 Marionette DEBUG Closed connection 392
09:59:39 INFO - 1499360379863 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49819
09:59:39 INFO - 1499360379863 Marionette DEBUG Closed connection 393
09:59:39 INFO - 1499360379864 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49820
09:59:39 INFO - 1499360379865 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:59:39 INFO - 1499360379866 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:39 INFO - 1499360379868 Marionette DEBUG Register listener.js for window 12
09:59:39 INFO - 1499360379871 Marionette TRACE 394 <- [1,1,null,{"sessionId":"9b73741f-76c7-41fd-95f3-d3384ee30535","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:39 INFO - 1499360379872 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"}]
09:59:39 INFO - 1499360379873 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:59:39 INFO - 1499360379875 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:59:39 INFO - 1499360379875 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:59:39 INFO - 1499360379892 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"
09:59:39 INFO - 1499360379895 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"
09:59:39 INFO - 1499360379903 Marionette TRACE 394 <- [1,2,null,{}]
09:59:39 INFO - 1499360379905 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:59:39 INFO - 1499360379907 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f3f25cdd-351f-4575-9a60-8cf05e2f427a","ELEMENT":"f3f25cdd-351f-4575-9a60-8cf05e2f427a"}}]
09:59:39 INFO - 1499360379908 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:59:39 INFO - 1499360379910 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7bc61ed2-4447-4be3-b6f3-040f1bcb4172","ELEMENT":"7bc61ed2-4447-4be3-b6f3-040f1bcb4172"}}]
09:59:39 INFO - 1499360379911 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"f3f25cdd-351f-4575-9a60-8cf05e2f427a"}]
09:59:40 INFO - 1499360380147 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:59:40 INFO - 1499360380148 Marionette TRACE 394 <- [1,5,null,{}]
09:59:40 INFO - 1499360380149 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"7bc61ed2-4447-4be3-b6f3-040f1bcb4172"}]
09:59:40 INFO - 1499360380162 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
09:59:40 INFO - 1499360380164 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:59:40 INFO - 1499360380167 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:59:40 INFO - 1499360380168 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}]
09:59:40 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499360380170 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:59:40 INFO - 1499360380171 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:59:40 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 352ms
09:59:40 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:59:40 INFO - 1499360380172 Marionette TRACE 394 <- [1,9,null,{}]
09:59:40 INFO - 1499360380174 Marionette DEBUG Closed connection 394
09:59:40 INFO - 1499360380175 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49821
09:59:40 INFO - 1499360380176 Marionette DEBUG Closed connection 395
09:59:40 INFO - 1499360380177 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49822
09:59:40 INFO - 1499360380177 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:40 INFO - 1499360380178 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:40 INFO - 1499360380180 Marionette DEBUG Register listener.js for window 12
09:59:40 INFO - 1499360380183 Marionette TRACE 396 <- [1,1,null,{"sessionId":"e98de5ed-4abc-4d74-ad2d-0957e9edebc5","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:40 INFO - 1499360380184 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:59:40 INFO - 1499360380184 Marionette TRACE 396 <- [1,2,null,{}]
09:59:40 INFO - 1499360380185 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:59:40 INFO - 1499360380185 Marionette TRACE 396 <- [1,3,null,{}]
09:59:40 INFO - 1499360380186 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:59:40 INFO - 1499360380186 Marionette TRACE 396 <- [1,4,null,{}]
09:59:40 INFO - 1499360380188 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}]
09:59:40 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499360380190 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:59:40 INFO - 1499360380191 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:59:40 INFO - 1499360380192 Marionette TRACE 396 <- [1,6,null,{}]
09:59:40 INFO - 1499360380193 Marionette DEBUG Closed connection 396
09:59:40 INFO - 1499360380194 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49823
09:59:40 INFO - 1499360380195 Marionette DEBUG Closed connection 397
09:59:40 INFO - 1499360380196 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49824
09:59:40 INFO - 1499360380196 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:59:40 INFO - 1499360380197 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:40 INFO - 1499360380199 Marionette DEBUG Register listener.js for window 12
09:59:40 INFO - 1499360380201 Marionette TRACE 398 <- [1,1,null,{"sessionId":"31885284-87c8-454e-bfa8-3f5a5ab9d438","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:40 INFO - 1499360380202 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:59:40 INFO - 1499360380203 Marionette TRACE 398 <- [1,2,null,{}]
09:59:40 INFO - 1499360380205 Marionette DEBUG Closed connection 398
09:59:40 INFO - 1499360380205 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49825
09:59:40 INFO - 1499360380206 Marionette DEBUG Closed connection 399
09:59:40 INFO - 1499360380207 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49826
09:59:40 INFO - 1499360380208 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:59:40 INFO - 1499360380208 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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}
09:59:40 INFO - 1499360380210 Marionette DEBUG Register listener.js for window 12
09:59:40 INFO - 1499360380213 Marionette TRACE 400 <- [1,1,null,{"sessionId":"3767cc38-0839-49a3-a653-852d95db28a5","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":3952,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnrex1m.mozrunner","moz:accessibilityChecks":false}}]
09:59:40 INFO - 1499360380214 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"}]
09:59:40 INFO - 1499360380215 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"
09:59:40 INFO - 1499360380217 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"
09:59:40 INFO - 1499360380217 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"
09:59:40 INFO - 1499360380235 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"
09:59:40 INFO - 1499360380237 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"
09:59:40 INFO - 1499360380239 Marionette TRACE 400 <- [1,2,null,{}]
09:59:40 INFO - 1499360380240 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:59:40 INFO - 1499360380242 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"02e8f73e-69f0-41f5-94a7-7b32de9123ea","ELEMENT":"02e8f73e-69f0-41f5-94a7-7b32de9123ea"}}]
09:59:40 INFO - 1499360380243 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"02e8f73e-69f0-41f5-94a7-7b32de9123ea"}]
09:59:40 INFO - 1499360380270 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:59:40 INFO - 1499360380271 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"02e8f73e-69f0-41f5-94a7-7b32de9123ea"}]
09:59:40 INFO - 1499360380285 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element