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]
12:19:15 INFO - 1499282355666 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}]
12:19:15 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 121ms
12:19:15 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499282355669 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:19:15 INFO - 1499282355669 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:19:15 INFO - 1499282355671 Marionette TRACE 352 <- [1,6,null,{}]
12:19:15 INFO - 1499282355672 Marionette DEBUG Closed connection 352
12:19:15 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:19:15 INFO - 1499282355674 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49796
12:19:15 INFO - 1499282355675 Marionette DEBUG Closed connection 353
12:19:15 INFO - 1499282355676 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49797
12:19:15 INFO - 1499282355677 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:15 INFO - 1499282355677 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:15 INFO - 1499282355679 Marionette DEBUG Register listener.js for window 12
12:19:15 INFO - 1499282355682 Marionette TRACE 354 <- [1,1,null,{"sessionId":"4e025ed0-3ff5-428c-b82d-930863797540","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:15 INFO - 1499282355683 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:19:15 INFO - 1499282355684 Marionette TRACE 354 <- [1,2,null,{}]
12:19:15 INFO - 1499282355684 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:15 INFO - 1499282355685 Marionette TRACE 354 <- [1,3,null,{}]
12:19:15 INFO - 1499282355686 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:15 INFO - 1499282355686 Marionette TRACE 354 <- [1,4,null,{}]
12:19:15 INFO - 1499282355688 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}]
12:19:15 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499282355690 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:19:15 INFO - 1499282355691 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:19:15 INFO - 1499282355692 Marionette TRACE 354 <- [1,6,null,{}]
12:19:15 INFO - 1499282355694 Marionette DEBUG Closed connection 354
12:19:15 INFO - 1499282355695 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49798
12:19:15 INFO - 1499282355695 Marionette DEBUG Closed connection 355
12:19:15 INFO - 1499282355696 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49799
12:19:15 INFO - 1499282355697 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:15 INFO - 1499282355698 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:15 INFO - 1499282355700 Marionette DEBUG Register listener.js for window 12
12:19:15 INFO - 1499282355702 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d597bf4c-1b6a-4324-ace9-9d7077046665","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:15 INFO - 1499282355703 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:19:15 INFO - 1499282355704 Marionette TRACE 356 <- [1,2,null,{}]
12:19:15 INFO - 1499282355706 Marionette DEBUG Closed connection 356
12:19:15 INFO - 1499282355707 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49800
12:19:15 INFO - 1499282355707 Marionette DEBUG Closed connection 357
12:19:15 INFO - 1499282355708 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49801
12:19:15 INFO - 1499282355709 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:15 INFO - 1499282355710 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:15 INFO - 1499282355711 Marionette DEBUG Register listener.js for window 12
12:19:15 INFO - 1499282355714 Marionette TRACE 358 <- [1,1,null,{"sessionId":"e5efa1e6-953b-4fbe-8f0e-94770ef23625","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:15 INFO - 1499282355716 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49367/clicks.html"}]
12:19:15 INFO - 1499282355717 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/hidden.html"
12:19:15 INFO - 1499282355722 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/hidden.html"
12:19:15 INFO - 1499282355723 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/hidden.html"
12:19:15 INFO - 1499282355738 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49367/clicks.html"
12:19:15 INFO - 1499282355743 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49367/clicks.html"
12:19:15 INFO - 1499282355746 Marionette TRACE 358 <- [1,2,null,{}]
12:19:15 INFO - 1499282355753 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:19:15 INFO - 1499282355756 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5aca1d9e-1ba6-496e-97db-e9873503f10f","ELEMENT":"5aca1d9e-1ba6-496e-97db-e9873503f10f"}}]
12:19:15 INFO - 1499282355757 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5aca1d9e-1ba6-496e-97db-e9873503f10f"}]
12:19:15 INFO - 1499282355803 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/clicks.html"
12:19:15 INFO - 1499282355807 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/clicks.html"
12:19:15 INFO - 1499282355808 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/clicks.html"
12:19:15 INFO - 1499282355826 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49367/test.html"
12:19:15 INFO - 1499282355829 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49367/test.html"
12:19:15 INFO - 1499282355838 Marionette TRACE 358 <- [1,4,null,{}]
12:19:15 INFO - 1499282355840 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:19:15 INFO - 1499282355843 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a5cf904d-03d7-4a79-b799-741cf72a32dd","ELEMENT":"a5cf904d-03d7-4a79-b799-741cf72a32dd"}}]
12:19:15 INFO - 1499282355844 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:19:15 INFO - 1499282355845 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:19:15 INFO - 1499282355846 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}]
12:19:15 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499282355850 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:19:15 INFO - 1499282355850 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:19:15 INFO - 1499282355852 Marionette TRACE 358 <- [1,8,null,{}]
12:19:15 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 181ms
12:19:15 INFO - TEST-START | test_click.py TestClick.test_container_element
12:19:15 INFO - 1499282355853 Marionette DEBUG Closed connection 358
12:19:15 INFO - 1499282355854 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49804
12:19:15 INFO - 1499282355855 Marionette DEBUG Closed connection 359
12:19:15 INFO - 1499282355856 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49805
12:19:15 INFO - 1499282355857 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:15 INFO - 1499282355859 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:15 INFO - 1499282355861 Marionette DEBUG Register listener.js for window 12
12:19:15 INFO - 1499282355867 Marionette TRACE 360 <- [1,1,null,{"sessionId":"6f9457d8-c98c-43af-8c53-45ca539e9db1","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:15 INFO - 1499282355868 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:19:15 INFO - 1499282355868 Marionette TRACE 360 <- [1,2,null,{}]
12:19:15 INFO - 1499282355869 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:15 INFO - 1499282355870 Marionette TRACE 360 <- [1,3,null,{}]
12:19:15 INFO - 1499282355870 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:15 INFO - 1499282355871 Marionette TRACE 360 <- [1,4,null,{}]
12:19:15 INFO - 1499282355873 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}]
12:19:15 INFO - TEST-START: test_click.py TestClick.test_container_element1499282355875 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:19:15 INFO - 1499282355876 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:19:15 INFO - 1499282355877 Marionette TRACE 360 <- [1,6,null,{}]
12:19:15 INFO - 1499282355880 Marionette DEBUG Closed connection 360
12:19:15 INFO - 1499282355880 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49806
12:19:15 INFO - 1499282355881 Marionette DEBUG Closed connection 361
12:19:15 INFO - 1499282355882 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49807
12:19:15 INFO - 1499282355883 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:15 INFO - 1499282355883 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:15 INFO - 1499282355885 Marionette DEBUG Register listener.js for window 12
12:19:15 INFO - 1499282355888 Marionette TRACE 362 <- [1,1,null,{"sessionId":"53f17efb-fab1-41eb-a5c3-5161d6ea7fa1","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:15 INFO - 1499282355889 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:19:15 INFO - 1499282355890 Marionette TRACE 362 <- [1,2,null,{}]
12:19:15 INFO - 1499282355892 Marionette DEBUG Closed connection 362
12:19:15 INFO - 1499282355892 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49808
12:19:15 INFO - 1499282355893 Marionette DEBUG Closed connection 363
12:19:15 INFO - 1499282355894 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49809
12:19:15 INFO - 1499282355896 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:15 INFO - 1499282355896 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:15 INFO - 1499282355898 Marionette DEBUG Register listener.js for window 12
12:19:15 INFO - 1499282355901 Marionette TRACE 364 <- [1,1,null,{"sessionId":"e81f50ba-4ac8-400b-a8b1-a32bf3d9764b","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:15 INFO - 1499282355902 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"}]
12:19:15 INFO - 1499282355904 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/test.html"
12:19:15 INFO - 1499282355907 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/test.html"
12:19:15 INFO - 1499282355907 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/test.html"
12:19:15 INFO - 1499282355933 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"
12:19:15 INFO - 1499282355939 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"
12:19:15 INFO - 1499282355943 Marionette TRACE 364 <- [1,2,null,{}]
12:19:15 INFO - 1499282355944 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:19:15 INFO - 1499282355953 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"82f6edf2-470b-4ebe-9b9a-39eb9536daeb","ELEMENT":"82f6edf2-470b-4ebe-9b9a-39eb9536daeb"}}]
12:19:15 INFO - 1499282355954 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"82f6edf2-470b-4ebe-9b9a-39eb9536daeb"}]
12:19:16 INFO - 1499282356191 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:16 INFO - 1499282356192 Marionette TRACE 364 <- [1,4,null,{}]
12:19:16 INFO - 1499282356210 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"82f6edf2-470b-4ebe-9b9a-39eb9536daeb","name":"selected"}]
12:19:16 INFO - 1499282356214 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:19:16 INFO - 1499282356217 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}]
12:19:16 INFO - TEST-END: test_click.py TestClick.test_container_element1499282356219 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:19:16 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 393ms
12:19:16 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:19:16 INFO - 1499282356244 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:19:16 INFO - 1499282356245 Marionette TRACE 364 <- [1,7,null,{}]
12:19:16 INFO - 1499282356248 Marionette DEBUG Closed connection 364
12:19:16 INFO - 1499282356249 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49810
12:19:16 INFO - 1499282356249 Marionette DEBUG Closed connection 365
12:19:16 INFO - 1499282356255 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49811
12:19:16 INFO - 1499282356256 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:16 INFO - 1499282356256 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356258 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356262 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8020ded7-c148-4d8a-ad15-8fb125bfe451","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356262 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:19:16 INFO - 1499282356263 Marionette TRACE 366 <- [1,2,null,{}]
12:19:16 INFO - 1499282356264 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:16 INFO - 1499282356264 Marionette TRACE 366 <- [1,3,null,{}]
12:19:16 INFO - 1499282356265 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:16 INFO - 1499282356265 Marionette TRACE 366 <- [1,4,null,{}]
12:19:16 INFO - 1499282356267 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}]
12:19:16 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499282356270 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:19:16 INFO - 1499282356271 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:19:16 INFO - 1499282356273 Marionette TRACE 366 <- [1,6,null,{}]
12:19:16 INFO - 1499282356275 Marionette DEBUG Closed connection 366
12:19:16 INFO - 1499282356276 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49812
12:19:16 INFO - 1499282356277 Marionette DEBUG Closed connection 367
12:19:16 INFO - 1499282356278 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49813
12:19:16 INFO - 1499282356278 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:16 INFO - 1499282356279 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356281 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356283 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cd43cf42-704e-416e-bb46-725fb1e7a8cc","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356284 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:19:16 INFO - 1499282356285 Marionette TRACE 368 <- [1,2,null,{}]
12:19:16 INFO - 1499282356287 Marionette DEBUG Closed connection 368
12:19:16 INFO - 1499282356288 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49814
12:19:16 INFO - 1499282356288 Marionette DEBUG Closed connection 369
12:19:16 INFO - 1499282356289 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49815
12:19:16 INFO - 1499282356290 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:16 INFO - 1499282356291 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356293 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356295 Marionette TRACE 370 <- [1,1,null,{"sessionId":"f6fc72e1-c352-45b0-93cb-5f51fc09a009","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356297 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"}]
12:19:16 INFO - 1499282356298 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"
12:19:16 INFO - 1499282356300 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"
12:19:16 INFO - 1499282356301 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"
12:19:16 INFO - 1499282356317 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"
12:19:16 INFO - 1499282356319 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"
12:19:16 INFO - 1499282356322 Marionette TRACE 370 <- [1,2,null,{}]
12:19:16 INFO - 1499282356323 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:19:16 INFO - 1499282356325 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e7b1de14-3eeb-4e69-8ff1-51dcd3dd7fc6","ELEMENT":"e7b1de14-3eeb-4e69-8ff1-51dcd3dd7fc6"}}]
12:19:16 INFO - 1499282356326 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"e7b1de14-3eeb-4e69-8ff1-51dcd3dd7fc6"}]
12:19:16 INFO - 1499282356577 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:16 INFO - 1499282356578 Marionette TRACE 370 <- [1,4,null,{}]
12:19:16 INFO - 1499282356579 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"e7b1de14-3eeb-4e69-8ff1-51dcd3dd7fc6","name":"selected"}]
12:19:16 INFO - 1499282356583 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:19:16 INFO - 1499282356585 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}]
12:19:16 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499282356587 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:19:16 INFO - 1499282356588 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:19:16 INFO - 1499282356589 Marionette TRACE 370 <- [1,7,null,{}]
12:19:16 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 344ms
12:19:16 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:19:16 INFO - 1499282356591 Marionette DEBUG Closed connection 370
12:19:16 INFO - 1499282356592 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49816
12:19:16 INFO - 1499282356593 Marionette DEBUG Closed connection 371
12:19:16 INFO - 1499282356593 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49817
12:19:16 INFO - 1499282356594 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:16 INFO - 1499282356595 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356597 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356600 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2f8ab711-d006-4a2b-9558-6362382774c2","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356601 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:19:16 INFO - 1499282356601 Marionette TRACE 372 <- [1,2,null,{}]
12:19:16 INFO - 1499282356602 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:16 INFO - 1499282356602 Marionette TRACE 372 <- [1,3,null,{}]
12:19:16 INFO - 1499282356603 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:16 INFO - 1499282356604 Marionette TRACE 372 <- [1,4,null,{}]
12:19:16 INFO - 1499282356605 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}]
12:19:16 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499282356608 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:19:16 INFO - 1499282356609 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:19:16 INFO - 1499282356610 Marionette TRACE 372 <- [1,6,null,{}]
12:19:16 INFO - 1499282356611 Marionette DEBUG Closed connection 372
12:19:16 INFO - 1499282356612 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49818
12:19:16 INFO - 1499282356613 Marionette DEBUG Closed connection 373
12:19:16 INFO - 1499282356613 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49819
12:19:16 INFO - 1499282356614 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:16 INFO - 1499282356615 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356617 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356619 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e9bf2abe-a178-4f3c-967e-cf8c98963d47","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356620 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:19:16 INFO - 1499282356621 Marionette TRACE 374 <- [1,2,null,{}]
12:19:16 INFO - 1499282356623 Marionette DEBUG Closed connection 374
12:19:16 INFO - 1499282356624 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49820
12:19:16 INFO - 1499282356624 Marionette DEBUG Closed connection 375
12:19:16 INFO - 1499282356625 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49821
12:19:16 INFO - 1499282356626 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:16 INFO - 1499282356626 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356628 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356631 Marionette TRACE 376 <- [1,1,null,{"sessionId":"9dc080a1-156b-4cca-a432-c4aa638abf58","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356632 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"}]
12:19:16 INFO - 1499282356634 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"
12:19:16 INFO - 1499282356636 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"
12:19:16 INFO - 1499282356637 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"
12:19:16 INFO - 1499282356652 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"
12:19:16 INFO - 1499282356655 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"
12:19:16 INFO - 1499282356658 Marionette TRACE 376 <- [1,2,null,{}]
12:19:16 INFO - 1499282356659 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:19:16 INFO - 1499282356667 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ee103413-8c26-484c-aa1e-a6320d1e7a42","ELEMENT":"ee103413-8c26-484c-aa1e-a6320d1e7a42"}}]
12:19:16 INFO - 1499282356668 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"ee103413-8c26-484c-aa1e-a6320d1e7a42"}]
12:19:16 INFO - 1499282356902 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:16 INFO - 1499282356903 Marionette TRACE 376 <- [1,4,null,{}]
12:19:16 INFO - 1499282356905 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}]
12:19:16 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499282356908 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:19:16 INFO - 1499282356908 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:19:16 INFO - 1499282356910 Marionette TRACE 376 <- [1,6,null,{}]
12:19:16 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 320ms
12:19:16 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:19:16 INFO - 1499282356911 Marionette DEBUG Closed connection 376
12:19:16 INFO - 1499282356912 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49822
12:19:16 INFO - 1499282356913 Marionette DEBUG Closed connection 377
12:19:16 INFO - 1499282356914 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49823
12:19:16 INFO - 1499282356915 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:16 INFO - 1499282356915 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356917 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356922 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4430cd26-e64d-4435-9ab7-5a8034a96e8f","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356923 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:19:16 INFO - 1499282356924 Marionette TRACE 378 <- [1,2,null,{}]
12:19:16 INFO - 1499282356924 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:16 INFO - 1499282356925 Marionette TRACE 378 <- [1,3,null,{}]
12:19:16 INFO - 1499282356926 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:16 INFO - 1499282356926 Marionette TRACE 378 <- [1,4,null,{}]
12:19:16 INFO - 1499282356928 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}]
12:19:16 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499282356930 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:19:16 INFO - 1499282356931 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:19:16 INFO - 1499282356932 Marionette TRACE 378 <- [1,6,null,{}]
12:19:16 INFO - 1499282356934 Marionette DEBUG Closed connection 378
12:19:16 INFO - 1499282356934 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49824
12:19:16 INFO - 1499282356935 Marionette DEBUG Closed connection 379
12:19:16 INFO - 1499282356936 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49825
12:19:16 INFO - 1499282356937 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:16 INFO - 1499282356937 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356939 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356942 Marionette TRACE 380 <- [1,1,null,{"sessionId":"5f492b5a-f5d8-4f50-8221-bfd2b7104ba7","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356943 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:19:16 INFO - 1499282356944 Marionette TRACE 380 <- [1,2,null,{}]
12:19:16 INFO - 1499282356945 Marionette DEBUG Closed connection 380
12:19:16 INFO - 1499282356946 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49826
12:19:16 INFO - 1499282356947 Marionette DEBUG Closed connection 381
12:19:16 INFO - 1499282356948 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49827
12:19:16 INFO - 1499282356948 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:16 INFO - 1499282356949 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:16 INFO - 1499282356951 Marionette DEBUG Register listener.js for window 12
12:19:16 INFO - 1499282356953 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2a395626-3bee-43a9-8d84-169dcd6fd93b","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:16 INFO - 1499282356954 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"}]
12:19:16 INFO - 1499282356956 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"
12:19:16 INFO - 1499282356957 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"
12:19:16 INFO - 1499282356958 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"
12:19:16 INFO - 1499282356972 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"
12:19:16 INFO - 1499282356975 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"
12:19:16 INFO - 1499282356978 Marionette TRACE 382 <- [1,2,null,{}]
12:19:16 INFO - 1499282356985 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:19:16 INFO - 1499282356987 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05acf390-7d9b-4c58-b513-31d133577bed","ELEMENT":"05acf390-7d9b-4c58-b513-31d133577bed"}}]
12:19:16 INFO - 1499282356990 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"05acf390-7d9b-4c58-b513-31d133577bed"}]
12:19:17 INFO - 1499282357234 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:17 INFO - 1499282357235 Marionette TRACE 382 <- [1,4,null,{}]
12:19:17 INFO - 1499282357237 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}]
12:19:17 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499282357240 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:19:17 INFO - 1499282357241 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:19:17 INFO - 1499282357242 Marionette TRACE 382 <- [1,6,null,{}]
12:19:17 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 332ms
12:19:17 INFO - TEST-START | test_click.py TestClick.test_input_file
12:19:17 INFO - 1499282357243 Marionette DEBUG Closed connection 382
12:19:17 INFO - 1499282357244 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49828
12:19:17 INFO - 1499282357245 Marionette DEBUG Closed connection 383
12:19:17 INFO - 1499282357246 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49829
12:19:17 INFO - 1499282357247 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:17 INFO - 1499282357248 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357250 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357252 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a5901ec9-d60e-4c62-9b07-c73614898c4b","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357253 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:19:17 INFO - 1499282357254 Marionette TRACE 384 <- [1,2,null,{}]
12:19:17 INFO - 1499282357254 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:17 INFO - 1499282357255 Marionette TRACE 384 <- [1,3,null,{}]
12:19:17 INFO - 1499282357256 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:17 INFO - 1499282357256 Marionette TRACE 384 <- [1,4,null,{}]
12:19:17 INFO - 1499282357258 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}]
12:19:17 INFO - TEST-START: test_click.py TestClick.test_input_file1499282357260 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:19:17 INFO - 1499282357261 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:19:17 INFO - 1499282357262 Marionette TRACE 384 <- [1,6,null,{}]
12:19:17 INFO - 1499282357264 Marionette DEBUG Closed connection 384
12:19:17 INFO - 1499282357264 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49830
12:19:17 INFO - 1499282357265 Marionette DEBUG Closed connection 385
12:19:17 INFO - 1499282357266 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49831
12:19:17 INFO - 1499282357267 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:17 INFO - 1499282357267 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357269 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357272 Marionette TRACE 386 <- [1,1,null,{"sessionId":"21bc28af-46bb-4e40-9676-16ad9eb28816","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357272 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:19:17 INFO - 1499282357273 Marionette TRACE 386 <- [1,2,null,{}]
12:19:17 INFO - 1499282357275 Marionette DEBUG Closed connection 386
12:19:17 INFO - 1499282357276 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49832
12:19:17 INFO - 1499282357276 Marionette DEBUG Closed connection 387
12:19:17 INFO - 1499282357277 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49833
12:19:17 INFO - 1499282357278 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:17 INFO - 1499282357279 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357280 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357283 Marionette TRACE 388 <- [1,1,null,{"sessionId":"3f36267c-d11b-422d-99dd-96b0e368a1cd","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357284 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:19:17 INFO - 1499282357285 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"
12:19:17 INFO - 1499282357287 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"
12:19:17 INFO - 1499282357288 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"
12:19:17 INFO - 1499282357303 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:17 INFO - 1499282357306 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:17 INFO - 1499282357309 Marionette TRACE 388 <- [1,2,null,{}]
12:19:17 INFO - 1499282357318 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:19:17 INFO - 1499282357320 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2269cabb-5156-4ead-a551-de90cc96bcab","ELEMENT":"2269cabb-5156-4ead-a551-de90cc96bcab"}}]
12:19:17 INFO - 1499282357321 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2269cabb-5156-4ead-a551-de90cc96bcab"}]
12:19:17 INFO - 1499282357346 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]
12:19:17 INFO - 1499282357351 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}]
12:19:17 INFO - TEST-END: test_click.py TestClick.test_input_file1499282357354 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:19:17 INFO - 1499282357362 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:19:17 INFO - 1499282357363 Marionette TRACE 388 <- [1,6,null,{}]
12:19:17 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 121ms
12:19:17 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:19:17 INFO - 1499282357365 Marionette DEBUG Closed connection 388
12:19:17 INFO - 1499282357366 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49834
12:19:17 INFO - 1499282357366 Marionette DEBUG Closed connection 389
12:19:17 INFO - 1499282357367 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49835
12:19:17 INFO - 1499282357368 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:17 INFO - 1499282357369 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357371 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357374 Marionette TRACE 390 <- [1,1,null,{"sessionId":"b8504cc0-b830-4139-9b3b-081a7be39968","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357375 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:19:17 INFO - 1499282357375 Marionette TRACE 390 <- [1,2,null,{}]
12:19:17 INFO - 1499282357376 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:17 INFO - 1499282357377 Marionette TRACE 390 <- [1,3,null,{}]
12:19:17 INFO - 1499282357377 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:17 INFO - 1499282357378 Marionette TRACE 390 <- [1,4,null,{}]
12:19:17 INFO - 1499282357380 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}]
12:19:17 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499282357382 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:19:17 INFO - 1499282357383 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:19:17 INFO - 1499282357384 Marionette TRACE 390 <- [1,6,null,{}]
12:19:17 INFO - 1499282357385 Marionette DEBUG Closed connection 390
12:19:17 INFO - 1499282357386 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49836
12:19:17 INFO - 1499282357386 Marionette DEBUG Closed connection 391
12:19:17 INFO - 1499282357387 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49837
12:19:17 INFO - 1499282357388 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:17 INFO - 1499282357388 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357390 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357393 Marionette TRACE 392 <- [1,1,null,{"sessionId":"2dcf8759-ae6d-4629-995e-a18f8136581f","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357394 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:19:17 INFO - 1499282357395 Marionette TRACE 392 <- [1,2,null,{}]
12:19:17 INFO - 1499282357397 Marionette DEBUG Closed connection 392
12:19:17 INFO - 1499282357397 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49838
12:19:17 INFO - 1499282357398 Marionette DEBUG Closed connection 393
12:19:17 INFO - 1499282357399 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49839
12:19:17 INFO - 1499282357400 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:17 INFO - 1499282357400 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357402 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357405 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a22134b0-45cd-454b-85da-e12c9fae2beb","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357406 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"}]
12:19:17 INFO - 1499282357407 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:17 INFO - 1499282357409 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:17 INFO - 1499282357409 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:17 INFO - 1499282357426 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"
12:19:17 INFO - 1499282357429 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"
12:19:17 INFO - 1499282357437 Marionette TRACE 394 <- [1,2,null,{}]
12:19:17 INFO - 1499282357444 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:19:17 INFO - 1499282357447 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"25bbe0e7-edb3-4f95-abb0-1ca0cbc10720","ELEMENT":"25bbe0e7-edb3-4f95-abb0-1ca0cbc10720"}}]
12:19:17 INFO - 1499282357459 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:19:17 INFO - 1499282357461 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7ec4196c-ca29-4021-992d-d681d41976ac","ELEMENT":"7ec4196c-ca29-4021-992d-d681d41976ac"}}]
12:19:17 INFO - 1499282357462 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"25bbe0e7-edb3-4f95-abb0-1ca0cbc10720"}]
12:19:17 INFO - 1499282357701 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:17 INFO - 1499282357702 Marionette TRACE 394 <- [1,5,null,{}]
12:19:17 INFO - 1499282357703 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"7ec4196c-ca29-4021-992d-d681d41976ac"}]
12:19:17 INFO - 1499282357717 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]
12:19:17 INFO - 1499282357718 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:19:17 INFO - 1499282357721 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:19:17 INFO - 1499282357722 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}]
12:19:17 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499282357725 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:19:17 INFO - 1499282357725 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:19:17 INFO - 1499282357727 Marionette TRACE 394 <- [1,9,null,{}]
12:19:17 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 363ms
12:19:17 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:19:17 INFO - 1499282357729 Marionette DEBUG Closed connection 394
12:19:17 INFO - 1499282357730 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49840
12:19:17 INFO - 1499282357730 Marionette DEBUG Closed connection 395
12:19:17 INFO - 1499282357731 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49841
12:19:17 INFO - 1499282357732 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:17 INFO - 1499282357733 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357735 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357738 Marionette TRACE 396 <- [1,1,null,{"sessionId":"60a5b6d3-dccd-4779-98cf-99a308c0287e","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357739 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:19:17 INFO - 1499282357739 Marionette TRACE 396 <- [1,2,null,{}]
12:19:17 INFO - 1499282357740 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:17 INFO - 1499282357740 Marionette TRACE 396 <- [1,3,null,{}]
12:19:17 INFO - 1499282357741 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:17 INFO - 1499282357741 Marionette TRACE 396 <- [1,4,null,{}]
12:19:17 INFO - 1499282357743 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}]
12:19:17 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499282357745 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:19:17 INFO - 1499282357746 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:19:17 INFO - 1499282357747 Marionette TRACE 396 <- [1,6,null,{}]
12:19:17 INFO - 1499282357749 Marionette DEBUG Closed connection 396
12:19:17 INFO - 1499282357750 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49842
12:19:17 INFO - 1499282357751 Marionette DEBUG Closed connection 397
12:19:17 INFO - 1499282357751 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49843
12:19:17 INFO - 1499282357752 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:17 INFO - 1499282357753 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357755 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357757 Marionette TRACE 398 <- [1,1,null,{"sessionId":"0fdd71c3-1e61-4a20-a909-e406bb7f6340","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357758 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:19:17 INFO - 1499282357759 Marionette TRACE 398 <- [1,2,null,{}]
12:19:17 INFO - 1499282357762 Marionette DEBUG Closed connection 398
12:19:17 INFO - 1499282357762 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49844
12:19:17 INFO - 1499282357763 Marionette DEBUG Closed connection 399
12:19:17 INFO - 1499282357764 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49845
12:19:17 INFO - 1499282357765 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:17 INFO - 1499282357765 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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}
12:19:17 INFO - 1499282357767 Marionette DEBUG Register listener.js for window 12
12:19:17 INFO - 1499282357770 Marionette TRACE 400 <- [1,1,null,{"sessionId":"bb8bbfbd-ecca-4ae8-8985-113133297c07","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":3172,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1xwbfg.mozrunner","moz:accessibilityChecks":false}}]
12:19:17 INFO - 1499282357771 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"}]
12:19:17 INFO - 1499282357772 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"
12:19:17 INFO - 1499282357774 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"
12:19:17 INFO - 1499282357774 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"
12:19:17 INFO - 1499282357790 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"
12:19:17 INFO - 1499282357793 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"
12:19:17 INFO - 1499282357795 Marionette TRACE 400 <- [1,2,null,{}]
12:19:17 INFO - 1499282357804 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:19:17 INFO - 1499282357806 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a251fd85-33d7-486b-8639-30feddcc0ac4","ELEMENT":"a251fd85-33d7-486b-8639-30feddcc0ac4"}}]
12:19:17 INFO - 1499282357807 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a251fd85-33d7-486b-8639-30feddcc0ac4"}]
12:19:17 INFO - 1499282357828 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:19:17 INFO - 1499282357829 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a251fd85-33d7-486b-8639-30feddcc0ac4"}]
12:19:17 INFO - 1499282357843 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element