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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:15:08 INFO - 1499426108808 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}]
04:15:08 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499426108810 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:15:08 INFO - 1499426108811 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:15:08 INFO - 1499426108812 Marionette TRACE 352 <- [1,6,null,{}]
04:15:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 122ms
04:15:08 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:15:08 INFO - 1499426108814 Marionette DEBUG Closed connection 352
04:15:08 INFO - 1499426108815 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49749
04:15:08 INFO - 1499426108816 Marionette DEBUG Closed connection 353
04:15:08 INFO - 1499426108816 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49750
04:15:08 INFO - 1499426108817 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:08 INFO - 1499426108818 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:08 INFO - 1499426108820 Marionette DEBUG Register listener.js for window 12
04:15:08 INFO - 1499426108822 Marionette TRACE 354 <- [1,1,null,{"sessionId":"2d136eb9-eaad-451a-98ec-dcb284b1fe3a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:08 INFO - 1499426108824 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:15:08 INFO - 1499426108824 Marionette TRACE 354 <- [1,2,null,{}]
04:15:08 INFO - 1499426108825 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:08 INFO - 1499426108825 Marionette TRACE 354 <- [1,3,null,{}]
04:15:08 INFO - 1499426108826 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:08 INFO - 1499426108826 Marionette TRACE 354 <- [1,4,null,{}]
04:15:08 INFO - 1499426108828 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}]
04:15:08 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499426108830 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:15:08 INFO - 1499426108831 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:15:08 INFO - 1499426108832 Marionette TRACE 354 <- [1,6,null,{}]
04:15:08 INFO - 1499426108834 Marionette DEBUG Closed connection 354
04:15:08 INFO - 1499426108834 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49751
04:15:08 INFO - 1499426108835 Marionette DEBUG Closed connection 355
04:15:08 INFO - 1499426108835 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49752
04:15:08 INFO - 1499426108836 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:08 INFO - 1499426108837 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:08 INFO - 1499426108839 Marionette DEBUG Register listener.js for window 12
04:15:08 INFO - 1499426108841 Marionette TRACE 356 <- [1,1,null,{"sessionId":"edf503b2-102e-4696-b116-fb4b1d3776c9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:08 INFO - 1499426108843 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:15:08 INFO - 1499426108844 Marionette TRACE 356 <- [1,2,null,{}]
04:15:08 INFO - 1499426108845 Marionette DEBUG Closed connection 356
04:15:08 INFO - 1499426108846 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49753
04:15:08 INFO - 1499426108846 Marionette DEBUG Closed connection 357
04:15:08 INFO - 1499426108847 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49754
04:15:08 INFO - 1499426108848 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:08 INFO - 1499426108849 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:08 INFO - 1499426108850 Marionette DEBUG Register listener.js for window 12
04:15:08 INFO - 1499426108853 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3d890838-63df-4b83-9757-047fbcad0744","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:08 INFO - 1499426108855 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49325/clicks.html"}]
04:15:08 INFO - 1499426108856 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/hidden.html"
04:15:08 INFO - 1499426108864 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/hidden.html"
04:15:08 INFO - 1499426108864 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/hidden.html"
04:15:08 INFO - 1499426108874 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49325/clicks.html"
04:15:08 INFO - 1499426108879 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49325/clicks.html"
04:15:08 INFO - 1499426108888 Marionette TRACE 358 <- [1,2,null,{}]
04:15:08 INFO - 1499426108889 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:15:08 INFO - 1499426108892 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d773b88b-c3a3-4705-866d-76002e38e5fe","ELEMENT":"d773b88b-c3a3-4705-866d-76002e38e5fe"}}]
04:15:08 INFO - 1499426108895 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"d773b88b-c3a3-4705-866d-76002e38e5fe"}]
04:15:08 INFO - 1499426108936 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/clicks.html"
04:15:08 INFO - 1499426108940 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/clicks.html"
04:15:08 INFO - 1499426108941 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/clicks.html"
04:15:08 INFO - 1499426108957 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49325/test.html"
04:15:08 INFO - 1499426108961 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49325/test.html"
04:15:08 INFO - 1499426108968 Marionette TRACE 358 <- [1,4,null,{}]
04:15:08 INFO - 1499426108969 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:15:08 INFO - 1499426108971 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c1480031-6867-414c-9d72-0c70d70400b1","ELEMENT":"c1480031-6867-414c-9d72-0c70d70400b1"}}]
04:15:08 INFO - 1499426108972 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:15:08 INFO - 1499426108972 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:15:08 INFO - 1499426108974 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}]
04:15:08 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499426108977 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:15:08 INFO - 1499426108979 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:15:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 167ms
04:15:08 INFO - 1499426108980 Marionette TRACE 358 <- [1,8,null,{}]
04:15:08 INFO - TEST-START | test_click.py TestClick.test_container_element
04:15:08 INFO - 1499426108982 Marionette DEBUG Closed connection 358
04:15:08 INFO - 1499426108983 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49757
04:15:08 INFO - 1499426108983 Marionette DEBUG Closed connection 359
04:15:08 INFO - 1499426108984 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49758
04:15:08 INFO - 1499426108985 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:08 INFO - 1499426108986 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:08 INFO - 1499426108988 Marionette DEBUG Register listener.js for window 12
04:15:08 INFO - 1499426108990 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4c4cb5f0-41ee-4739-abca-a26f254abbb3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:08 INFO - 1499426108992 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:15:08 INFO - 1499426108992 Marionette TRACE 360 <- [1,2,null,{}]
04:15:08 INFO - 1499426108994 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:08 INFO - 1499426108994 Marionette TRACE 360 <- [1,3,null,{}]
04:15:08 INFO - 1499426108995 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:08 INFO - 1499426108996 Marionette TRACE 360 <- [1,4,null,{}]
04:15:09 INFO - 1499426108997 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}]
04:15:09 INFO - TEST-START: test_click.py TestClick.test_container_element1499426108999 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:15:09 INFO - 1499426109000 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:15:09 INFO - 1499426109001 Marionette TRACE 360 <- [1,6,null,{}]
04:15:09 INFO - 1499426109003 Marionette DEBUG Closed connection 360
04:15:09 INFO - 1499426109003 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49759
04:15:09 INFO - 1499426109004 Marionette DEBUG Closed connection 361
04:15:09 INFO - 1499426109005 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49760
04:15:09 INFO - 1499426109005 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:09 INFO - 1499426109007 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109009 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109013 Marionette TRACE 362 <- [1,1,null,{"sessionId":"cf8c2099-da1c-4b69-9f48-3cd5d8ef1f6c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109015 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:15:09 INFO - 1499426109016 Marionette TRACE 362 <- [1,2,null,{}]
04:15:09 INFO - 1499426109018 Marionette DEBUG Closed connection 362
04:15:09 INFO - 1499426109019 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49761
04:15:09 INFO - 1499426109019 Marionette DEBUG Closed connection 363
04:15:09 INFO - 1499426109020 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49762
04:15:09 INFO - 1499426109021 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:09 INFO - 1499426109022 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109024 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109027 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1860d10f-d446-4199-a82d-eee8143301e8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109031 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"}]
04:15:09 INFO - 1499426109032 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/test.html"
04:15:09 INFO - 1499426109034 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/test.html"
04:15:09 INFO - 1499426109034 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/test.html"
04:15:09 INFO - 1499426109055 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"
04:15:09 INFO - 1499426109059 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"
04:15:09 INFO - 1499426109062 Marionette TRACE 364 <- [1,2,null,{}]
04:15:09 INFO - 1499426109075 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:15:09 INFO - 1499426109077 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"835e72a1-6632-4d74-9e71-69a99e92085e","ELEMENT":"835e72a1-6632-4d74-9e71-69a99e92085e"}}]
04:15:09 INFO - 1499426109079 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"835e72a1-6632-4d74-9e71-69a99e92085e"}]
04:15:09 INFO - 1499426109318 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:09 INFO - 1499426109319 Marionette TRACE 364 <- [1,4,null,{}]
04:15:09 INFO - 1499426109320 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"835e72a1-6632-4d74-9e71-69a99e92085e","name":"selected"}]
04:15:09 INFO - 1499426109324 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:15:09 INFO - 1499426109326 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}]
04:15:09 INFO - TEST-END: test_click.py TestClick.test_container_element1499426109328 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:15:09 INFO - 1499426109329 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:15:09 INFO - 1499426109330 Marionette TRACE 364 <- [1,7,null,{}]
04:15:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 349ms
04:15:09 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:15:09 INFO - 1499426109331 Marionette DEBUG Closed connection 364
04:15:09 INFO - 1499426109332 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49763
04:15:09 INFO - 1499426109333 Marionette DEBUG Closed connection 365
04:15:09 INFO - 1499426109333 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49764
04:15:09 INFO - 1499426109334 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:09 INFO - 1499426109335 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109337 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109339 Marionette TRACE 366 <- [1,1,null,{"sessionId":"00638aa2-de2f-4a0d-ac84-765add54b9d5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109341 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:15:09 INFO - 1499426109341 Marionette TRACE 366 <- [1,2,null,{}]
04:15:09 INFO - 1499426109342 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:09 INFO - 1499426109342 Marionette TRACE 366 <- [1,3,null,{}]
04:15:09 INFO - 1499426109343 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:09 INFO - 1499426109343 Marionette TRACE 366 <- [1,4,null,{}]
04:15:09 INFO - 1499426109345 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}]
04:15:09 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499426109347 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:15:09 INFO - 1499426109348 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:15:09 INFO - 1499426109349 Marionette TRACE 366 <- [1,6,null,{}]
04:15:09 INFO - 1499426109350 Marionette DEBUG Closed connection 366
04:15:09 INFO - 1499426109350 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49765
04:15:09 INFO - 1499426109351 Marionette DEBUG Closed connection 367
04:15:09 INFO - 1499426109352 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49766
04:15:09 INFO - 1499426109352 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:09 INFO - 1499426109353 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109355 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109357 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b6af0aed-60c8-47da-b4e5-3c37fb426644","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109359 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:15:09 INFO - 1499426109360 Marionette TRACE 368 <- [1,2,null,{}]
04:15:09 INFO - 1499426109361 Marionette DEBUG Closed connection 368
04:15:09 INFO - 1499426109362 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49767
04:15:09 INFO - 1499426109362 Marionette DEBUG Closed connection 369
04:15:09 INFO - 1499426109363 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49768
04:15:09 INFO - 1499426109364 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:09 INFO - 1499426109364 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109366 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109368 Marionette TRACE 370 <- [1,1,null,{"sessionId":"32bf297b-ba86-4544-bf0f-2e2150a1a7c4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109378 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"}]
04:15:09 INFO - 1499426109379 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"
04:15:09 INFO - 1499426109380 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"
04:15:09 INFO - 1499426109381 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"
04:15:09 INFO - 1499426109389 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"
04:15:09 INFO - 1499426109393 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"
04:15:09 INFO - 1499426109402 Marionette TRACE 370 <- [1,2,null,{}]
04:15:09 INFO - 1499426109403 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:15:09 INFO - 1499426109405 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a1aa72f-5a2e-43e5-ba9b-691b7f313c64","ELEMENT":"8a1aa72f-5a2e-43e5-ba9b-691b7f313c64"}}]
04:15:09 INFO - 1499426109406 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"8a1aa72f-5a2e-43e5-ba9b-691b7f313c64"}]
04:15:09 INFO - 1499426109644 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:09 INFO - 1499426109645 Marionette TRACE 370 <- [1,4,null,{}]
04:15:09 INFO - 1499426109646 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"8a1aa72f-5a2e-43e5-ba9b-691b7f313c64","name":"selected"}]
04:15:09 INFO - 1499426109649 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:15:09 INFO - 1499426109651 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}]
04:15:09 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499426109653 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:15:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 325ms
04:15:09 INFO - 1499426109654 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:15:09 INFO - 1499426109655 Marionette TRACE 370 <- [1,7,null,{}]
04:15:09 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:15:09 INFO - 1499426109657 Marionette DEBUG Closed connection 370
04:15:09 INFO - 1499426109658 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49769
04:15:09 INFO - 1499426109659 Marionette DEBUG Closed connection 371
04:15:09 INFO - 1499426109660 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49770
04:15:09 INFO - 1499426109660 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:09 INFO - 1499426109661 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109663 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109665 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d81304e2-29f5-452d-8b4a-85a0aef7b8ad","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109667 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:15:09 INFO - 1499426109667 Marionette TRACE 372 <- [1,2,null,{}]
04:15:09 INFO - 1499426109668 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:09 INFO - 1499426109669 Marionette TRACE 372 <- [1,3,null,{}]
04:15:09 INFO - 1499426109669 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:09 INFO - 1499426109670 Marionette TRACE 372 <- [1,4,null,{}]
04:15:09 INFO - 1499426109672 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}]
04:15:09 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499426109674 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:15:09 INFO - 1499426109675 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:15:09 INFO - 1499426109675 Marionette TRACE 372 <- [1,6,null,{}]
04:15:09 INFO - 1499426109677 Marionette DEBUG Closed connection 372
04:15:09 INFO - 1499426109677 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49771
04:15:09 INFO - 1499426109678 Marionette DEBUG Closed connection 373
04:15:09 INFO - 1499426109679 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49772
04:15:09 INFO - 1499426109680 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:09 INFO - 1499426109680 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109682 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109684 Marionette TRACE 374 <- [1,1,null,{"sessionId":"535f92b2-5c40-48c7-880a-18c24dd8045b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109686 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:15:09 INFO - 1499426109687 Marionette TRACE 374 <- [1,2,null,{}]
04:15:09 INFO - 1499426109688 Marionette DEBUG Closed connection 374
04:15:09 INFO - 1499426109688 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49773
04:15:09 INFO - 1499426109689 Marionette DEBUG Closed connection 375
04:15:09 INFO - 1499426109690 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49774
04:15:09 INFO - 1499426109691 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:09 INFO - 1499426109691 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109693 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109695 Marionette TRACE 376 <- [1,1,null,{"sessionId":"03f3ae5e-f464-4707-9b8c-0ea36bc7184b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109697 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"}]
04:15:09 INFO - 1499426109698 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"
04:15:09 INFO - 1499426109700 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"
04:15:09 INFO - 1499426109700 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"
04:15:09 INFO - 1499426109710 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"
04:15:09 INFO - 1499426109713 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"
04:15:09 INFO - 1499426109720 Marionette TRACE 376 <- [1,2,null,{}]
04:15:09 INFO - 1499426109722 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:15:09 INFO - 1499426109724 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d65e22e5-5355-4933-9dab-b2f319bf680b","ELEMENT":"d65e22e5-5355-4933-9dab-b2f319bf680b"}}]
04:15:09 INFO - 1499426109725 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d65e22e5-5355-4933-9dab-b2f319bf680b"}]
04:15:09 INFO - 1499426109966 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:09 INFO - 1499426109966 Marionette TRACE 376 <- [1,4,null,{}]
04:15:09 INFO - 1499426109968 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}]
04:15:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499426109970 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:15:09 INFO - 1499426109971 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:15:09 INFO - 1499426109972 Marionette TRACE 376 <- [1,6,null,{}]
04:15:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 315ms
04:15:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:15:09 INFO - 1499426109974 Marionette DEBUG Closed connection 376
04:15:09 INFO - 1499426109974 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49775
04:15:09 INFO - 1499426109975 Marionette DEBUG Closed connection 377
04:15:09 INFO - 1499426109976 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49776
04:15:09 INFO - 1499426109977 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:09 INFO - 1499426109977 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109979 Marionette DEBUG Register listener.js for window 12
04:15:09 INFO - 1499426109981 Marionette TRACE 378 <- [1,1,null,{"sessionId":"70881c5c-e555-4933-bae3-ca1360eb612c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:09 INFO - 1499426109983 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:15:09 INFO - 1499426109983 Marionette TRACE 378 <- [1,2,null,{}]
04:15:09 INFO - 1499426109984 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:09 INFO - 1499426109985 Marionette TRACE 378 <- [1,3,null,{}]
04:15:09 INFO - 1499426109985 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:09 INFO - 1499426109986 Marionette TRACE 378 <- [1,4,null,{}]
04:15:09 INFO - 1499426109987 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}]
04:15:09 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499426109989 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:15:09 INFO - 1499426109990 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:15:09 INFO - 1499426109991 Marionette TRACE 378 <- [1,6,null,{}]
04:15:09 INFO - 1499426109993 Marionette DEBUG Closed connection 378
04:15:09 INFO - 1499426109993 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49777
04:15:09 INFO - 1499426109994 Marionette DEBUG Closed connection 379
04:15:09 INFO - 1499426109994 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49778
04:15:09 INFO - 1499426109995 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:09 INFO - 1499426109996 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:09 INFO - 1499426109998 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110000 Marionette TRACE 380 <- [1,1,null,{"sessionId":"909161be-a64d-4c2b-90f9-9ceb825a65e4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110002 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:15:10 INFO - 1499426110003 Marionette TRACE 380 <- [1,2,null,{}]
04:15:10 INFO - 1499426110004 Marionette DEBUG Closed connection 380
04:15:10 INFO - 1499426110005 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49779
04:15:10 INFO - 1499426110005 Marionette DEBUG Closed connection 381
04:15:10 INFO - 1499426110006 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49780
04:15:10 INFO - 1499426110007 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:10 INFO - 1499426110007 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110009 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110012 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ec50d768-c7f6-4457-8fc7-be167b2643fe","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110014 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"}]
04:15:10 INFO - 1499426110016 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"
04:15:10 INFO - 1499426110017 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"
04:15:10 INFO - 1499426110018 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"
04:15:10 INFO - 1499426110027 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"
04:15:10 INFO - 1499426110035 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"
04:15:10 INFO - 1499426110038 Marionette TRACE 382 <- [1,2,null,{}]
04:15:10 INFO - 1499426110039 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:15:10 INFO - 1499426110041 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dc37611d-7a6f-46b6-af8d-062be2aec0ee","ELEMENT":"dc37611d-7a6f-46b6-af8d-062be2aec0ee"}}]
04:15:10 INFO - 1499426110042 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"dc37611d-7a6f-46b6-af8d-062be2aec0ee"}]
04:15:10 INFO - 1499426110282 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:10 INFO - 1499426110282 Marionette TRACE 382 <- [1,4,null,{}]
04:15:10 INFO - 1499426110284 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}]
04:15:10 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499426110286 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:15:10 INFO - 1499426110287 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:15:10 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
04:15:10 INFO - TEST-START | test_click.py TestClick.test_input_file
04:15:10 INFO - 1499426110288 Marionette TRACE 382 <- [1,6,null,{}]
04:15:10 INFO - 1499426110290 Marionette DEBUG Closed connection 382
04:15:10 INFO - 1499426110291 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49781
04:15:10 INFO - 1499426110292 Marionette DEBUG Closed connection 383
04:15:10 INFO - 1499426110293 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49782
04:15:10 INFO - 1499426110293 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:10 INFO - 1499426110294 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110296 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110299 Marionette TRACE 384 <- [1,1,null,{"sessionId":"0dc44cb9-9485-42d5-995d-cf2c3b01d0d4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110300 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:15:10 INFO - 1499426110301 Marionette TRACE 384 <- [1,2,null,{}]
04:15:10 INFO - 1499426110301 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:10 INFO - 1499426110302 Marionette TRACE 384 <- [1,3,null,{}]
04:15:10 INFO - 1499426110302 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:10 INFO - 1499426110303 Marionette TRACE 384 <- [1,4,null,{}]
04:15:10 INFO - 1499426110304 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}]
04:15:10 INFO - TEST-START: test_click.py TestClick.test_input_file1499426110306 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:15:10 INFO - 1499426110307 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:15:10 INFO - 1499426110308 Marionette TRACE 384 <- [1,6,null,{}]
04:15:10 INFO - 1499426110310 Marionette DEBUG Closed connection 384
04:15:10 INFO - 1499426110310 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49783
04:15:10 INFO - 1499426110311 Marionette DEBUG Closed connection 385
04:15:10 INFO - 1499426110312 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49784
04:15:10 INFO - 1499426110313 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:10 INFO - 1499426110313 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110315 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110317 Marionette TRACE 386 <- [1,1,null,{"sessionId":"24fe3b3a-acde-4ea4-b93a-afd136aa2f88","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110319 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:15:10 INFO - 1499426110320 Marionette TRACE 386 <- [1,2,null,{}]
04:15:10 INFO - 1499426110321 Marionette DEBUG Closed connection 386
04:15:10 INFO - 1499426110322 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49785
04:15:10 INFO - 1499426110323 Marionette DEBUG Closed connection 387
04:15:10 INFO - 1499426110323 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49786
04:15:10 INFO - 1499426110324 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:10 INFO - 1499426110325 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110326 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110329 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4f16d93e-40dc-482f-8e1b-73f9ac5653b6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110330 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:15:10 INFO - 1499426110332 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"
04:15:10 INFO - 1499426110334 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"
04:15:10 INFO - 1499426110334 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"
04:15:10 INFO - 1499426110343 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:10 INFO - 1499426110351 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:10 INFO - 1499426110354 Marionette TRACE 388 <- [1,2,null,{}]
04:15:10 INFO - 1499426110355 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:15:10 INFO - 1499426110357 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f6569ced-9a11-42db-a315-29d53a6d1b4e","ELEMENT":"f6569ced-9a11-42db-a315-29d53a6d1b4e"}}]
04:15:10 INFO - 1499426110358 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"f6569ced-9a11-42db-a315-29d53a6d1b4e"}]
04:15:10 INFO - 1499426110395 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:15:10 INFO - 1499426110397 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}]
04:15:10 INFO - TEST-END: test_click.py TestClick.test_input_file1499426110399 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:15:10 INFO - 1499426110399 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:15:10 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 112ms
04:15:10 INFO - 1499426110400 Marionette TRACE 388 <- [1,6,null,{}]
04:15:10 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:15:10 INFO - 1499426110402 Marionette DEBUG Closed connection 388
04:15:10 INFO - 1499426110404 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49787
04:15:10 INFO - 1499426110405 Marionette DEBUG Closed connection 389
04:15:10 INFO - 1499426110406 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49788
04:15:10 INFO - 1499426110407 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:10 INFO - 1499426110407 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110409 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110412 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9907706f-d21b-42a0-a827-19249ac9b62b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110413 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:15:10 INFO - 1499426110414 Marionette TRACE 390 <- [1,2,null,{}]
04:15:10 INFO - 1499426110415 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:10 INFO - 1499426110415 Marionette TRACE 390 <- [1,3,null,{}]
04:15:10 INFO - 1499426110416 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:10 INFO - 1499426110416 Marionette TRACE 390 <- [1,4,null,{}]
04:15:10 INFO - 1499426110418 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}]
04:15:10 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499426110420 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:15:10 INFO - 1499426110421 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:15:10 INFO - 1499426110421 Marionette TRACE 390 <- [1,6,null,{}]
04:15:10 INFO - 1499426110423 Marionette DEBUG Closed connection 390
04:15:10 INFO - 1499426110423 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49789
04:15:10 INFO - 1499426110424 Marionette DEBUG Closed connection 391
04:15:10 INFO - 1499426110425 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49790
04:15:10 INFO - 1499426110426 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:10 INFO - 1499426110426 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110428 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110430 Marionette TRACE 392 <- [1,1,null,{"sessionId":"1629e859-f2a3-4960-94cc-5d3474e4dbd6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110432 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:15:10 INFO - 1499426110433 Marionette TRACE 392 <- [1,2,null,{}]
04:15:10 INFO - 1499426110434 Marionette DEBUG Closed connection 392
04:15:10 INFO - 1499426110435 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49791
04:15:10 INFO - 1499426110436 Marionette DEBUG Closed connection 393
04:15:10 INFO - 1499426110436 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49792
04:15:10 INFO - 1499426110437 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:10 INFO - 1499426110438 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110439 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110442 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f2d23b90-0773-4747-8d91-f0b0d0881be5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110443 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"}]
04:15:10 INFO - 1499426110444 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:10 INFO - 1499426110446 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:10 INFO - 1499426110446 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:10 INFO - 1499426110455 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"
04:15:10 INFO - 1499426110458 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"
04:15:10 INFO - 1499426110461 Marionette TRACE 394 <- [1,2,null,{}]
04:15:10 INFO - 1499426110467 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:15:10 INFO - 1499426110469 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f7bf72b-1e5f-479d-a64a-b7f3a7efdf40","ELEMENT":"9f7bf72b-1e5f-479d-a64a-b7f3a7efdf40"}}]
04:15:10 INFO - 1499426110469 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:15:10 INFO - 1499426110471 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1677c1a7-d50b-41f8-87c1-9863272b41cb","ELEMENT":"1677c1a7-d50b-41f8-87c1-9863272b41cb"}}]
04:15:10 INFO - 1499426110472 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9f7bf72b-1e5f-479d-a64a-b7f3a7efdf40"}]
04:15:10 INFO - 1499426110708 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:10 INFO - 1499426110708 Marionette TRACE 394 <- [1,5,null,{}]
04:15:10 INFO - 1499426110709 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"1677c1a7-d50b-41f8-87c1-9863272b41cb"}]
04:15:10 INFO - 1499426110722 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:15:10 INFO - 1499426110724 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:15:10 INFO - 1499426110726 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:15:10 INFO - 1499426110727 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}]
04:15:10 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499426110729 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:15:10 INFO - 1499426110730 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:15:10 INFO - 1499426110731 Marionette TRACE 394 <- [1,9,null,{}]
04:15:10 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 329ms
04:15:10 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:15:10 INFO - 1499426110733 Marionette DEBUG Closed connection 394
04:15:10 INFO - 1499426110734 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49793
04:15:10 INFO - 1499426110734 Marionette DEBUG Closed connection 395
04:15:10 INFO - 1499426110735 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49794
04:15:10 INFO - 1499426110736 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:10 INFO - 1499426110736 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110738 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110747 Marionette TRACE 396 <- [1,1,null,{"sessionId":"91cc3704-471a-4752-85e4-fc876370c272","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110749 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:15:10 INFO - 1499426110749 Marionette TRACE 396 <- [1,2,null,{}]
04:15:10 INFO - 1499426110750 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:10 INFO - 1499426110751 Marionette TRACE 396 <- [1,3,null,{}]
04:15:10 INFO - 1499426110751 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:10 INFO - 1499426110752 Marionette TRACE 396 <- [1,4,null,{}]
04:15:10 INFO - 1499426110753 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}]
04:15:10 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499426110755 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:15:10 INFO - 1499426110756 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:15:10 INFO - 1499426110757 Marionette TRACE 396 <- [1,6,null,{}]
04:15:10 INFO - 1499426110759 Marionette DEBUG Closed connection 396
04:15:10 INFO - 1499426110759 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49795
04:15:10 INFO - 1499426110760 Marionette DEBUG Closed connection 397
04:15:10 INFO - 1499426110761 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49796
04:15:10 INFO - 1499426110761 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:10 INFO - 1499426110762 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110764 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110766 Marionette TRACE 398 <- [1,1,null,{"sessionId":"daaf7071-1188-48ad-9935-1ded5e3fa483","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110768 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:15:10 INFO - 1499426110768 Marionette TRACE 398 <- [1,2,null,{}]
04:15:10 INFO - 1499426110770 Marionette DEBUG Closed connection 398
04:15:10 INFO - 1499426110770 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49797
04:15:10 INFO - 1499426110771 Marionette DEBUG Closed connection 399
04:15:10 INFO - 1499426110772 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49798
04:15:10 INFO - 1499426110773 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:10 INFO - 1499426110773 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}
04:15:10 INFO - 1499426110775 Marionette DEBUG Register listener.js for window 12
04:15:10 INFO - 1499426110777 Marionette TRACE 400 <- [1,1,null,{"sessionId":"210e39ed-8b1a-4a91-854a-c1926a4048e8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2492,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-347.001\\AppData\\Local\\Temp\\tmpyi411n.mozrunner","moz:accessibilityChecks":false}}]
04:15:10 INFO - 1499426110781 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"}]
04:15:10 INFO - 1499426110782 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"
04:15:10 INFO - 1499426110784 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"
04:15:10 INFO - 1499426110784 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"
04:15:10 INFO - 1499426110801 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"
04:15:10 INFO - 1499426110802 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"
04:15:10 INFO - 1499426110804 Marionette TRACE 400 <- [1,2,null,{}]
04:15:10 INFO - 1499426110805 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:15:10 INFO - 1499426110808 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"583eb6c6-8fe9-49f6-a305-26a881b3670d","ELEMENT":"583eb6c6-8fe9-49f6-a305-26a881b3670d"}}]
04:15:10 INFO - 1499426110809 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"583eb6c6-8fe9-49f6-a305-26a881b3670d"}]
04:15:10 INFO - 1499426110835 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:15:10 INFO - 1499426110836 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"583eb6c6-8fe9-49f6-a305-26a881b3670d"}]
04:15:10 INFO - 1499426110852 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element