could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
02:48:01 INFO - 1499075281666 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}]
02:48:01 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499075281669 Marionette TRACE 352 <- [1,5,null,{"value":null}]
02:48:01 INFO - 1499075281670 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
02:48:01 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 132ms
02:48:01 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
02:48:01 INFO - 1499075281671 Marionette TRACE 352 <- [1,6,null,{}]
02:48:01 INFO - 1499075281673 Marionette DEBUG Closed connection 352
02:48:01 INFO - 1499075281674 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49769
02:48:01 INFO - 1499075281675 Marionette DEBUG Closed connection 353
02:48:01 INFO - 1499075281675 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49770
02:48:01 INFO - 1499075281676 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:01 INFO - 1499075281677 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:01 INFO - 1499075281680 Marionette DEBUG Register listener.js for window 4294967297
02:48:01 INFO - 1499075281683 Marionette TRACE 354 <- [1,1,null,{"sessionId":"810e03b5-4866-4ed0-af4f-c0756c2d1bfc","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:01 INFO - 1499075281685 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
02:48:01 INFO - 1499075281685 Marionette TRACE 354 <- [1,2,null,{}]
02:48:01 INFO - 1499075281686 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:48:01 INFO - 1499075281687 Marionette TRACE 354 <- [1,3,null,{}]
02:48:01 INFO - 1499075281687 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
02:48:01 INFO - 1499075281688 Marionette TRACE 354 <- [1,4,null,{}]
02:48:01 INFO - 1499075281690 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}]
02:48:01 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499075281692 Marionette TRACE 354 <- [1,5,null,{"value":null}]
02:48:01 INFO - 1499075281693 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
02:48:01 INFO - 1499075281695 Marionette TRACE 354 <- [1,6,null,{}]
02:48:01 INFO - 1499075281697 Marionette DEBUG Closed connection 354
02:48:01 INFO - 1499075281697 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49771
02:48:01 INFO - 1499075281698 Marionette DEBUG Closed connection 355
02:48:01 INFO - 1499075281699 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49772
02:48:01 INFO - 1499075281700 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:01 INFO - 1499075281701 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:01 INFO - 1499075281704 Marionette DEBUG Register listener.js for window 4294967297
02:48:01 INFO - 1499075281707 Marionette TRACE 356 <- [1,1,null,{"sessionId":"2ff8207a-c04e-47fe-8c35-bbe2e712bcf2","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:01 INFO - 1499075281709 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
02:48:01 INFO - 1499075281710 Marionette TRACE 356 <- [1,2,null,{}]
02:48:01 INFO - 1499075281712 Marionette DEBUG Closed connection 356
02:48:01 INFO - 1499075281712 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49773
02:48:01 INFO - 1499075281713 Marionette DEBUG Closed connection 357
02:48:01 INFO - 1499075281714 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49774
02:48:01 INFO - 1499075281715 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:48:01 INFO - 1499075281715 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:01 INFO - 1499075281718 Marionette DEBUG Register listener.js for window 4294967297
02:48:01 INFO - 1499075281722 Marionette TRACE 358 <- [1,1,null,{"sessionId":"1f7528a7-3dc3-44a7-914f-506027dba122","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:01 INFO - 1499075281723 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
02:48:01 INFO - 1499075281725 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
02:48:01 INFO - 1499075281733 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
02:48:01 INFO - 1499075281734 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
02:48:01 INFO - 1499075281742 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
02:48:01 INFO - 1499075281748 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
02:48:01 INFO - 1499075281749 Marionette TRACE 358 <- [1,2,null,{}]
02:48:01 INFO - 1499075281755 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
02:48:01 INFO - 1499075281759 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"acce58c8-32fa-4afe-9fb7-d6a2f0b48481","ELEMENT":"acce58c8-32fa-4afe-9fb7-d6a2f0b48481"}}]
02:48:01 INFO - 1499075281760 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"acce58c8-32fa-4afe-9fb7-d6a2f0b48481"}]
02:48:01 INFO - 1499075281803 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
02:48:01 INFO - 1499075281810 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
02:48:01 INFO - 1499075281810 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
02:48:01 INFO - 1499075281820 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
02:48:01 INFO - 1499075281822 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
02:48:01 INFO - 1499075281826 Marionette TRACE 358 <- [1,4,null,{}]
02:48:01 INFO - 1499075281830 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
02:48:01 INFO - 1499075281832 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4402c639-2fde-4f55-9d92-69319d9f4960","ELEMENT":"4402c639-2fde-4f55-9d92-69319d9f4960"}}]
02:48:01 INFO - 1499075281838 Marionette TRACE 358 -> [0,6,"getTitle",{}]
02:48:01 INFO - 1499075281849 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
02:48:01 INFO - 1499075281852 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}]
02:48:01 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 198ms
02:48:01 INFO - TEST-START | test_click.py TestClick.test_container_element
02:48:01 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499075281867 Marionette TRACE 358 <- [1,7,null,{"value":null}]
02:48:01 INFO - 1499075281869 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
02:48:01 INFO - 1499075281870 Marionette TRACE 358 <- [1,8,null,{}]
02:48:01 INFO - 1499075281872 Marionette DEBUG Closed connection 358
02:48:01 INFO - 1499075281872 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49777
02:48:01 INFO - 1499075281873 Marionette DEBUG Closed connection 359
02:48:01 INFO - 1499075281874 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49778
02:48:01 INFO - 1499075281875 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:01 INFO - 1499075281876 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:01 INFO - 1499075281878 Marionette DEBUG Register listener.js for window 4294967297
02:48:01 INFO - 1499075281882 Marionette TRACE 360 <- [1,1,null,{"sessionId":"33fa7de8-2aae-49b8-a068-cbd1379bc4ab","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:01 INFO - 1499075281883 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
02:48:01 INFO - 1499075281884 Marionette TRACE 360 <- [1,2,null,{}]
02:48:01 INFO - 1499075281887 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:48:01 INFO - 1499075281887 Marionette TRACE 360 <- [1,3,null,{}]
02:48:01 INFO - 1499075281890 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
02:48:01 INFO - 1499075281890 Marionette TRACE 360 <- [1,4,null,{}]
02:48:01 INFO - 1499075281892 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}]
02:48:01 INFO - TEST-START: test_click.py TestClick.test_container_element1499075281895 Marionette TRACE 360 <- [1,5,null,{"value":null}]
02:48:01 INFO - 1499075281896 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
02:48:01 INFO - 1499075281897 Marionette TRACE 360 <- [1,6,null,{}]
02:48:01 INFO - 1499075281899 Marionette DEBUG Closed connection 360
02:48:01 INFO - 1499075281900 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49779
02:48:01 INFO - 1499075281901 Marionette DEBUG Closed connection 361
02:48:01 INFO - 1499075281901 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49780
02:48:01 INFO - 1499075281902 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:01 INFO - 1499075281903 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:01 INFO - 1499075281906 Marionette DEBUG Register listener.js for window 4294967297
02:48:01 INFO - 1499075281909 Marionette TRACE 362 <- [1,1,null,{"sessionId":"7e488879-faea-459d-afcd-583ad9d25c55","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:01 INFO - 1499075281910 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
02:48:01 INFO - 1499075281912 Marionette TRACE 362 <- [1,2,null,{}]
02:48:01 INFO - 1499075281914 Marionette DEBUG Closed connection 362
02:48:01 INFO - 1499075281915 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49781
02:48:01 INFO - 1499075281916 Marionette DEBUG Closed connection 363
02:48:01 INFO - 1499075281917 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49782
02:48:01 INFO - 1499075281918 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:48:01 INFO - 1499075281919 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:01 INFO - 1499075281921 Marionette DEBUG Register listener.js for window 4294967297
02:48:01 INFO - 1499075281925 Marionette TRACE 364 <- [1,1,null,{"sessionId":"35d931bd-d30f-4c56-b8d0-fc34a82b3544","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:01 INFO - 1499075281926 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"}]
02:48:01 INFO - 1499075281928 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
02:48:01 INFO - 1499075281934 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
02:48:01 INFO - 1499075281934 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
02:48:01 INFO - 1499075281941 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"
02:48:01 INFO - 1499075281945 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"
02:48:01 INFO - 1499075281955 Marionette TRACE 364 <- [1,2,null,{}]
02:48:01 INFO - 1499075281958 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:48:01 INFO - 1499075281962 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e022c737-39c5-478e-a3fc-85da62beb528","ELEMENT":"e022c737-39c5-478e-a3fc-85da62beb528"}}]
02:48:01 INFO - 1499075281963 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e022c737-39c5-478e-a3fc-85da62beb528"}]
02:48:02 INFO - 1499075282205 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:48:02 INFO - 1499075282207 Marionette TRACE 364 <- [1,4,null,{}]
02:48:02 INFO - 1499075282208 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e022c737-39c5-478e-a3fc-85da62beb528","name":"selected"}]
02:48:02 INFO - 1499075282213 Marionette TRACE 364 <- [1,5,null,{"value":true}]
02:48:02 INFO - 1499075282215 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}]
02:48:02 INFO - TEST-END: test_click.py TestClick.test_container_element1499075282217 Marionette TRACE 364 <- [1,6,null,{"value":null}]
02:48:02 INFO - 1499075282218 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
02:48:02 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 350ms
02:48:02 INFO - 1499075282220 Marionette TRACE 364 <- [1,7,null,{}]
02:48:02 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
02:48:02 INFO - 1499075282221 Marionette DEBUG Closed connection 364
02:48:02 INFO - 1499075282223 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49783
02:48:02 INFO - 1499075282223 Marionette DEBUG Closed connection 365
02:48:02 INFO - 1499075282224 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49784
02:48:02 INFO - 1499075282225 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:02 INFO - 1499075282226 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282228 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282232 Marionette TRACE 366 <- [1,1,null,{"sessionId":"79140543-93d7-4d1c-a806-fc061ff04085","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282233 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
02:48:02 INFO - 1499075282234 Marionette TRACE 366 <- [1,2,null,{}]
02:48:02 INFO - 1499075282235 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:48:02 INFO - 1499075282235 Marionette TRACE 366 <- [1,3,null,{}]
02:48:02 INFO - 1499075282236 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
02:48:02 INFO - 1499075282237 Marionette TRACE 366 <- [1,4,null,{}]
02:48:02 INFO - 1499075282238 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}]
02:48:02 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499075282241 Marionette TRACE 366 <- [1,5,null,{"value":null}]
02:48:02 INFO - 1499075282242 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
02:48:02 INFO - 1499075282244 Marionette TRACE 366 <- [1,6,null,{}]
02:48:02 INFO - 1499075282245 Marionette DEBUG Closed connection 366
02:48:02 INFO - 1499075282246 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49785
02:48:02 INFO - 1499075282247 Marionette DEBUG Closed connection 367
02:48:02 INFO - 1499075282247 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49786
02:48:02 INFO - 1499075282248 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:02 INFO - 1499075282249 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282252 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282255 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d294aa61-379c-4783-be57-9596d317beab","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282257 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
02:48:02 INFO - 1499075282258 Marionette TRACE 368 <- [1,2,null,{}]
02:48:02 INFO - 1499075282260 Marionette DEBUG Closed connection 368
02:48:02 INFO - 1499075282260 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49787
02:48:02 INFO - 1499075282261 Marionette DEBUG Closed connection 369
02:48:02 INFO - 1499075282262 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49788
02:48:02 INFO - 1499075282263 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:48:02 INFO - 1499075282263 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282266 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282270 Marionette TRACE 370 <- [1,1,null,{"sessionId":"e88c3a6f-5ef0-4582-940b-3af17d1e0315","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282271 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"}]
02:48:02 INFO - 1499075282273 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"
02:48:02 INFO - 1499075282275 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"
02:48:02 INFO - 1499075282275 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"
02:48:02 INFO - 1499075282281 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"
02:48:02 INFO - 1499075282285 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"
02:48:02 INFO - 1499075282291 Marionette TRACE 370 <- [1,2,null,{}]
02:48:02 INFO - 1499075282294 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:48:02 INFO - 1499075282297 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f545dc33-5ab1-4044-ad6e-02d3aa7ac6a0","ELEMENT":"f545dc33-5ab1-4044-ad6e-02d3aa7ac6a0"}}]
02:48:02 INFO - 1499075282298 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"f545dc33-5ab1-4044-ad6e-02d3aa7ac6a0"}]
02:48:02 INFO - 1499075282546 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:48:02 INFO - 1499075282547 Marionette TRACE 370 <- [1,4,null,{}]
02:48:02 INFO - 1499075282548 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"f545dc33-5ab1-4044-ad6e-02d3aa7ac6a0","name":"selected"}]
02:48:02 INFO - 1499075282552 Marionette TRACE 370 <- [1,5,null,{"value":true}]
02:48:02 INFO - 1499075282554 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}]
02:48:02 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499075282556 Marionette TRACE 370 <- [1,6,null,{"value":null}]
02:48:02 INFO - 1499075282557 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
02:48:02 INFO - 1499075282559 Marionette TRACE 370 <- [1,7,null,{}]
02:48:02 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 338ms
02:48:02 INFO - TEST-START | test_click.py TestClick.test_css_transforms
02:48:02 INFO - 1499075282560 Marionette DEBUG Closed connection 370
02:48:02 INFO - 1499075282561 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49789
02:48:02 INFO - 1499075282562 Marionette DEBUG Closed connection 371
02:48:02 INFO - 1499075282563 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49790
02:48:02 INFO - 1499075282564 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:02 INFO - 1499075282565 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282568 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282571 Marionette TRACE 372 <- [1,1,null,{"sessionId":"30fb664b-23e1-4d34-9504-6baad1514455","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282573 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
02:48:02 INFO - 1499075282573 Marionette TRACE 372 <- [1,2,null,{}]
02:48:02 INFO - 1499075282574 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:48:02 INFO - 1499075282574 Marionette TRACE 372 <- [1,3,null,{}]
02:48:02 INFO - 1499075282575 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
02:48:02 INFO - 1499075282576 Marionette TRACE 372 <- [1,4,null,{}]
02:48:02 INFO - 1499075282578 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}]
02:48:02 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499075282581 Marionette TRACE 372 <- [1,5,null,{"value":null}]
02:48:02 INFO - 1499075282582 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
02:48:02 INFO - 1499075282584 Marionette TRACE 372 <- [1,6,null,{}]
02:48:02 INFO - 1499075282585 Marionette DEBUG Closed connection 372
02:48:02 INFO - 1499075282586 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49791
02:48:02 INFO - 1499075282587 Marionette DEBUG Closed connection 373
02:48:02 INFO - 1499075282587 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49792
02:48:02 INFO - 1499075282588 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:02 INFO - 1499075282589 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282591 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282595 Marionette TRACE 374 <- [1,1,null,{"sessionId":"9fc8e5b4-5808-43a4-b3a9-3e0df453a2a4","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282596 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
02:48:02 INFO - 1499075282597 Marionette TRACE 374 <- [1,2,null,{}]
02:48:02 INFO - 1499075282598 Marionette DEBUG Closed connection 374
02:48:02 INFO - 1499075282599 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49793
02:48:02 INFO - 1499075282600 Marionette DEBUG Closed connection 375
02:48:02 INFO - 1499075282600 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49794
02:48:02 INFO - 1499075282602 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:48:02 INFO - 1499075282602 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282605 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282608 Marionette TRACE 376 <- [1,1,null,{"sessionId":"f87afd88-6e3e-4a62-845b-565bd4b9de92","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282609 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"}]
02:48:02 INFO - 1499075282611 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"
02:48:02 INFO - 1499075282613 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"
02:48:02 INFO - 1499075282613 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"
02:48:02 INFO - 1499075282620 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"
02:48:02 INFO - 1499075282623 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"
02:48:02 INFO - 1499075282630 Marionette TRACE 376 <- [1,2,null,{}]
02:48:02 INFO - 1499075282640 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
02:48:02 INFO - 1499075282642 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c8c76ed-b294-49f8-83ed-d5b24f0944ff","ELEMENT":"3c8c76ed-b294-49f8-83ed-d5b24f0944ff"}}]
02:48:02 INFO - 1499075282643 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"3c8c76ed-b294-49f8-83ed-d5b24f0944ff"}]
02:48:02 INFO - 1499075282888 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:48:02 INFO - 1499075282889 Marionette TRACE 376 <- [1,4,null,{}]
02:48:02 INFO - 1499075282892 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}]
02:48:02 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499075282896 Marionette TRACE 376 <- [1,5,null,{"value":null}]
02:48:02 INFO - 1499075282898 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
02:48:02 INFO - 1499075282901 Marionette TRACE 376 <- [1,6,null,{}]
02:48:02 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 342ms
02:48:02 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
02:48:02 INFO - 1499075282904 Marionette DEBUG Closed connection 376
02:48:02 INFO - 1499075282905 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49795
02:48:02 INFO - 1499075282906 Marionette DEBUG Closed connection 377
02:48:02 INFO - 1499075282907 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49796
02:48:02 INFO - 1499075282909 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:02 INFO - 1499075282912 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282916 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282921 Marionette TRACE 378 <- [1,1,null,{"sessionId":"dbb5df50-955c-495a-97de-1b190f96dcab","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282924 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
02:48:02 INFO - 1499075282925 Marionette TRACE 378 <- [1,2,null,{}]
02:48:02 INFO - 1499075282926 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:48:02 INFO - 1499075282928 Marionette TRACE 378 <- [1,3,null,{}]
02:48:02 INFO - 1499075282929 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
02:48:02 INFO - 1499075282930 Marionette TRACE 378 <- [1,4,null,{}]
02:48:02 INFO - 1499075282932 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}]
02:48:02 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499075282936 Marionette TRACE 378 <- [1,5,null,{"value":null}]
02:48:02 INFO - 1499075282937 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
02:48:02 INFO - 1499075282939 Marionette TRACE 378 <- [1,6,null,{}]
02:48:02 INFO - 1499075282942 Marionette DEBUG Closed connection 378
02:48:02 INFO - 1499075282943 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49797
02:48:02 INFO - 1499075282944 Marionette DEBUG Closed connection 379
02:48:02 INFO - 1499075282945 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49798
02:48:02 INFO - 1499075282947 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:02 INFO - 1499075282948 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282951 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282956 Marionette TRACE 380 <- [1,1,null,{"sessionId":"482a29f6-22a5-4b06-b99d-d89579de6f03","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282957 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
02:48:02 INFO - 1499075282959 Marionette TRACE 380 <- [1,2,null,{}]
02:48:02 INFO - 1499075282961 Marionette DEBUG Closed connection 380
02:48:02 INFO - 1499075282962 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49799
02:48:02 INFO - 1499075282963 Marionette DEBUG Closed connection 381
02:48:02 INFO - 1499075282964 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49800
02:48:02 INFO - 1499075282967 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:48:02 INFO - 1499075282969 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:02 INFO - 1499075282972 Marionette DEBUG Register listener.js for window 4294967297
02:48:02 INFO - 1499075282976 Marionette TRACE 382 <- [1,1,null,{"sessionId":"8066b845-095c-4c22-b478-e0a0c0f41122","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:02 INFO - 1499075282978 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"}]
02:48:02 INFO - 1499075282980 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"
02:48:02 INFO - 1499075282982 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"
02:48:02 INFO - 1499075282982 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"
02:48:02 INFO - 1499075282989 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"
02:48:02 INFO - 1499075282992 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"
02:48:03 INFO - 1499075283000 Marionette TRACE 382 <- [1,2,null,{}]
02:48:03 INFO - 1499075283009 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
02:48:03 INFO - 1499075283012 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"00aac429-00d4-48ed-a69f-b2b6e0cba7dc","ELEMENT":"00aac429-00d4-48ed-a69f-b2b6e0cba7dc"}}]
02:48:03 INFO - 1499075283013 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"00aac429-00d4-48ed-a69f-b2b6e0cba7dc"}]
02:48:03 INFO - 1499075283257 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:48:03 INFO - 1499075283258 Marionette TRACE 382 <- [1,4,null,{}]
02:48:03 INFO - 1499075283260 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}]
02:48:03 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499075283263 Marionette TRACE 382 <- [1,5,null,{"value":null}]
02:48:03 INFO - 1499075283265 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
02:48:03 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 365ms
02:48:03 INFO - TEST-START | test_click.py TestClick.test_input_file
02:48:03 INFO - 1499075283266 Marionette TRACE 382 <- [1,6,null,{}]
02:48:03 INFO - 1499075283268 Marionette DEBUG Closed connection 382
02:48:03 INFO - 1499075283269 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49801
02:48:03 INFO - 1499075283270 Marionette DEBUG Closed connection 383
02:48:03 INFO - 1499075283271 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49802
02:48:03 INFO - 1499075283272 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:03 INFO - 1499075283273 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283276 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283280 Marionette TRACE 384 <- [1,1,null,{"sessionId":"9795db35-e021-459a-95d6-855b6f227110","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283281 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
02:48:03 INFO - 1499075283282 Marionette TRACE 384 <- [1,2,null,{}]
02:48:03 INFO - 1499075283283 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:48:03 INFO - 1499075283284 Marionette TRACE 384 <- [1,3,null,{}]
02:48:03 INFO - 1499075283285 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
02:48:03 INFO - 1499075283286 Marionette TRACE 384 <- [1,4,null,{}]
02:48:03 INFO - 1499075283287 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}]
02:48:03 INFO - TEST-START: test_click.py TestClick.test_input_file1499075283291 Marionette TRACE 384 <- [1,5,null,{"value":null}]
02:48:03 INFO - 1499075283292 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
02:48:03 INFO - 1499075283294 Marionette TRACE 384 <- [1,6,null,{}]
02:48:03 INFO - 1499075283295 Marionette DEBUG Closed connection 384
02:48:03 INFO - 1499075283296 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49803
02:48:03 INFO - 1499075283297 Marionette DEBUG Closed connection 385
02:48:03 INFO - 1499075283298 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49804
02:48:03 INFO - 1499075283299 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:03 INFO - 1499075283300 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283303 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283307 Marionette TRACE 386 <- [1,1,null,{"sessionId":"020e19e0-bbae-48a6-a27e-570a761e6db6","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283309 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
02:48:03 INFO - 1499075283312 Marionette TRACE 386 <- [1,2,null,{}]
02:48:03 INFO - 1499075283314 Marionette DEBUG Closed connection 386
02:48:03 INFO - 1499075283317 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49805
02:48:03 INFO - 1499075283318 Marionette DEBUG Closed connection 387
02:48:03 INFO - 1499075283319 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49806
02:48:03 INFO - 1499075283320 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:48:03 INFO - 1499075283322 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283325 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283329 Marionette TRACE 388 <- [1,1,null,{"sessionId":"44b65c97-4a42-4ce9-9366-fe5c1305b3e0","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283331 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
02:48:03 INFO - 1499075283333 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"
02:48:03 INFO - 1499075283334 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"
02:48:03 INFO - 1499075283335 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"
02:48:03 INFO - 1499075283341 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:48:03 INFO - 1499075283346 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:48:03 INFO - 1499075283357 Marionette TRACE 388 <- [1,2,null,{}]
02:48:03 INFO - 1499075283360 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
02:48:03 INFO - 1499075283363 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"17adc018-2a4c-4229-ba3d-9136804c3748","ELEMENT":"17adc018-2a4c-4229-ba3d-9136804c3748"}}]
02:48:03 INFO - 1499075283364 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"17adc018-2a4c-4229-ba3d-9136804c3748"}]
02:48:03 INFO - 1499075283399 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
02:48:03 INFO - 1499075283401 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}]
02:48:03 INFO - TEST-END: test_click.py TestClick.test_input_file1499075283404 Marionette TRACE 388 <- [1,5,null,{"value":null}]
02:48:03 INFO - 1499075283405 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
02:48:03 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 141ms
02:48:03 INFO - TEST-START | test_click.py TestClick.test_obscured_element
02:48:03 INFO - 1499075283407 Marionette TRACE 388 <- [1,6,null,{}]
02:48:03 INFO - 1499075283409 Marionette DEBUG Closed connection 388
02:48:03 INFO - 1499075283409 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49807
02:48:03 INFO - 1499075283410 Marionette DEBUG Closed connection 389
02:48:03 INFO - 1499075283411 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49808
02:48:03 INFO - 1499075283412 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:03 INFO - 1499075283413 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283416 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283419 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8e5b32e3-c082-4504-9ae5-bda10b9dac34","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283420 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
02:48:03 INFO - 1499075283421 Marionette TRACE 390 <- [1,2,null,{}]
02:48:03 INFO - 1499075283422 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:48:03 INFO - 1499075283422 Marionette TRACE 390 <- [1,3,null,{}]
02:48:03 INFO - 1499075283423 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
02:48:03 INFO - 1499075283424 Marionette TRACE 390 <- [1,4,null,{}]
02:48:03 INFO - 1499075283425 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}]
02:48:03 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499075283429 Marionette TRACE 390 <- [1,5,null,{"value":null}]
02:48:03 INFO - 1499075283430 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
02:48:03 INFO - 1499075283432 Marionette TRACE 390 <- [1,6,null,{}]
02:48:03 INFO - 1499075283433 Marionette DEBUG Closed connection 390
02:48:03 INFO - 1499075283434 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49809
02:48:03 INFO - 1499075283435 Marionette DEBUG Closed connection 391
02:48:03 INFO - 1499075283435 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49810
02:48:03 INFO - 1499075283437 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:03 INFO - 1499075283437 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283440 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283444 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0eb090ad-0ec3-4de3-accd-6cc48a619474","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283445 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
02:48:03 INFO - 1499075283447 Marionette TRACE 392 <- [1,2,null,{}]
02:48:03 INFO - 1499075283448 Marionette DEBUG Closed connection 392
02:48:03 INFO - 1499075283449 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49811
02:48:03 INFO - 1499075283450 Marionette DEBUG Closed connection 393
02:48:03 INFO - 1499075283450 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49812
02:48:03 INFO - 1499075283452 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:48:03 INFO - 1499075283452 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283455 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283459 Marionette TRACE 394 <- [1,1,null,{"sessionId":"dde7a7c9-ca7e-4762-b7b1-940e55b1e93e","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283460 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"}]
02:48:03 INFO - 1499075283462 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:48:03 INFO - 1499075283464 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:48:03 INFO - 1499075283464 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:48:03 INFO - 1499075283471 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"
02:48:03 INFO - 1499075283474 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"
02:48:03 INFO - 1499075283480 Marionette TRACE 394 <- [1,2,null,{}]
02:48:03 INFO - 1499075283489 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
02:48:03 INFO - 1499075283492 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"655b07ef-ba84-4960-b458-e5d10c9108bf","ELEMENT":"655b07ef-ba84-4960-b458-e5d10c9108bf"}}]
02:48:03 INFO - 1499075283493 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
02:48:03 INFO - 1499075283495 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93c790f6-464d-41af-a8f9-f4be9091371d","ELEMENT":"93c790f6-464d-41af-a8f9-f4be9091371d"}}]
02:48:03 INFO - 1499075283496 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"655b07ef-ba84-4960-b458-e5d10c9108bf"}]
02:48:03 INFO - 1499075283736 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:48:03 INFO - 1499075283737 Marionette TRACE 394 <- [1,5,null,{}]
02:48:03 INFO - 1499075283738 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"93c790f6-464d-41af-a8f9-f4be9091371d"}]
02:48:03 INFO - 1499075283754 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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
02:48:03 INFO - 1499075283756 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
02:48:03 INFO - 1499075283759 Marionette TRACE 394 <- [1,7,null,{"value":false}]
02:48:03 INFO - 1499075283761 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}]
02:48:03 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499075283764 Marionette TRACE 394 <- [1,8,null,{"value":null}]
02:48:03 INFO - 1499075283765 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
02:48:03 INFO - 1499075283766 Marionette TRACE 394 <- [1,9,null,{}]
02:48:03 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 359ms
02:48:03 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
02:48:03 INFO - 1499075283768 Marionette DEBUG Closed connection 394
02:48:03 INFO - 1499075283769 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49813
02:48:03 INFO - 1499075283770 Marionette DEBUG Closed connection 395
02:48:03 INFO - 1499075283771 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49814
02:48:03 INFO - 1499075283772 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:03 INFO - 1499075283772 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283775 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283779 Marionette TRACE 396 <- [1,1,null,{"sessionId":"eddc5fbe-9c19-4a12-8484-4f81bf9450a8","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283781 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
02:48:03 INFO - 1499075283781 Marionette TRACE 396 <- [1,2,null,{}]
02:48:03 INFO - 1499075283782 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:48:03 INFO - 1499075283783 Marionette TRACE 396 <- [1,3,null,{}]
02:48:03 INFO - 1499075283784 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
02:48:03 INFO - 1499075283784 Marionette TRACE 396 <- [1,4,null,{}]
02:48:03 INFO - 1499075283786 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}]
02:48:03 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499075283789 Marionette TRACE 396 <- [1,5,null,{"value":null}]
02:48:03 INFO - 1499075283790 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
02:48:03 INFO - 1499075283791 Marionette TRACE 396 <- [1,6,null,{}]
02:48:03 INFO - 1499075283793 Marionette DEBUG Closed connection 396
02:48:03 INFO - 1499075283795 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49815
02:48:03 INFO - 1499075283796 Marionette DEBUG Closed connection 397
02:48:03 INFO - 1499075283796 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49816
02:48:03 INFO - 1499075283797 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:48:03 INFO - 1499075283799 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283803 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283808 Marionette TRACE 398 <- [1,1,null,{"sessionId":"28f94503-ce56-46d1-8997-dc5aa4e558b4","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283809 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
02:48:03 INFO - 1499075283810 Marionette TRACE 398 <- [1,2,null,{}]
02:48:03 INFO - 1499075283812 Marionette DEBUG Closed connection 398
02:48:03 INFO - 1499075283813 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49817
02:48:03 INFO - 1499075283814 Marionette DEBUG Closed connection 399
02:48:03 INFO - 1499075283815 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49818
02:48:03 INFO - 1499075283816 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:48:03 INFO - 1499075283816 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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}
02:48:03 INFO - 1499075283819 Marionette DEBUG Register listener.js for window 4294967297
02:48:03 INFO - 1499075283823 Marionette TRACE 400 <- [1,1,null,{"sessionId":"fd13253e-021e-445c-ac88-c1e3cc25045b","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":3104,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpy4r2hd.mozrunner","moz:accessibilityChecks":false}}]
02:48:03 INFO - 1499075283824 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"}]
02:48:03 INFO - 1499075283874 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"
02:48:03 INFO - 1499075283877 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"
02:48:03 INFO - 1499075283877 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"
02:48:03 INFO - 1499075283886 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"
02:48:03 INFO - 1499075283888 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"
02:48:03 INFO - 1499075283895 Marionette TRACE 400 <- [1,2,null,{}]
02:48:03 INFO - 1499075283899 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
02:48:03 INFO - 1499075283904 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"67856cf9-8cdf-4910-a50f-d25419d251b4","ELEMENT":"67856cf9-8cdf-4910-a50f-d25419d251b4"}}]
02:48:03 INFO - 1499075283906 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"67856cf9-8cdf-4910-a50f-d25419d251b4"}]
02:48:03 INFO - 1499075283932 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
02:48:03 INFO - 1499075283934 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"67856cf9-8cdf-4910-a50f-d25419d251b4"}]
02:48:03 INFO - 1499075283950 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element