could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
21:43:17 INFO - 1500525797689 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}]
21:43:17 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500525797691 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:43:17 INFO - 1500525797692 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:43:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 122ms
21:43:17 INFO - 1500525797694 Marionette TRACE 352 <- [1,6,null,{}]
21:43:17 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:43:17 INFO - 1500525797695 Marionette DEBUG Closed connection 352
21:43:17 INFO - 1500525797697 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49761
21:43:17 INFO - 1500525797697 Marionette DEBUG Closed connection 353
21:43:17 INFO - 1500525797698 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49762
21:43:17 INFO - 1500525797699 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:17 INFO - 1500525797699 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:17 INFO - 1500525797701 Marionette DEBUG Register listener.js for window 15
21:43:17 INFO - 1500525797704 Marionette TRACE 354 <- [1,1,null,{"sessionId":"8fd98cdf-fe8b-4f1f-a1bb-f49183d6925f","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:17 INFO - 1500525797705 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:43:17 INFO - 1500525797706 Marionette TRACE 354 <- [1,2,null,{}]
21:43:17 INFO - 1500525797706 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:17 INFO - 1500525797707 Marionette TRACE 354 <- [1,3,null,{}]
21:43:17 INFO - 1500525797708 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:17 INFO - 1500525797708 Marionette TRACE 354 <- [1,4,null,{}]
21:43:17 INFO - 1500525797710 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}]
21:43:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500525797712 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:43:17 INFO - 1500525797713 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:43:17 INFO - 1500525797714 Marionette TRACE 354 <- [1,6,null,{}]
21:43:17 INFO - 1500525797716 Marionette DEBUG Closed connection 354
21:43:17 INFO - 1500525797716 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49763
21:43:17 INFO - 1500525797717 Marionette DEBUG Closed connection 355
21:43:17 INFO - 1500525797718 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49764
21:43:17 INFO - 1500525797718 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:17 INFO - 1500525797719 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:17 INFO - 1500525797721 Marionette DEBUG Register listener.js for window 15
21:43:17 INFO - 1500525797724 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f2126915-2317-4fcc-a89c-11128d2939a8","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:17 INFO - 1500525797725 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:43:17 INFO - 1500525797726 Marionette TRACE 356 <- [1,2,null,{}]
21:43:17 INFO - 1500525797728 Marionette DEBUG Closed connection 356
21:43:17 INFO - 1500525797728 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49765
21:43:17 INFO - 1500525797729 Marionette DEBUG Closed connection 357
21:43:17 INFO - 1500525797730 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49766
21:43:17 INFO - 1500525797730 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:17 INFO - 1500525797731 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:17 INFO - 1500525797733 Marionette DEBUG Register listener.js for window 15
21:43:17 INFO - 1500525797735 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0cb23b7f-82dd-4636-99fc-8c5f73fcccc4","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:17 INFO - 1500525797737 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
21:43:17 INFO - 1500525797738 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
21:43:17 INFO - 1500525797743 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
21:43:17 INFO - 1500525797743 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
21:43:17 INFO - 1500525797763 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
21:43:17 INFO - 1500525797764 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
21:43:17 INFO - 1500525797767 Marionette TRACE 358 <- [1,2,null,{}]
21:43:17 INFO - 1500525797774 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:43:17 INFO - 1500525797776 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"154326d2-a098-4323-bd9a-a3a32d30d5d3","ELEMENT":"154326d2-a098-4323-bd9a-a3a32d30d5d3"}}]
21:43:17 INFO - 1500525797777 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"154326d2-a098-4323-bd9a-a3a32d30d5d3"}]
21:43:17 INFO - 1500525797821 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
21:43:17 INFO - 1500525797826 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
21:43:17 INFO - 1500525797826 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
21:43:17 INFO - 1500525797845 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
21:43:17 INFO - 1500525797846 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
21:43:17 INFO - 1500525797848 Marionette TRACE 358 <- [1,4,null,{}]
21:43:17 INFO - 1500525797849 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:43:17 INFO - 1500525797851 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05a91727-dafb-48d9-8327-9e64e134bfaf","ELEMENT":"05a91727-dafb-48d9-8327-9e64e134bfaf"}}]
21:43:17 INFO - 1500525797857 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:43:17 INFO - 1500525797857 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:43:17 INFO - 1500525797859 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}]
21:43:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500525797861 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:43:17 INFO - 1500525797861 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:43:17 INFO - 1500525797863 Marionette TRACE 358 <- [1,8,null,{}]
21:43:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 168ms
21:43:17 INFO - TEST-START | test_click.py TestClick.test_container_element
21:43:17 INFO - 1500525797864 Marionette DEBUG Closed connection 358
21:43:17 INFO - 1500525797865 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49769
21:43:17 INFO - 1500525797866 Marionette DEBUG Closed connection 359
21:43:17 INFO - 1500525797866 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49770
21:43:17 INFO - 1500525797867 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:17 INFO - 1500525797867 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:17 INFO - 1500525797869 Marionette DEBUG Register listener.js for window 15
21:43:17 INFO - 1500525797873 Marionette TRACE 360 <- [1,1,null,{"sessionId":"671c5a1e-c2a1-4b44-8fbd-6cead2f8d563","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:17 INFO - 1500525797875 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:43:17 INFO - 1500525797875 Marionette TRACE 360 <- [1,2,null,{}]
21:43:17 INFO - 1500525797876 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:17 INFO - 1500525797876 Marionette TRACE 360 <- [1,3,null,{}]
21:43:17 INFO - 1500525797877 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:17 INFO - 1500525797877 Marionette TRACE 360 <- [1,4,null,{}]
21:43:17 INFO - 1500525797879 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}]
21:43:17 INFO - TEST-START: test_click.py TestClick.test_container_element1500525797881 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:43:17 INFO - 1500525797881 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:43:17 INFO - 1500525797882 Marionette TRACE 360 <- [1,6,null,{}]
21:43:17 INFO - 1500525797884 Marionette DEBUG Closed connection 360
21:43:17 INFO - 1500525797886 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49771
21:43:17 INFO - 1500525797886 Marionette DEBUG Closed connection 361
21:43:17 INFO - 1500525797887 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49772
21:43:17 INFO - 1500525797888 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:17 INFO - 1500525797889 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:17 INFO - 1500525797891 Marionette DEBUG Register listener.js for window 15
21:43:17 INFO - 1500525797894 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5281a9b2-6030-409e-a63b-4d41da356a38","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:17 INFO - 1500525797895 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:43:17 INFO - 1500525797896 Marionette TRACE 362 <- [1,2,null,{}]
21:43:17 INFO - 1500525797898 Marionette DEBUG Closed connection 362
21:43:17 INFO - 1500525797899 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49773
21:43:17 INFO - 1500525797899 Marionette DEBUG Closed connection 363
21:43:17 INFO - 1500525797900 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49774
21:43:17 INFO - 1500525797901 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:17 INFO - 1500525797901 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:17 INFO - 1500525797904 Marionette DEBUG Register listener.js for window 15
21:43:17 INFO - 1500525797907 Marionette TRACE 364 <- [1,1,null,{"sessionId":"81e7c0cd-d571-4622-a05a-dcc3ecd7f1be","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:17 INFO - 1500525797910 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"}]
21:43:17 INFO - 1500525797911 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
21:43:17 INFO - 1500525797913 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
21:43:17 INFO - 1500525797913 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
21:43:17 INFO - 1500525797934 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"
21:43:17 INFO - 1500525797937 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"
21:43:17 INFO - 1500525797940 Marionette TRACE 364 <- [1,2,null,{}]
21:43:17 INFO - 1500525797950 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:43:17 INFO - 1500525797957 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8aee328b-85cd-4754-9b1b-76c33f0ea3a5","ELEMENT":"8aee328b-85cd-4754-9b1b-76c33f0ea3a5"}}]
21:43:17 INFO - 1500525797965 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8aee328b-85cd-4754-9b1b-76c33f0ea3a5"}]
21:43:18 INFO - 1500525798203 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:18 INFO - 1500525798204 Marionette TRACE 364 <- [1,4,null,{}]
21:43:18 INFO - 1500525798205 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8aee328b-85cd-4754-9b1b-76c33f0ea3a5","name":"selected"}]
21:43:18 INFO - 1500525798209 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:43:18 INFO - 1500525798211 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}]
21:43:18 INFO - TEST-END: test_click.py TestClick.test_container_element1500525798213 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:43:18 INFO - 1500525798214 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:43:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 352ms
21:43:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:43:18 INFO - 1500525798215 Marionette TRACE 364 <- [1,7,null,{}]
21:43:18 INFO - 1500525798216 Marionette DEBUG Closed connection 364
21:43:18 INFO - 1500525798217 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49775
21:43:18 INFO - 1500525798218 Marionette DEBUG Closed connection 365
21:43:18 INFO - 1500525798219 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49776
21:43:18 INFO - 1500525798220 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:18 INFO - 1500525798220 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798222 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798225 Marionette TRACE 366 <- [1,1,null,{"sessionId":"5837f323-aeb1-40f8-992b-dfa5b3ab1233","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798227 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:43:18 INFO - 1500525798227 Marionette TRACE 366 <- [1,2,null,{}]
21:43:18 INFO - 1500525798228 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:18 INFO - 1500525798228 Marionette TRACE 366 <- [1,3,null,{}]
21:43:18 INFO - 1500525798229 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:18 INFO - 1500525798230 Marionette TRACE 366 <- [1,4,null,{}]
21:43:18 INFO - 1500525798232 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}]
21:43:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500525798234 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:43:18 INFO - 1500525798235 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:43:18 INFO - 1500525798236 Marionette TRACE 366 <- [1,6,null,{}]
21:43:18 INFO - 1500525798238 Marionette DEBUG Closed connection 366
21:43:18 INFO - 1500525798238 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49777
21:43:18 INFO - 1500525798239 Marionette DEBUG Closed connection 367
21:43:18 INFO - 1500525798240 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49778
21:43:18 INFO - 1500525798240 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:18 INFO - 1500525798241 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798243 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798246 Marionette TRACE 368 <- [1,1,null,{"sessionId":"a32c42ee-9e29-4694-9f1f-32cf50093e8d","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798248 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:43:18 INFO - 1500525798249 Marionette TRACE 368 <- [1,2,null,{}]
21:43:18 INFO - 1500525798250 Marionette DEBUG Closed connection 368
21:43:18 INFO - 1500525798251 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49779
21:43:18 INFO - 1500525798251 Marionette DEBUG Closed connection 369
21:43:18 INFO - 1500525798252 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49780
21:43:18 INFO - 1500525798253 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:18 INFO - 1500525798253 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798255 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798265 Marionette TRACE 370 <- [1,1,null,{"sessionId":"1e0719dd-f613-419c-b3e3-cd028c8ef2fe","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798267 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"}]
21:43:18 INFO - 1500525798268 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"
21:43:18 INFO - 1500525798269 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"
21:43:18 INFO - 1500525798270 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"
21:43:18 INFO - 1500525798279 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"
21:43:18 INFO - 1500525798282 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"
21:43:18 INFO - 1500525798285 Marionette TRACE 370 <- [1,2,null,{}]
21:43:18 INFO - 1500525798293 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:43:18 INFO - 1500525798295 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9d528b5c-6e18-41f6-965c-f5489065fd69","ELEMENT":"9d528b5c-6e18-41f6-965c-f5489065fd69"}}]
21:43:18 INFO - 1500525798296 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9d528b5c-6e18-41f6-965c-f5489065fd69"}]
21:43:18 INFO - 1500525798533 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:18 INFO - 1500525798534 Marionette TRACE 370 <- [1,4,null,{}]
21:43:18 INFO - 1500525798535 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9d528b5c-6e18-41f6-965c-f5489065fd69","name":"selected"}]
21:43:18 INFO - 1500525798539 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:43:18 INFO - 1500525798541 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}]
21:43:18 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500525798543 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:43:18 INFO - 1500525798544 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:43:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 330ms
21:43:18 INFO - 1500525798545 Marionette TRACE 370 <- [1,7,null,{}]
21:43:18 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:43:18 INFO - 1500525798547 Marionette DEBUG Closed connection 370
21:43:18 INFO - 1500525798548 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49781
21:43:18 INFO - 1500525798548 Marionette DEBUG Closed connection 371
21:43:18 INFO - 1500525798549 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49782
21:43:18 INFO - 1500525798550 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:18 INFO - 1500525798550 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798552 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798555 Marionette TRACE 372 <- [1,1,null,{"sessionId":"522d659c-79fe-4fcb-be66-c8ac986f6941","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798556 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:43:18 INFO - 1500525798557 Marionette TRACE 372 <- [1,2,null,{}]
21:43:18 INFO - 1500525798557 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:18 INFO - 1500525798558 Marionette TRACE 372 <- [1,3,null,{}]
21:43:18 INFO - 1500525798559 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:18 INFO - 1500525798559 Marionette TRACE 372 <- [1,4,null,{}]
21:43:18 INFO - 1500525798560 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}]
21:43:18 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500525798562 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:43:18 INFO - 1500525798563 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:43:18 INFO - 1500525798564 Marionette TRACE 372 <- [1,6,null,{}]
21:43:18 INFO - 1500525798565 Marionette DEBUG Closed connection 372
21:43:18 INFO - 1500525798566 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49783
21:43:18 INFO - 1500525798567 Marionette DEBUG Closed connection 373
21:43:18 INFO - 1500525798567 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49784
21:43:18 INFO - 1500525798568 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:18 INFO - 1500525798569 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798571 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798573 Marionette TRACE 374 <- [1,1,null,{"sessionId":"0ca4e185-767c-4c20-9d72-6ff9d2a10020","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798575 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:43:18 INFO - 1500525798576 Marionette TRACE 374 <- [1,2,null,{}]
21:43:18 INFO - 1500525798577 Marionette DEBUG Closed connection 374
21:43:18 INFO - 1500525798578 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49785
21:43:18 INFO - 1500525798578 Marionette DEBUG Closed connection 375
21:43:18 INFO - 1500525798579 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49786
21:43:18 INFO - 1500525798580 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:18 INFO - 1500525798580 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798582 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798585 Marionette TRACE 376 <- [1,1,null,{"sessionId":"beb6c916-1bfe-411a-bf38-e6131cc07323","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798587 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"}]
21:43:18 INFO - 1500525798588 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"
21:43:18 INFO - 1500525798589 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"
21:43:18 INFO - 1500525798590 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"
21:43:18 INFO - 1500525798599 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"
21:43:18 INFO - 1500525798602 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"
21:43:18 INFO - 1500525798610 Marionette TRACE 376 <- [1,2,null,{}]
21:43:18 INFO - 1500525798612 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:43:18 INFO - 1500525798614 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"806ae84c-f537-47a1-8684-8e3afde34662","ELEMENT":"806ae84c-f537-47a1-8684-8e3afde34662"}}]
21:43:18 INFO - 1500525798615 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"806ae84c-f537-47a1-8684-8e3afde34662"}]
21:43:18 INFO - 1500525798857 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:18 INFO - 1500525798857 Marionette TRACE 376 <- [1,4,null,{}]
21:43:18 INFO - 1500525798859 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}]
21:43:18 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500525798861 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:43:18 INFO - 1500525798862 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:43:18 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 317ms
21:43:18 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:43:18 INFO - 1500525798863 Marionette TRACE 376 <- [1,6,null,{}]
21:43:18 INFO - 1500525798864 Marionette DEBUG Closed connection 376
21:43:18 INFO - 1500525798865 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49787
21:43:18 INFO - 1500525798866 Marionette DEBUG Closed connection 377
21:43:18 INFO - 1500525798867 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49788
21:43:18 INFO - 1500525798868 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:18 INFO - 1500525798868 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798870 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798873 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6149b92b-2a39-4fdc-abf9-1bd846b64388","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798874 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:43:18 INFO - 1500525798875 Marionette TRACE 378 <- [1,2,null,{}]
21:43:18 INFO - 1500525798876 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:18 INFO - 1500525798876 Marionette TRACE 378 <- [1,3,null,{}]
21:43:18 INFO - 1500525798877 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:18 INFO - 1500525798877 Marionette TRACE 378 <- [1,4,null,{}]
21:43:18 INFO - 1500525798879 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}]
21:43:18 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500525798881 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:43:18 INFO - 1500525798882 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:43:18 INFO - 1500525798883 Marionette TRACE 378 <- [1,6,null,{}]
21:43:18 INFO - 1500525798884 Marionette DEBUG Closed connection 378
21:43:18 INFO - 1500525798885 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49789
21:43:18 INFO - 1500525798885 Marionette DEBUG Closed connection 379
21:43:18 INFO - 1500525798886 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49790
21:43:18 INFO - 1500525798887 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:18 INFO - 1500525798887 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798889 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798891 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c6e62b4c-783e-4822-9885-ba7adbef47c8","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798893 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:43:18 INFO - 1500525798894 Marionette TRACE 380 <- [1,2,null,{}]
21:43:18 INFO - 1500525798896 Marionette DEBUG Closed connection 380
21:43:18 INFO - 1500525798896 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49791
21:43:18 INFO - 1500525798897 Marionette DEBUG Closed connection 381
21:43:18 INFO - 1500525798897 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49792
21:43:18 INFO - 1500525798898 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:18 INFO - 1500525798899 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1500525798901 Marionette DEBUG Register listener.js for window 15
21:43:18 INFO - 1500525798903 Marionette TRACE 382 <- [1,1,null,{"sessionId":"33f81ae2-d76b-4112-91ef-6c8d451a8048","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1500525798905 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"}]
21:43:18 INFO - 1500525798906 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"
21:43:18 INFO - 1500525798908 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"
21:43:18 INFO - 1500525798908 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"
21:43:18 INFO - 1500525798917 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"
21:43:18 INFO - 1500525798925 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"
21:43:18 INFO - 1500525798928 Marionette TRACE 382 <- [1,2,null,{}]
21:43:18 INFO - 1500525798929 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:43:18 INFO - 1500525798931 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9ae3f34e-8fb4-4beb-8960-075fe86daefb","ELEMENT":"9ae3f34e-8fb4-4beb-8960-075fe86daefb"}}]
21:43:18 INFO - 1500525798932 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"9ae3f34e-8fb4-4beb-8960-075fe86daefb"}]
21:43:19 INFO - 1500525799173 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:19 INFO - 1500525799173 Marionette TRACE 382 <- [1,4,null,{}]
21:43:19 INFO - 1500525799175 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}]
21:43:19 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500525799177 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:43:19 INFO - 1500525799178 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:43:19 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
21:43:19 INFO - TEST-START | test_click.py TestClick.test_input_file
21:43:19 INFO - 1500525799179 Marionette TRACE 382 <- [1,6,null,{}]
21:43:19 INFO - 1500525799180 Marionette DEBUG Closed connection 382
21:43:19 INFO - 1500525799181 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49793
21:43:19 INFO - 1500525799182 Marionette DEBUG Closed connection 383
21:43:19 INFO - 1500525799183 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49794
21:43:19 INFO - 1500525799184 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:19 INFO - 1500525799184 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799186 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799188 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a56802c3-9bd4-4151-85d1-36720cca2dbc","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799190 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:43:19 INFO - 1500525799190 Marionette TRACE 384 <- [1,2,null,{}]
21:43:19 INFO - 1500525799191 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:19 INFO - 1500525799192 Marionette TRACE 384 <- [1,3,null,{}]
21:43:19 INFO - 1500525799192 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:19 INFO - 1500525799193 Marionette TRACE 384 <- [1,4,null,{}]
21:43:19 INFO - 1500525799194 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}]
21:43:19 INFO - TEST-START: test_click.py TestClick.test_input_file1500525799196 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:43:19 INFO - 1500525799197 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:43:19 INFO - 1500525799198 Marionette TRACE 384 <- [1,6,null,{}]
21:43:19 INFO - 1500525799200 Marionette DEBUG Closed connection 384
21:43:19 INFO - 1500525799200 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49795
21:43:19 INFO - 1500525799201 Marionette DEBUG Closed connection 385
21:43:19 INFO - 1500525799202 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49796
21:43:19 INFO - 1500525799202 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:19 INFO - 1500525799203 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799205 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799207 Marionette TRACE 386 <- [1,1,null,{"sessionId":"3815e036-adc9-4e71-96a9-8d913c1ae4e5","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799209 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:43:19 INFO - 1500525799210 Marionette TRACE 386 <- [1,2,null,{}]
21:43:19 INFO - 1500525799212 Marionette DEBUG Closed connection 386
21:43:19 INFO - 1500525799212 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49797
21:43:19 INFO - 1500525799213 Marionette DEBUG Closed connection 387
21:43:19 INFO - 1500525799213 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49798
21:43:19 INFO - 1500525799214 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:19 INFO - 1500525799215 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799217 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799219 Marionette TRACE 388 <- [1,1,null,{"sessionId":"37d047d1-8918-4b30-a3a4-c45e82d4df5a","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799221 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:43:19 INFO - 1500525799222 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"
21:43:19 INFO - 1500525799224 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"
21:43:19 INFO - 1500525799224 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"
21:43:19 INFO - 1500525799234 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:19 INFO - 1500525799242 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:19 INFO - 1500525799245 Marionette TRACE 388 <- [1,2,null,{}]
21:43:19 INFO - 1500525799246 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:43:19 INFO - 1500525799248 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6235aff4-425a-4352-b91a-d4fe1f2c1092","ELEMENT":"6235aff4-425a-4352-b91a-d4fe1f2c1092"}}]
21:43:19 INFO - 1500525799249 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6235aff4-425a-4352-b91a-d4fe1f2c1092"}]
21:43:19 INFO - 1500525799287 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
21:43:19 INFO - 1500525799289 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}]
21:43:19 INFO - TEST-END: test_click.py TestClick.test_input_file1500525799291 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:43:19 INFO - 1500525799292 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:43:19 INFO - 1500525799293 Marionette TRACE 388 <- [1,6,null,{}]
21:43:19 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 114ms
21:43:19 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:43:19 INFO - 1500525799294 Marionette DEBUG Closed connection 388
21:43:19 INFO - 1500525799295 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49799
21:43:19 INFO - 1500525799297 Marionette DEBUG Closed connection 389
21:43:19 INFO - 1500525799297 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49800
21:43:19 INFO - 1500525799298 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:19 INFO - 1500525799298 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799300 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799303 Marionette TRACE 390 <- [1,1,null,{"sessionId":"344002bb-289e-4b7f-a08b-10c6f4457138","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799305 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:43:19 INFO - 1500525799305 Marionette TRACE 390 <- [1,2,null,{}]
21:43:19 INFO - 1500525799306 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:19 INFO - 1500525799307 Marionette TRACE 390 <- [1,3,null,{}]
21:43:19 INFO - 1500525799307 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:19 INFO - 1500525799308 Marionette TRACE 390 <- [1,4,null,{}]
21:43:19 INFO - 1500525799309 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}]
21:43:19 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500525799311 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:43:19 INFO - 1500525799312 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:43:19 INFO - 1500525799313 Marionette TRACE 390 <- [1,6,null,{}]
21:43:19 INFO - 1500525799315 Marionette DEBUG Closed connection 390
21:43:19 INFO - 1500525799315 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49801
21:43:19 INFO - 1500525799316 Marionette DEBUG Closed connection 391
21:43:19 INFO - 1500525799317 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49802
21:43:19 INFO - 1500525799318 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:19 INFO - 1500525799318 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799320 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799323 Marionette TRACE 392 <- [1,1,null,{"sessionId":"c7bd224c-0b96-4722-a788-5cc366a282e6","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799324 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:43:19 INFO - 1500525799326 Marionette TRACE 392 <- [1,2,null,{}]
21:43:19 INFO - 1500525799327 Marionette DEBUG Closed connection 392
21:43:19 INFO - 1500525799328 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49803
21:43:19 INFO - 1500525799328 Marionette DEBUG Closed connection 393
21:43:19 INFO - 1500525799329 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49804
21:43:19 INFO - 1500525799330 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:19 INFO - 1500525799330 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799332 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799335 Marionette TRACE 394 <- [1,1,null,{"sessionId":"d853ce88-75cb-4ef1-943a-cafe4c90a109","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799336 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"}]
21:43:19 INFO - 1500525799338 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:19 INFO - 1500525799339 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:19 INFO - 1500525799339 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:19 INFO - 1500525799349 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"
21:43:19 INFO - 1500525799352 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"
21:43:19 INFO - 1500525799360 Marionette TRACE 394 <- [1,2,null,{}]
21:43:19 INFO - 1500525799361 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:43:19 INFO - 1500525799363 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e7288042-e15e-4760-86b3-506774b6e5d4","ELEMENT":"e7288042-e15e-4760-86b3-506774b6e5d4"}}]
21:43:19 INFO - 1500525799364 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:43:19 INFO - 1500525799365 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9111263b-ea26-440f-a0a7-d228254bb32e","ELEMENT":"9111263b-ea26-440f-a0a7-d228254bb32e"}}]
21:43:19 INFO - 1500525799366 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e7288042-e15e-4760-86b3-506774b6e5d4"}]
21:43:19 INFO - 1500525799601 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:19 INFO - 1500525799601 Marionette TRACE 394 <- [1,5,null,{}]
21:43:19 INFO - 1500525799603 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9111263b-ea26-440f-a0a7-d228254bb32e"}]
21:43:19 INFO - 1500525799616 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
21:43:19 INFO - 1500525799617 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
21:43:19 INFO - 1500525799620 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:43:19 INFO - 1500525799621 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}]
21:43:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500525799623 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:43:19 INFO - 1500525799624 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:43:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 332ms
21:43:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:43:19 INFO - 1500525799625 Marionette TRACE 394 <- [1,9,null,{}]
21:43:19 INFO - 1500525799626 Marionette DEBUG Closed connection 394
21:43:19 INFO - 1500525799627 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49805
21:43:19 INFO - 1500525799628 Marionette DEBUG Closed connection 395
21:43:19 INFO - 1500525799629 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49806
21:43:19 INFO - 1500525799630 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:19 INFO - 1500525799636 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799638 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799641 Marionette TRACE 396 <- [1,1,null,{"sessionId":"f6d20055-28b6-468d-8795-d3e04135d23c","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799643 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:43:19 INFO - 1500525799643 Marionette TRACE 396 <- [1,2,null,{}]
21:43:19 INFO - 1500525799644 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:19 INFO - 1500525799644 Marionette TRACE 396 <- [1,3,null,{}]
21:43:19 INFO - 1500525799645 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:19 INFO - 1500525799645 Marionette TRACE 396 <- [1,4,null,{}]
21:43:19 INFO - 1500525799647 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}]
21:43:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500525799649 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:43:19 INFO - 1500525799650 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:43:19 INFO - 1500525799651 Marionette TRACE 396 <- [1,6,null,{}]
21:43:19 INFO - 1500525799652 Marionette DEBUG Closed connection 396
21:43:19 INFO - 1500525799653 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49807
21:43:19 INFO - 1500525799654 Marionette DEBUG Closed connection 397
21:43:19 INFO - 1500525799654 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49808
21:43:19 INFO - 1500525799655 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:19 INFO - 1500525799656 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799658 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799660 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4297da96-1014-4559-b2b6-653528fea4fe","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799662 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:43:19 INFO - 1500525799663 Marionette TRACE 398 <- [1,2,null,{}]
21:43:19 INFO - 1500525799665 Marionette DEBUG Closed connection 398
21:43:19 INFO - 1500525799665 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49809
21:43:19 INFO - 1500525799666 Marionette DEBUG Closed connection 399
21:43:19 INFO - 1500525799666 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49810
21:43:19 INFO - 1500525799667 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:19 INFO - 1500525799668 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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}
21:43:19 INFO - 1500525799670 Marionette DEBUG Register listener.js for window 15
21:43:19 INFO - 1500525799674 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e0869df9-68a1-4ded-b395-80a899c39005","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":3540,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmponx03a.mozrunner","moz:accessibilityChecks":false}}]
21:43:19 INFO - 1500525799676 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"}]
21:43:19 INFO - 1500525799677 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"
21:43:19 INFO - 1500525799679 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"
21:43:19 INFO - 1500525799679 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"
21:43:19 INFO - 1500525799697 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"
21:43:19 INFO - 1500525799698 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"
21:43:19 INFO - 1500525799701 Marionette TRACE 400 <- [1,2,null,{}]
21:43:19 INFO - 1500525799707 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:43:19 INFO - 1500525799709 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8f952bbe-c690-4212-aba8-1e2b9d69c8db","ELEMENT":"8f952bbe-c690-4212-aba8-1e2b9d69c8db"}}]
21:43:19 INFO - 1500525799716 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8f952bbe-c690-4212-aba8-1e2b9d69c8db"}]
21:43:19 INFO - 1500525799739 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:43:19 INFO - 1500525799750 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"8f952bbe-c690-4212-aba8-1e2b9d69c8db"}]
21:43:19 INFO - 1500525799766 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element