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:23:40 INFO - 1500546220652 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:23:40 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500546220655 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:23:40 INFO - 1500546220656 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:23:40 INFO - 1500546220657 Marionette TRACE 352 <- [1,6,null,{}]
03:23:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 143ms
03:23:40 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:23:40 INFO - 1500546220659 Marionette DEBUG Closed connection 352
03:23:40 INFO - 1500546220660 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50421
03:23:40 INFO - 1500546220661 Marionette DEBUG Closed connection 353
03:23:40 INFO - 1500546220661 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50422
03:23:40 INFO - 1500546220662 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:40 INFO - 1500546220663 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:40 INFO - 1500546220666 Marionette DEBUG Register listener.js for window 6442450945
03:23:40 INFO - 1500546220670 Marionette TRACE 354 <- [1,1,null,{"sessionId":"20f9d559-bc43-4bdb-a008-a0fff020fab0","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:40 INFO - 1500546220671 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:23:40 INFO - 1500546220672 Marionette TRACE 354 <- [1,2,null,{}]
03:23:40 INFO - 1500546220673 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:40 INFO - 1500546220673 Marionette TRACE 354 <- [1,3,null,{}]
03:23:40 INFO - 1500546220674 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:40 INFO - 1500546220675 Marionette TRACE 354 <- [1,4,null,{}]
03:23:40 INFO - 1500546220677 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:23:40 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500546220680 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:23:40 INFO - 1500546220681 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:23:40 INFO - 1500546220682 Marionette TRACE 354 <- [1,6,null,{}]
03:23:40 INFO - 1500546220684 Marionette DEBUG Closed connection 354
03:23:40 INFO - 1500546220684 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50423
03:23:40 INFO - 1500546220685 Marionette DEBUG Closed connection 355
03:23:40 INFO - 1500546220686 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50424
03:23:40 INFO - 1500546220687 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:40 INFO - 1500546220688 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:40 INFO - 1500546220691 Marionette DEBUG Register listener.js for window 6442450945
03:23:40 INFO - 1500546220694 Marionette TRACE 356 <- [1,1,null,{"sessionId":"359a2706-b4bd-401c-85b4-57c234dbd324","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:40 INFO - 1500546220696 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:23:40 INFO - 1500546220697 Marionette TRACE 356 <- [1,2,null,{}]
03:23:40 INFO - 1500546220699 Marionette DEBUG Closed connection 356
03:23:40 INFO - 1500546220700 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50425
03:23:40 INFO - 1500546220701 Marionette DEBUG Closed connection 357
03:23:40 INFO - 1500546220701 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50426
03:23:40 INFO - 1500546220702 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:40 INFO - 1500546220703 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:40 INFO - 1500546220706 Marionette DEBUG Register listener.js for window 6442450945
03:23:40 INFO - 1500546220709 Marionette TRACE 358 <- [1,1,null,{"sessionId":"80b7695e-8044-416b-9456-ae6ebf669d7a","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:40 INFO - 1500546220710 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49975/clicks.html"}]
03:23:40 INFO - 1500546220712 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49975/hidden.html"
03:23:40 INFO - 1500546220721 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49975/hidden.html"
03:23:40 INFO - 1500546220721 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49975/hidden.html"
03:23:40 INFO - 1500546220729 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49975/clicks.html"
03:23:40 INFO - 1500546220734 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49975/clicks.html"
03:23:40 INFO - 1500546220737 Marionette TRACE 358 <- [1,2,null,{}]
03:23:40 INFO - 1500546220745 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:23:40 INFO - 1500546220749 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da48b95d-c75b-431c-888f-2d21202eff46","ELEMENT":"da48b95d-c75b-431c-888f-2d21202eff46"}}]
03:23:40 INFO - 1500546220750 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"da48b95d-c75b-431c-888f-2d21202eff46"}]
03:23:40 INFO - 1500546220795 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49975/clicks.html"
03:23:40 INFO - 1500546220804 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49975/clicks.html"
03:23:40 INFO - 1500546220804 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49975/clicks.html"
03:23:40 INFO - 1500546220812 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49975/test.html"
03:23:40 INFO - 1500546220816 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49975/test.html"
03:23:40 INFO - 1500546220834 Marionette TRACE 358 <- [1,4,null,{}]
03:23:40 INFO - 1500546220849 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:23:40 INFO - 1500546220852 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4814d713-c9bd-4ae3-8bb6-0da4778a92a1","ELEMENT":"4814d713-c9bd-4ae3-8bb6-0da4778a92a1"}}]
03:23:40 INFO - 1500546220853 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:23:40 INFO - 1500546220854 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:23:40 INFO - 1500546220856 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:23:40 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500546220859 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:23:40 INFO - 1500546220860 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:23:40 INFO - 1500546220861 Marionette TRACE 358 <- [1,8,null,{}]
03:23:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 204ms
03:23:40 INFO - TEST-START | test_click.py TestClick.test_container_element
03:23:40 INFO - 1500546220863 Marionette DEBUG Closed connection 358
03:23:40 INFO - 1500546220864 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50429
03:23:40 INFO - 1500546220865 Marionette DEBUG Closed connection 359
03:23:40 INFO - 1500546220865 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50430
03:23:40 INFO - 1500546220867 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:40 INFO - 1500546220867 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:40 INFO - 1500546220870 Marionette DEBUG Register listener.js for window 6442450945
03:23:40 INFO - 1500546220874 Marionette TRACE 360 <- [1,1,null,{"sessionId":"e4fa6e2c-da36-4959-94b7-a430eefcce16","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:40 INFO - 1500546220875 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:23:40 INFO - 1500546220876 Marionette TRACE 360 <- [1,2,null,{}]
03:23:40 INFO - 1500546220877 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:40 INFO - 1500546220877 Marionette TRACE 360 <- [1,3,null,{}]
03:23:40 INFO - 1500546220878 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:40 INFO - 1500546220879 Marionette TRACE 360 <- [1,4,null,{}]
03:23:40 INFO - 1500546220881 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:23:40 INFO - TEST-START: test_click.py TestClick.test_container_element1500546220883 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:23:40 INFO - 1500546220884 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:23:40 INFO - 1500546220886 Marionette TRACE 360 <- [1,6,null,{}]
03:23:40 INFO - 1500546220887 Marionette DEBUG Closed connection 360
03:23:40 INFO - 1500546220888 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50431
03:23:40 INFO - 1500546220889 Marionette DEBUG Closed connection 361
03:23:40 INFO - 1500546220890 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50432
03:23:40 INFO - 1500546220891 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:40 INFO - 1500546220891 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:40 INFO - 1500546220894 Marionette DEBUG Register listener.js for window 6442450945
03:23:40 INFO - 1500546220898 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a816ddf1-0317-49f1-877e-11885b2565a3","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:40 INFO - 1500546220899 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:23:40 INFO - 1500546220900 Marionette TRACE 362 <- [1,2,null,{}]
03:23:40 INFO - 1500546220902 Marionette DEBUG Closed connection 362
03:23:40 INFO - 1500546220903 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50433
03:23:40 INFO - 1500546220903 Marionette DEBUG Closed connection 363
03:23:40 INFO - 1500546220904 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50434
03:23:40 INFO - 1500546220905 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:40 INFO - 1500546220906 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:40 INFO - 1500546220909 Marionette DEBUG Register listener.js for window 6442450945
03:23:40 INFO - 1500546220913 Marionette TRACE 364 <- [1,1,null,{"sessionId":"3c0114e0-04fb-448f-8c8c-be058a659185","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:40 INFO - 1500546220914 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:23:40 INFO - 1500546220916 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49975/test.html"
03:23:40 INFO - 1500546220918 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49975/test.html"
03:23:40 INFO - 1500546220918 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49975/test.html"
03:23:40 INFO - 1500546220931 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:23:40 INFO - 1500546220935 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:23:40 INFO - 1500546220938 Marionette TRACE 364 <- [1,2,null,{}]
03:23:40 INFO - 1500546220949 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:23:40 INFO - 1500546220952 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83a3c751-f2b2-4ea0-87c1-86971fbf22ca","ELEMENT":"83a3c751-f2b2-4ea0-87c1-86971fbf22ca"}}]
03:23:40 INFO - 1500546220953 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"83a3c751-f2b2-4ea0-87c1-86971fbf22ca"}]
03:23:41 INFO - 1500546221199 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:41 INFO - 1500546221200 Marionette TRACE 364 <- [1,4,null,{}]
03:23:41 INFO - 1500546221201 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"83a3c751-f2b2-4ea0-87c1-86971fbf22ca","name":"selected"}]
03:23:41 INFO - 1500546221206 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:23:41 INFO - 1500546221208 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:23:41 INFO - TEST-END: test_click.py TestClick.test_container_element1500546221211 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:23:41 INFO - 1500546221212 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:23:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 352ms
03:23:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:23:41 INFO - 1500546221214 Marionette TRACE 364 <- [1,7,null,{}]
03:23:41 INFO - 1500546221216 Marionette DEBUG Closed connection 364
03:23:41 INFO - 1500546221216 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50435
03:23:41 INFO - 1500546221217 Marionette DEBUG Closed connection 365
03:23:41 INFO - 1500546221218 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50436
03:23:41 INFO - 1500546221219 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1500546221220 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221223 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221228 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fb2c6504-4466-46bd-a286-a330d1136175","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221229 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:23:41 INFO - 1500546221229 Marionette TRACE 366 <- [1,2,null,{}]
03:23:41 INFO - 1500546221230 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:41 INFO - 1500546221231 Marionette TRACE 366 <- [1,3,null,{}]
03:23:41 INFO - 1500546221232 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:41 INFO - 1500546221232 Marionette TRACE 366 <- [1,4,null,{}]
03:23:41 INFO - 1500546221234 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:23:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500546221237 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1500546221238 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:23:41 INFO - 1500546221239 Marionette TRACE 366 <- [1,6,null,{}]
03:23:41 INFO - 1500546221241 Marionette DEBUG Closed connection 366
03:23:41 INFO - 1500546221242 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50437
03:23:41 INFO - 1500546221242 Marionette DEBUG Closed connection 367
03:23:41 INFO - 1500546221243 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50438
03:23:41 INFO - 1500546221244 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1500546221245 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221248 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221251 Marionette TRACE 368 <- [1,1,null,{"sessionId":"85cc7f81-44db-413c-8b25-d3b89dee6bd1","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221252 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:23:41 INFO - 1500546221254 Marionette TRACE 368 <- [1,2,null,{}]
03:23:41 INFO - 1500546221255 Marionette DEBUG Closed connection 368
03:23:41 INFO - 1500546221256 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50439
03:23:41 INFO - 1500546221257 Marionette DEBUG Closed connection 369
03:23:41 INFO - 1500546221258 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50440
03:23:41 INFO - 1500546221259 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:41 INFO - 1500546221260 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221262 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221266 Marionette TRACE 370 <- [1,1,null,{"sessionId":"49ee4056-94f3-45da-aef0-b2f89d870502","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221267 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:23:41 INFO - 1500546221269 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:23:41 INFO - 1500546221271 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:23:41 INFO - 1500546221271 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:23:41 INFO - 1500546221278 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:23:41 INFO - 1500546221282 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:23:41 INFO - 1500546221295 Marionette TRACE 370 <- [1,2,null,{}]
03:23:41 INFO - 1500546221301 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:23:41 INFO - 1500546221305 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"362045a8-e2dc-4a46-9297-c8a899a84df4","ELEMENT":"362045a8-e2dc-4a46-9297-c8a899a84df4"}}]
03:23:41 INFO - 1500546221312 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"362045a8-e2dc-4a46-9297-c8a899a84df4"}]
03:23:41 INFO - 1500546221562 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:41 INFO - 1500546221563 Marionette TRACE 370 <- [1,4,null,{}]
03:23:41 INFO - 1500546221564 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"362045a8-e2dc-4a46-9297-c8a899a84df4","name":"selected"}]
03:23:41 INFO - 1500546221569 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:23:41 INFO - 1500546221571 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:23:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 369ms
03:23:41 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:23:41 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500546221581 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:23:41 INFO - 1500546221582 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:23:41 INFO - 1500546221583 Marionette TRACE 370 <- [1,7,null,{}]
03:23:41 INFO - 1500546221585 Marionette DEBUG Closed connection 370
03:23:41 INFO - 1500546221586 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50441
03:23:41 INFO - 1500546221587 Marionette DEBUG Closed connection 371
03:23:41 INFO - 1500546221587 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50442
03:23:41 INFO - 1500546221588 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1500546221589 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221592 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221603 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7a7c4c74-0e74-4eca-86d2-e0a172c95709","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221605 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:23:41 INFO - 1500546221605 Marionette TRACE 372 <- [1,2,null,{}]
03:23:41 INFO - 1500546221606 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:41 INFO - 1500546221607 Marionette TRACE 372 <- [1,3,null,{}]
03:23:41 INFO - 1500546221608 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:41 INFO - 1500546221608 Marionette TRACE 372 <- [1,4,null,{}]
03:23:41 INFO - 1500546221610 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:23:41 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500546221613 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1500546221614 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:23:41 INFO - 1500546221616 Marionette TRACE 372 <- [1,6,null,{}]
03:23:41 INFO - 1500546221618 Marionette DEBUG Closed connection 372
03:23:41 INFO - 1500546221619 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50443
03:23:41 INFO - 1500546221620 Marionette DEBUG Closed connection 373
03:23:41 INFO - 1500546221620 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50444
03:23:41 INFO - 1500546221621 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1500546221622 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221625 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221628 Marionette TRACE 374 <- [1,1,null,{"sessionId":"1267f1b2-91a9-4928-8170-148cea2a5e38","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221630 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:23:41 INFO - 1500546221631 Marionette TRACE 374 <- [1,2,null,{}]
03:23:41 INFO - 1500546221633 Marionette DEBUG Closed connection 374
03:23:41 INFO - 1500546221633 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50445
03:23:41 INFO - 1500546221634 Marionette DEBUG Closed connection 375
03:23:41 INFO - 1500546221635 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50446
03:23:41 INFO - 1500546221636 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:41 INFO - 1500546221637 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221640 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221643 Marionette TRACE 376 <- [1,1,null,{"sessionId":"110e0c78-5812-4b41-9d4e-84a1e702e610","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221644 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:23:41 INFO - 1500546221647 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:23:41 INFO - 1500546221650 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:23:41 INFO - 1500546221651 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:23:41 INFO - 1500546221660 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:23:41 INFO - 1500546221665 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:23:41 INFO - 1500546221672 Marionette TRACE 376 <- [1,2,null,{}]
03:23:41 INFO - 1500546221676 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:23:41 INFO - 1500546221681 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"684bb89c-d9a2-42df-9f39-202d7cab74f9","ELEMENT":"684bb89c-d9a2-42df-9f39-202d7cab74f9"}}]
03:23:41 INFO - 1500546221682 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"684bb89c-d9a2-42df-9f39-202d7cab74f9"}]
03:23:41 INFO - 1500546221924 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:41 INFO - 1500546221925 Marionette TRACE 376 <- [1,4,null,{}]
03:23:41 INFO - 1500546221927 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:23:41 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500546221930 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1500546221931 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:23:41 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 349ms
03:23:41 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:23:41 INFO - 1500546221933 Marionette TRACE 376 <- [1,6,null,{}]
03:23:41 INFO - 1500546221934 Marionette DEBUG Closed connection 376
03:23:41 INFO - 1500546221935 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50447
03:23:41 INFO - 1500546221936 Marionette DEBUG Closed connection 377
03:23:41 INFO - 1500546221937 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50448
03:23:41 INFO - 1500546221938 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1500546221938 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221941 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221945 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d0a9a68b-4eeb-4267-9463-9297349c9dbe","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221946 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:23:41 INFO - 1500546221947 Marionette TRACE 378 <- [1,2,null,{}]
03:23:41 INFO - 1500546221948 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:41 INFO - 1500546221948 Marionette TRACE 378 <- [1,3,null,{}]
03:23:41 INFO - 1500546221949 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:41 INFO - 1500546221950 Marionette TRACE 378 <- [1,4,null,{}]
03:23:41 INFO - 1500546221951 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:23:41 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500546221954 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1500546221955 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:23:41 INFO - 1500546221957 Marionette TRACE 378 <- [1,6,null,{}]
03:23:41 INFO - 1500546221958 Marionette DEBUG Closed connection 378
03:23:41 INFO - 1500546221959 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50449
03:23:41 INFO - 1500546221960 Marionette DEBUG Closed connection 379
03:23:41 INFO - 1500546221961 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50450
03:23:41 INFO - 1500546221962 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1500546221962 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221965 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221969 Marionette TRACE 380 <- [1,1,null,{"sessionId":"674a4ff4-d2e8-4b0f-b2a7-0da6544e7897","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221970 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:23:41 INFO - 1500546221971 Marionette TRACE 380 <- [1,2,null,{}]
03:23:41 INFO - 1500546221973 Marionette DEBUG Closed connection 380
03:23:41 INFO - 1500546221974 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50451
03:23:41 INFO - 1500546221974 Marionette DEBUG Closed connection 381
03:23:41 INFO - 1500546221975 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50452
03:23:41 INFO - 1500546221976 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:41 INFO - 1500546221977 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1500546221980 Marionette DEBUG Register listener.js for window 6442450945
03:23:41 INFO - 1500546221983 Marionette TRACE 382 <- [1,1,null,{"sessionId":"0eecb281-9c60-4e99-a70d-043bd70fd168","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1500546221984 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:23:41 INFO - 1500546221986 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:23:41 INFO - 1500546221988 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:23:41 INFO - 1500546221989 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:23:42 INFO - 1500546221995 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:23:42 INFO - 1500546222000 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:23:42 INFO - 1500546222005 Marionette TRACE 382 <- [1,2,null,{}]
03:23:42 INFO - 1500546222015 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:23:42 INFO - 1500546222018 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c06a27ee-b1d6-4409-8e84-8ff8d27f0bda","ELEMENT":"c06a27ee-b1d6-4409-8e84-8ff8d27f0bda"}}]
03:23:42 INFO - 1500546222019 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c06a27ee-b1d6-4409-8e84-8ff8d27f0bda"}]
03:23:42 INFO - 1500546222270 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:42 INFO - 1500546222271 Marionette TRACE 382 <- [1,4,null,{}]
03:23:42 INFO - 1500546222273 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:23:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500546222276 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1500546222277 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1500546222278 Marionette TRACE 382 <- [1,6,null,{}]
03:23:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 345ms
03:23:42 INFO - TEST-START | test_click.py TestClick.test_input_file
03:23:42 INFO - 1500546222280 Marionette DEBUG Closed connection 382
03:23:42 INFO - 1500546222281 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50453
03:23:42 INFO - 1500546222281 Marionette DEBUG Closed connection 383
03:23:42 INFO - 1500546222282 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50454
03:23:42 INFO - 1500546222283 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1500546222284 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222287 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222290 Marionette TRACE 384 <- [1,1,null,{"sessionId":"73345bb9-f2ba-43be-a5c7-557ae9d3fba1","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222292 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:23:42 INFO - 1500546222292 Marionette TRACE 384 <- [1,2,null,{}]
03:23:42 INFO - 1500546222293 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:42 INFO - 1500546222294 Marionette TRACE 384 <- [1,3,null,{}]
03:23:42 INFO - 1500546222295 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:42 INFO - 1500546222295 Marionette TRACE 384 <- [1,4,null,{}]
03:23:42 INFO - 1500546222297 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:23:42 INFO - TEST-START: test_click.py TestClick.test_input_file1500546222300 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1500546222301 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1500546222302 Marionette TRACE 384 <- [1,6,null,{}]
03:23:42 INFO - 1500546222304 Marionette DEBUG Closed connection 384
03:23:42 INFO - 1500546222305 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50455
03:23:42 INFO - 1500546222306 Marionette DEBUG Closed connection 385
03:23:42 INFO - 1500546222306 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50456
03:23:42 INFO - 1500546222307 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1500546222308 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222311 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222314 Marionette TRACE 386 <- [1,1,null,{"sessionId":"63dbfdc7-6209-4e74-bd5e-5cb7574e4e98","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222316 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:23:42 INFO - 1500546222317 Marionette TRACE 386 <- [1,2,null,{}]
03:23:42 INFO - 1500546222319 Marionette DEBUG Closed connection 386
03:23:42 INFO - 1500546222319 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50457
03:23:42 INFO - 1500546222320 Marionette DEBUG Closed connection 387
03:23:42 INFO - 1500546222321 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50458
03:23:42 INFO - 1500546222322 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:42 INFO - 1500546222323 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222325 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222329 Marionette TRACE 388 <- [1,1,null,{"sessionId":"c36a92a7-aab0-445f-90d4-c8fc03f5a6f6","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222330 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:23:42 INFO - 1500546222333 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:23:42 INFO - 1500546222335 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:23:42 INFO - 1500546222336 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:23:42 INFO - 1500546222343 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1500546222348 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1500546222355 Marionette TRACE 388 <- [1,2,null,{}]
03:23:42 INFO - 1500546222362 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:23:42 INFO - 1500546222366 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c821931-3818-4f32-b367-b75c5386094e","ELEMENT":"3c821931-3818-4f32-b367-b75c5386094e"}}]
03:23:42 INFO - 1500546222367 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3c821931-3818-4f32-b367-b75c5386094e"}]
03:23:42 INFO - 1500546222395 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:23:42 INFO - 1500546222397 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:23:42 INFO - TEST-END: test_click.py TestClick.test_input_file1500546222400 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1500546222401 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:23:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 124ms
03:23:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:23:42 INFO - 1500546222403 Marionette TRACE 388 <- [1,6,null,{}]
03:23:42 INFO - 1500546222404 Marionette DEBUG Closed connection 388
03:23:42 INFO - 1500546222405 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50459
03:23:42 INFO - 1500546222406 Marionette DEBUG Closed connection 389
03:23:42 INFO - 1500546222407 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50460
03:23:42 INFO - 1500546222408 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1500546222409 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222412 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222415 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7d1dcce8-a183-4921-8af3-819fc8c46805","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222416 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:23:42 INFO - 1500546222417 Marionette TRACE 390 <- [1,2,null,{}]
03:23:42 INFO - 1500546222418 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:42 INFO - 1500546222418 Marionette TRACE 390 <- [1,3,null,{}]
03:23:42 INFO - 1500546222419 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:42 INFO - 1500546222420 Marionette TRACE 390 <- [1,4,null,{}]
03:23:42 INFO - 1500546222422 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:23:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500546222424 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1500546222425 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1500546222427 Marionette TRACE 390 <- [1,6,null,{}]
03:23:42 INFO - 1500546222428 Marionette DEBUG Closed connection 390
03:23:42 INFO - 1500546222429 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50461
03:23:42 INFO - 1500546222430 Marionette DEBUG Closed connection 391
03:23:42 INFO - 1500546222431 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50462
03:23:42 INFO - 1500546222432 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1500546222432 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222435 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222438 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d26097a0-e2df-422b-9a01-f9316d484ac1","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222440 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:23:42 INFO - 1500546222441 Marionette TRACE 392 <- [1,2,null,{}]
03:23:42 INFO - 1500546222443 Marionette DEBUG Closed connection 392
03:23:42 INFO - 1500546222444 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50463
03:23:42 INFO - 1500546222444 Marionette DEBUG Closed connection 393
03:23:42 INFO - 1500546222445 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50464
03:23:42 INFO - 1500546222446 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:42 INFO - 1500546222447 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222450 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222454 Marionette TRACE 394 <- [1,1,null,{"sessionId":"fc6938e3-36fc-444c-8b24-b91ae6f02bd0","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222455 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:23:42 INFO - 1500546222457 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1500546222459 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1500546222459 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1500546222466 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:23:42 INFO - 1500546222469 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:23:42 INFO - 1500546222472 Marionette TRACE 394 <- [1,2,null,{}]
03:23:42 INFO - 1500546222483 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:23:42 INFO - 1500546222486 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2b7b139b-904d-4dfc-b551-d42b41b71d29","ELEMENT":"2b7b139b-904d-4dfc-b551-d42b41b71d29"}}]
03:23:42 INFO - 1500546222487 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:23:42 INFO - 1500546222494 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"669e8f5a-af5b-468b-b8f6-fe99e60aa497","ELEMENT":"669e8f5a-af5b-468b-b8f6-fe99e60aa497"}}]
03:23:42 INFO - 1500546222496 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2b7b139b-904d-4dfc-b551-d42b41b71d29"}]
03:23:42 INFO - 1500546222736 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:42 INFO - 1500546222737 Marionette TRACE 394 <- [1,5,null,{}]
03:23:42 INFO - 1500546222738 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"669e8f5a-af5b-468b-b8f6-fe99e60aa497"}]
03:23:42 INFO - 1500546222756 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,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:23:42 INFO - 1500546222758 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
03:23:42 INFO - 1500546222761 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:23:42 INFO - 1500546222763 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:23:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500546222766 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:23:42 INFO - 1500546222767 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:23:42 INFO - 1500546222768 Marionette TRACE 394 <- [1,9,null,{}]
03:23:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 365ms
03:23:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:23:42 INFO - 1500546222770 Marionette DEBUG Closed connection 394
03:23:42 INFO - 1500546222771 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50465
03:23:42 INFO - 1500546222772 Marionette DEBUG Closed connection 395
03:23:42 INFO - 1500546222773 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50466
03:23:42 INFO - 1500546222774 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1500546222774 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222777 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222781 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9dc23eda-e4fc-482e-aebe-738d5f7f95c0","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222782 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:23:42 INFO - 1500546222783 Marionette TRACE 396 <- [1,2,null,{}]
03:23:42 INFO - 1500546222784 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:42 INFO - 1500546222784 Marionette TRACE 396 <- [1,3,null,{}]
03:23:42 INFO - 1500546222785 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:42 INFO - 1500546222786 Marionette TRACE 396 <- [1,4,null,{}]
03:23:42 INFO - 1500546222788 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:23:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500546222791 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1500546222792 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1500546222793 Marionette TRACE 396 <- [1,6,null,{}]
03:23:42 INFO - 1500546222795 Marionette DEBUG Closed connection 396
03:23:42 INFO - 1500546222796 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50467
03:23:42 INFO - 1500546222796 Marionette DEBUG Closed connection 397
03:23:42 INFO - 1500546222797 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50468
03:23:42 INFO - 1500546222798 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1500546222799 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222802 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222805 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4edacd38-65f9-458e-b66c-a064e8f4304a","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222806 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:23:42 INFO - 1500546222808 Marionette TRACE 398 <- [1,2,null,{}]
03:23:42 INFO - 1500546222810 Marionette DEBUG Closed connection 398
03:23:42 INFO - 1500546222811 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50469
03:23:42 INFO - 1500546222812 Marionette DEBUG Closed connection 399
03:23:42 INFO - 1500546222812 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50470
03:23:42 INFO - 1500546222814 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:42 INFO - 1500546222814 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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1500546222817 Marionette DEBUG Register listener.js for window 6442450945
03:23:42 INFO - 1500546222821 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1f9f7d85-77c0-425d-b619-a4db605fc081","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":5596,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdit32o.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1500546222822 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:23:42 INFO - 1500546222824 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:23:42 INFO - 1500546222826 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:23:42 INFO - 1500546222826 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:23:42 INFO - 1500546222834 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:23:42 INFO - 1500546222836 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:23:42 INFO - 1500546222840 Marionette TRACE 400 <- [1,2,null,{}]
03:23:42 INFO - 1500546222851 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:23:42 INFO - 1500546222854 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a31f5993-58b3-48f6-8e10-94b2378921f2","ELEMENT":"a31f5993-58b3-48f6-8e10-94b2378921f2"}}]
03:23:42 INFO - 1500546222855 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a31f5993-58b3-48f6-8e10-94b2378921f2"}]
03:23:42 INFO - 1500546222884 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:23:42 INFO - 1500546222886 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a31f5993-58b3-48f6-8e10-94b2378921f2"}]
03:23:42 INFO - 1500546222903 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element