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]
15:59:25 INFO - 1499813965726 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}]
15:59:25 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499813965730 Marionette TRACE 352 <- [1,5,null,{"value":null}]
15:59:25 INFO - 1499813965731 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
15:59:25 INFO - 1499813965733 Marionette TRACE 352 <- [1,6,null,{}]
15:59:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 199ms
15:59:25 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:59:25 INFO - 1499813965735 Marionette DEBUG Closed connection 352
15:59:25 INFO - 1499813965737 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49822
15:59:25 INFO - 1499813965738 Marionette DEBUG Closed connection 353
15:59:25 INFO - 1499813965739 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49823
15:59:25 INFO - 1499813965740 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:25 INFO - 1499813965741 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:25 INFO - 1499813965744 Marionette DEBUG Register listener.js for window 4294967297
15:59:25 INFO - 1499813965749 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ae99d633-7a44-4a62-adf5-34ca11b2d2fb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:25 INFO - 1499813965750 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
15:59:25 INFO - 1499813965751 Marionette TRACE 354 <- [1,2,null,{}]
15:59:25 INFO - 1499813965752 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:59:25 INFO - 1499813965752 Marionette TRACE 354 <- [1,3,null,{}]
15:59:25 INFO - 1499813965754 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
15:59:25 INFO - 1499813965754 Marionette TRACE 354 <- [1,4,null,{}]
15:59:25 INFO - 1499813965756 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}]
15:59:25 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499813965760 Marionette TRACE 354 <- [1,5,null,{"value":null}]
15:59:25 INFO - 1499813965761 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
15:59:25 INFO - 1499813965763 Marionette TRACE 354 <- [1,6,null,{}]
15:59:25 INFO - 1499813965765 Marionette DEBUG Closed connection 354
15:59:25 INFO - 1499813965766 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49824
15:59:25 INFO - 1499813965767 Marionette DEBUG Closed connection 355
15:59:25 INFO - 1499813965768 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49825
15:59:25 INFO - 1499813965769 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:25 INFO - 1499813965770 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:25 INFO - 1499813965773 Marionette DEBUG Register listener.js for window 4294967297
15:59:25 INFO - 1499813965777 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9edaa7e5-a897-45d0-931b-fe52e9c0d510","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:25 INFO - 1499813965779 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
15:59:25 INFO - 1499813965781 Marionette TRACE 356 <- [1,2,null,{}]
15:59:25 INFO - 1499813965783 Marionette DEBUG Closed connection 356
15:59:25 INFO - 1499813965784 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49826
15:59:25 INFO - 1499813965785 Marionette DEBUG Closed connection 357
15:59:25 INFO - 1499813965786 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49827
15:59:25 INFO - 1499813965787 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:59:25 INFO - 1499813965788 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:25 INFO - 1499813965791 Marionette DEBUG Register listener.js for window 4294967297
15:59:25 INFO - 1499813965795 Marionette TRACE 358 <- [1,1,null,{"sessionId":"1b70962d-0219-4401-baea-ebb7988e5308","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:25 INFO - 1499813965797 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49370/clicks.html"}]
15:59:25 INFO - 1499813965799 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49370/hidden.html"
15:59:25 INFO - 1499813965809 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49370/hidden.html"
15:59:25 INFO - 1499813965809 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49370/hidden.html"
15:59:25 INFO - 1499813965818 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49370/clicks.html"
15:59:25 INFO - 1499813965823 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49370/clicks.html"
15:59:25 INFO - 1499813965830 Marionette TRACE 358 <- [1,2,null,{}]
15:59:25 INFO - 1499813965837 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:59:25 INFO - 1499813965841 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"03a2086a-8c0e-4a37-a89d-074aa8e23178","ELEMENT":"03a2086a-8c0e-4a37-a89d-074aa8e23178"}}]
15:59:25 INFO - 1499813965848 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"03a2086a-8c0e-4a37-a89d-074aa8e23178"}]
15:59:25 INFO - 1499813965898 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49370/clicks.html"
15:59:25 INFO - 1499813965908 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49370/clicks.html"
15:59:25 INFO - 1499813965909 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49370/clicks.html"
15:59:25 INFO - 1499813965917 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49370/test.html"
15:59:25 INFO - 1499813965921 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49370/test.html"
15:59:25 INFO - 1499813965931 Marionette TRACE 358 <- [1,4,null,{}]
15:59:25 INFO - 1499813965938 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:59:25 INFO - 1499813965948 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab313067-c474-4ed6-88fe-6cab7ba6d859","ELEMENT":"ab313067-c474-4ed6-88fe-6cab7ba6d859"}}]
15:59:25 INFO - 1499813965950 Marionette TRACE 358 -> [0,6,"getTitle",{}]
15:59:25 INFO - 1499813965951 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
15:59:25 INFO - 1499813965953 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}]
15:59:25 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499813965958 Marionette TRACE 358 <- [1,7,null,{"value":null}]
15:59:25 INFO - 1499813965959 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
15:59:25 INFO - 1499813965961 Marionette TRACE 358 <- [1,8,null,{}]
15:59:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 227ms
15:59:25 INFO - TEST-START | test_click.py TestClick.test_container_element
15:59:25 INFO - 1499813965963 Marionette DEBUG Closed connection 358
15:59:25 INFO - 1499813965964 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49830
15:59:25 INFO - 1499813965965 Marionette DEBUG Closed connection 359
15:59:25 INFO - 1499813965966 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49831
15:59:25 INFO - 1499813965967 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:25 INFO - 1499813965967 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:25 INFO - 1499813965971 Marionette DEBUG Register listener.js for window 4294967297
15:59:25 INFO - 1499813965975 Marionette TRACE 360 <- [1,1,null,{"sessionId":"639d36b4-954e-4317-aea2-82e76fb9a1c5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:25 INFO - 1499813965977 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
15:59:25 INFO - 1499813965977 Marionette TRACE 360 <- [1,2,null,{}]
15:59:25 INFO - 1499813965978 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:59:25 INFO - 1499813965979 Marionette TRACE 360 <- [1,3,null,{}]
15:59:25 INFO - 1499813965980 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
15:59:25 INFO - 1499813965980 Marionette TRACE 360 <- [1,4,null,{}]
15:59:25 INFO - 1499813965982 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}]
15:59:25 INFO - TEST-START: test_click.py TestClick.test_container_element1499813965986 Marionette TRACE 360 <- [1,5,null,{"value":null}]
15:59:25 INFO - 1499813965987 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
15:59:25 INFO - 1499813965989 Marionette TRACE 360 <- [1,6,null,{}]
15:59:25 INFO - 1499813965991 Marionette DEBUG Closed connection 360
15:59:25 INFO - 1499813965992 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49832
15:59:25 INFO - 1499813965993 Marionette DEBUG Closed connection 361
15:59:25 INFO - 1499813965994 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49833
15:59:25 INFO - 1499813965995 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:25 INFO - 1499813965996 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:26 INFO - 1499813965999 Marionette DEBUG Register listener.js for window 4294967297
15:59:26 INFO - 1499813966003 Marionette TRACE 362 <- [1,1,null,{"sessionId":"bb53a008-dc2a-4dc5-808a-c4efdc04e2f6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:26 INFO - 1499813966005 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
15:59:26 INFO - 1499813966007 Marionette TRACE 362 <- [1,2,null,{}]
15:59:26 INFO - 1499813966008 Marionette DEBUG Closed connection 362
15:59:26 INFO - 1499813966009 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49834
15:59:26 INFO - 1499813966010 Marionette DEBUG Closed connection 363
15:59:26 INFO - 1499813966011 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49835
15:59:26 INFO - 1499813966013 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:59:26 INFO - 1499813966014 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:26 INFO - 1499813966017 Marionette DEBUG Register listener.js for window 4294967297
15:59:26 INFO - 1499813966021 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1841e191-eea8-4749-b5d6-f0cbfe7c7130","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:26 INFO - 1499813966023 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"}]
15:59:26 INFO - 1499813966025 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49370/test.html"
15:59:26 INFO - 1499813966028 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49370/test.html"
15:59:26 INFO - 1499813966028 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49370/test.html"
15:59:26 INFO - 1499813966035 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"
15:59:26 INFO - 1499813966039 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"
15:59:26 INFO - 1499813966055 Marionette TRACE 364 <- [1,2,null,{}]
15:59:26 INFO - 1499813966069 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:59:26 INFO - 1499813966073 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f497320a-250a-4d0a-902c-9ce3f57d4cc5","ELEMENT":"f497320a-250a-4d0a-902c-9ce3f57d4cc5"}}]
15:59:26 INFO - 1499813966074 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"f497320a-250a-4d0a-902c-9ce3f57d4cc5"}]
15:59:26 INFO - 1499813966323 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:59:26 INFO - 1499813966324 Marionette TRACE 364 <- [1,4,null,{}]
15:59:26 INFO - 1499813966326 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"f497320a-250a-4d0a-902c-9ce3f57d4cc5","name":"selected"}]
15:59:26 INFO - 1499813966332 Marionette TRACE 364 <- [1,5,null,{"value":true}]
15:59:26 INFO - 1499813966335 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}]
15:59:26 INFO - TEST-END: test_click.py TestClick.test_container_element1499813966339 Marionette TRACE 364 <- [1,6,null,{"value":null}]
15:59:26 INFO - 1499813966340 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
15:59:26 INFO - 1499813966342 Marionette TRACE 364 <- [1,7,null,{}]
15:59:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 381ms
15:59:26 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:59:26 INFO - 1499813966344 Marionette DEBUG Closed connection 364
15:59:26 INFO - 1499813966345 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49836
15:59:26 INFO - 1499813966346 Marionette DEBUG Closed connection 365
15:59:26 INFO - 1499813966347 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49837
15:59:26 INFO - 1499813966348 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:26 INFO - 1499813966349 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:26 INFO - 1499813966353 Marionette DEBUG Register listener.js for window 4294967297
15:59:26 INFO - 1499813966368 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b81fc71c-0495-4754-ae77-cf99a3e707c5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:26 INFO - 1499813966370 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
15:59:26 INFO - 1499813966370 Marionette TRACE 366 <- [1,2,null,{}]
15:59:26 INFO - 1499813966371 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:59:26 INFO - 1499813966372 Marionette TRACE 366 <- [1,3,null,{}]
15:59:26 INFO - 1499813966373 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
15:59:26 INFO - 1499813966373 Marionette TRACE 366 <- [1,4,null,{}]
15:59:26 INFO - 1499813966375 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}]
15:59:26 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499813966379 Marionette TRACE 366 <- [1,5,null,{"value":null}]
15:59:26 INFO - 1499813966381 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
15:59:26 INFO - 1499813966383 Marionette TRACE 366 <- [1,6,null,{}]
15:59:26 INFO - 1499813966385 Marionette DEBUG Closed connection 366
15:59:26 INFO - 1499813966386 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49838
15:59:26 INFO - 1499813966386 Marionette DEBUG Closed connection 367
15:59:26 INFO - 1499813966387 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49839
15:59:26 INFO - 1499813966389 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:26 INFO - 1499813966389 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:26 INFO - 1499813966393 Marionette DEBUG Register listener.js for window 4294967297
15:59:26 INFO - 1499813966397 Marionette TRACE 368 <- [1,1,null,{"sessionId":"36726641-179a-47d8-b5f9-417a9218547a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:26 INFO - 1499813966399 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
15:59:26 INFO - 1499813966401 Marionette TRACE 368 <- [1,2,null,{}]
15:59:26 INFO - 1499813966403 Marionette DEBUG Closed connection 368
15:59:26 INFO - 1499813966403 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49840
15:59:26 INFO - 1499813966404 Marionette DEBUG Closed connection 369
15:59:26 INFO - 1499813966405 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49841
15:59:26 INFO - 1499813966406 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:59:26 INFO - 1499813966407 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:26 INFO - 1499813966411 Marionette DEBUG Register listener.js for window 4294967297
15:59:26 INFO - 1499813966415 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ec5861e3-3b0e-4b57-b738-adfcfe68fc33","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:26 INFO - 1499813966416 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"}]
15:59:26 INFO - 1499813966419 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"
15:59:26 INFO - 1499813966421 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"
15:59:26 INFO - 1499813966422 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"
15:59:26 INFO - 1499813966432 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"
15:59:26 INFO - 1499813966434 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"
15:59:26 INFO - 1499813966441 Marionette TRACE 370 <- [1,2,null,{}]
15:59:26 INFO - 1499813966454 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:59:26 INFO - 1499813966458 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1acb8b6f-203e-4f3b-b134-a7c7e6f67a86","ELEMENT":"1acb8b6f-203e-4f3b-b134-a7c7e6f67a86"}}]
15:59:26 INFO - 1499813966465 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1acb8b6f-203e-4f3b-b134-a7c7e6f67a86"}]
15:59:26 INFO - 1499813966716 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:59:26 INFO - 1499813966724 Marionette TRACE 370 <- [1,4,null,{}]
15:59:26 INFO - 1499813966731 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1acb8b6f-203e-4f3b-b134-a7c7e6f67a86","name":"selected"}]
15:59:26 INFO - 1499813966739 Marionette TRACE 370 <- [1,5,null,{"value":true}]
15:59:26 INFO - 1499813966748 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}]
15:59:26 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499813966753 Marionette TRACE 370 <- [1,6,null,{"value":null}]
15:59:26 INFO - 1499813966756 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
15:59:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 418ms
15:59:26 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:59:26 INFO - 1499813966760 Marionette TRACE 370 <- [1,7,null,{}]
15:59:26 INFO - 1499813966768 Marionette DEBUG Closed connection 370
15:59:26 INFO - 1499813966769 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49842
15:59:26 INFO - 1499813966777 Marionette DEBUG Closed connection 371
15:59:26 INFO - 1499813966779 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49843
15:59:26 INFO - 1499813966781 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:26 INFO - 1499813966785 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:26 INFO - 1499813966789 Marionette DEBUG Register listener.js for window 4294967297
15:59:26 INFO - 1499813966795 Marionette TRACE 372 <- [1,1,null,{"sessionId":"4552d200-e34b-4be0-a401-1faf6ec25cd8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:26 INFO - 1499813966798 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
15:59:26 INFO - 1499813966799 Marionette TRACE 372 <- [1,2,null,{}]
15:59:26 INFO - 1499813966801 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:59:26 INFO - 1499813966802 Marionette TRACE 372 <- [1,3,null,{}]
15:59:26 INFO - 1499813966803 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
15:59:26 INFO - 1499813966804 Marionette TRACE 372 <- [1,4,null,{}]
15:59:26 INFO - 1499813966807 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}]
15:59:26 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499813966854 Marionette TRACE 372 <- [1,5,null,{"value":null}]
15:59:26 INFO - 1499813966856 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
15:59:26 INFO - 1499813966859 Marionette TRACE 372 <- [1,6,null,{}]
15:59:26 INFO - 1499813966868 Marionette DEBUG Closed connection 372
15:59:26 INFO - 1499813966869 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49844
15:59:26 INFO - 1499813966870 Marionette DEBUG Closed connection 373
15:59:26 INFO - 1499813966872 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49845
15:59:26 INFO - 1499813966874 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:26 INFO - 1499813966875 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:26 INFO - 1499813966878 Marionette DEBUG Register listener.js for window 4294967297
15:59:26 INFO - 1499813966884 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ca9e37af-428f-4926-848f-ff92de23e685","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:26 INFO - 1499813966888 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
15:59:26 INFO - 1499813966892 Marionette TRACE 374 <- [1,2,null,{}]
15:59:26 INFO - 1499813966896 Marionette DEBUG Closed connection 374
15:59:26 INFO - 1499813966897 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49846
15:59:26 INFO - 1499813966898 Marionette DEBUG Closed connection 375
15:59:26 INFO - 1499813966899 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49847
15:59:26 INFO - 1499813966900 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:59:26 INFO - 1499813966902 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:26 INFO - 1499813966906 Marionette DEBUG Register listener.js for window 4294967297
15:59:26 INFO - 1499813966911 Marionette TRACE 376 <- [1,1,null,{"sessionId":"63033d68-39fc-435e-b5a3-471cb2b7d339","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:26 INFO - 1499813966913 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"}]
15:59:26 INFO - 1499813966915 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"
15:59:26 INFO - 1499813966918 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"
15:59:26 INFO - 1499813966918 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"
15:59:26 INFO - 1499813966934 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"
15:59:26 INFO - 1499813966936 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"
15:59:26 INFO - 1499813966951 Marionette TRACE 376 <- [1,2,null,{}]
15:59:26 INFO - 1499813966957 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:59:26 INFO - 1499813966968 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cc087683-3361-459c-ab10-d8ba26149f85","ELEMENT":"cc087683-3361-459c-ab10-d8ba26149f85"}}]
15:59:26 INFO - 1499813966970 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"cc087683-3361-459c-ab10-d8ba26149f85"}]
15:59:27 INFO - 1499813967224 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:59:27 INFO - 1499813967226 Marionette TRACE 376 <- [1,4,null,{}]
15:59:27 INFO - 1499813967228 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}]
15:59:27 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499813967233 Marionette TRACE 376 <- [1,5,null,{"value":null}]
15:59:27 INFO - 1499813967234 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
15:59:27 INFO - 1499813967236 Marionette TRACE 376 <- [1,6,null,{}]
15:59:27 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 476ms
15:59:27 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:59:27 INFO - 1499813967239 Marionette DEBUG Closed connection 376
15:59:27 INFO - 1499813967240 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49848
15:59:27 INFO - 1499813967241 Marionette DEBUG Closed connection 377
15:59:27 INFO - 1499813967242 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49849
15:59:27 INFO - 1499813967243 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:27 INFO - 1499813967244 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967248 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967254 Marionette TRACE 378 <- [1,1,null,{"sessionId":"dc29508e-ade9-4f25-9f6c-146a9f45dacf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967256 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
15:59:27 INFO - 1499813967257 Marionette TRACE 378 <- [1,2,null,{}]
15:59:27 INFO - 1499813967258 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:59:27 INFO - 1499813967259 Marionette TRACE 378 <- [1,3,null,{}]
15:59:27 INFO - 1499813967260 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
15:59:27 INFO - 1499813967260 Marionette TRACE 378 <- [1,4,null,{}]
15:59:27 INFO - 1499813967262 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}]
15:59:27 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499813967267 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:59:27 INFO - 1499813967268 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:59:27 INFO - 1499813967270 Marionette TRACE 378 <- [1,6,null,{}]
15:59:27 INFO - 1499813967272 Marionette DEBUG Closed connection 378
15:59:27 INFO - 1499813967273 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49850
15:59:27 INFO - 1499813967274 Marionette DEBUG Closed connection 379
15:59:27 INFO - 1499813967275 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49851
15:59:27 INFO - 1499813967277 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:27 INFO - 1499813967278 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967282 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967288 Marionette TRACE 380 <- [1,1,null,{"sessionId":"fc2673b6-ce3e-4315-9275-2326ec19a24e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967290 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
15:59:27 INFO - 1499813967293 Marionette TRACE 380 <- [1,2,null,{}]
15:59:27 INFO - 1499813967295 Marionette DEBUG Closed connection 380
15:59:27 INFO - 1499813967298 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49852
15:59:27 INFO - 1499813967299 Marionette DEBUG Closed connection 381
15:59:27 INFO - 1499813967300 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49853
15:59:27 INFO - 1499813967302 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:59:27 INFO - 1499813967303 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967306 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967311 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c8382328-2da2-463f-bae2-5f392292d9f7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967313 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"}]
15:59:27 INFO - 1499813967316 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"
15:59:27 INFO - 1499813967318 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"
15:59:27 INFO - 1499813967319 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"
15:59:27 INFO - 1499813967333 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"
15:59:27 INFO - 1499813967335 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"
15:59:27 INFO - 1499813967349 Marionette TRACE 382 <- [1,2,null,{}]
15:59:27 INFO - 1499813967354 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:59:27 INFO - 1499813967366 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"76a2ced6-c6a2-4639-bd11-8d7a2989361e","ELEMENT":"76a2ced6-c6a2-4639-bd11-8d7a2989361e"}}]
15:59:27 INFO - 1499813967367 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"76a2ced6-c6a2-4639-bd11-8d7a2989361e"}]
15:59:27 INFO - 1499813967618 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:59:27 INFO - 1499813967620 Marionette TRACE 382 <- [1,4,null,{}]
15:59:27 INFO - 1499813967622 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}]
15:59:27 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499813967626 Marionette TRACE 382 <- [1,5,null,{"value":null}]
15:59:27 INFO - 1499813967627 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
15:59:27 INFO - 1499813967629 Marionette TRACE 382 <- [1,6,null,{}]
15:59:27 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 392ms
15:59:27 INFO - TEST-START | test_click.py TestClick.test_input_file
15:59:27 INFO - 1499813967632 Marionette DEBUG Closed connection 382
15:59:27 INFO - 1499813967633 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49854
15:59:27 INFO - 1499813967634 Marionette DEBUG Closed connection 383
15:59:27 INFO - 1499813967635 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49855
15:59:27 INFO - 1499813967636 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:27 INFO - 1499813967637 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967641 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967645 Marionette TRACE 384 <- [1,1,null,{"sessionId":"55856afc-afa1-414e-bb7f-bb990f7dd81d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967646 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
15:59:27 INFO - 1499813967647 Marionette TRACE 384 <- [1,2,null,{}]
15:59:27 INFO - 1499813967648 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:59:27 INFO - 1499813967649 Marionette TRACE 384 <- [1,3,null,{}]
15:59:27 INFO - 1499813967650 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
15:59:27 INFO - 1499813967651 Marionette TRACE 384 <- [1,4,null,{}]
15:59:27 INFO - 1499813967653 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}]
15:59:27 INFO - TEST-START: test_click.py TestClick.test_input_file1499813967657 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:59:27 INFO - 1499813967659 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:59:27 INFO - 1499813967661 Marionette TRACE 384 <- [1,6,null,{}]
15:59:27 INFO - 1499813967663 Marionette DEBUG Closed connection 384
15:59:27 INFO - 1499813967664 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49856
15:59:27 INFO - 1499813967665 Marionette DEBUG Closed connection 385
15:59:27 INFO - 1499813967665 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49857
15:59:27 INFO - 1499813967667 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:27 INFO - 1499813967668 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967671 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967675 Marionette TRACE 386 <- [1,1,null,{"sessionId":"63bf8e2a-419c-444b-b304-255e83064ca0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967677 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
15:59:27 INFO - 1499813967679 Marionette TRACE 386 <- [1,2,null,{}]
15:59:27 INFO - 1499813967681 Marionette DEBUG Closed connection 386
15:59:27 INFO - 1499813967682 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49858
15:59:27 INFO - 1499813967683 Marionette DEBUG Closed connection 387
15:59:27 INFO - 1499813967684 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49859
15:59:27 INFO - 1499813967685 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:59:27 INFO - 1499813967686 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967690 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967694 Marionette TRACE 388 <- [1,1,null,{"sessionId":"14d674be-e123-48cc-a121-934607456bb9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967696 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:59:27 INFO - 1499813967698 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"
15:59:27 INFO - 1499813967701 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"
15:59:27 INFO - 1499813967702 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"
15:59:27 INFO - 1499813967715 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:59:27 INFO - 1499813967717 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:59:27 INFO - 1499813967735 Marionette TRACE 388 <- [1,2,null,{}]
15:59:27 INFO - 1499813967749 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:59:27 INFO - 1499813967753 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7cc5cc05-0c53-427f-89af-1b428821e8e3","ELEMENT":"7cc5cc05-0c53-427f-89af-1b428821e8e3"}}]
15:59:27 INFO - 1499813967754 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"7cc5cc05-0c53-427f-89af-1b428821e8e3"}]
15:59:27 INFO - 1499813967786 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]
15:59:27 INFO - 1499813967789 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}]
15:59:27 INFO - TEST-END: test_click.py TestClick.test_input_file1499813967793 Marionette TRACE 388 <- [1,5,null,{"value":null}]
15:59:27 INFO - 1499813967794 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
15:59:27 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 166ms
15:59:27 INFO - 1499813967796 Marionette TRACE 388 <- [1,6,null,{}]
15:59:27 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:59:27 INFO - 1499813967798 Marionette DEBUG Closed connection 388
15:59:27 INFO - 1499813967800 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49860
15:59:27 INFO - 1499813967801 Marionette DEBUG Closed connection 389
15:59:27 INFO - 1499813967802 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49861
15:59:27 INFO - 1499813967803 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:27 INFO - 1499813967804 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967807 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967812 Marionette TRACE 390 <- [1,1,null,{"sessionId":"e39b17fa-ec49-44bf-b1cb-13ef5559f2ec","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967813 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
15:59:27 INFO - 1499813967814 Marionette TRACE 390 <- [1,2,null,{}]
15:59:27 INFO - 1499813967815 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:59:27 INFO - 1499813967816 Marionette TRACE 390 <- [1,3,null,{}]
15:59:27 INFO - 1499813967817 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
15:59:27 INFO - 1499813967817 Marionette TRACE 390 <- [1,4,null,{}]
15:59:27 INFO - 1499813967819 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}]
15:59:27 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499813967824 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:59:27 INFO - 1499813967825 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:59:27 INFO - 1499813967827 Marionette TRACE 390 <- [1,6,null,{}]
15:59:27 INFO - 1499813967829 Marionette DEBUG Closed connection 390
15:59:27 INFO - 1499813967831 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49862
15:59:27 INFO - 1499813967832 Marionette DEBUG Closed connection 391
15:59:27 INFO - 1499813967833 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49863
15:59:27 INFO - 1499813967834 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:27 INFO - 1499813967836 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967841 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967846 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ce3e65a5-b2e3-4912-8bc5-d3748d92ae1a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967848 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
15:59:27 INFO - 1499813967850 Marionette TRACE 392 <- [1,2,null,{}]
15:59:27 INFO - 1499813967852 Marionette DEBUG Closed connection 392
15:59:27 INFO - 1499813967853 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49864
15:59:27 INFO - 1499813967854 Marionette DEBUG Closed connection 393
15:59:27 INFO - 1499813967855 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49865
15:59:27 INFO - 1499813967857 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:59:27 INFO - 1499813967858 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:27 INFO - 1499813967861 Marionette DEBUG Register listener.js for window 4294967297
15:59:27 INFO - 1499813967865 Marionette TRACE 394 <- [1,1,null,{"sessionId":"7c9e9ec1-f1a4-43dc-bf91-c317f833cfd1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:27 INFO - 1499813967867 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"}]
15:59:27 INFO - 1499813967870 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:59:27 INFO - 1499813967873 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:59:27 INFO - 1499813967873 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:59:27 INFO - 1499813967882 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"
15:59:27 INFO - 1499813967885 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"
15:59:27 INFO - 1499813967900 Marionette TRACE 394 <- [1,2,null,{}]
15:59:27 INFO - 1499813967906 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:59:27 INFO - 1499813967916 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a022ce4e-f1e1-4bdc-94e9-28eb71c40b24","ELEMENT":"a022ce4e-f1e1-4bdc-94e9-28eb71c40b24"}}]
15:59:27 INFO - 1499813967917 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:59:27 INFO - 1499813967921 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f5388012-f8f7-4575-aef5-2080bc780e49","ELEMENT":"f5388012-f8f7-4575-aef5-2080bc780e49"}}]
15:59:27 INFO - 1499813967923 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a022ce4e-f1e1-4bdc-94e9-28eb71c40b24"}]
15:59:28 INFO - 1499813968174 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:59:28 INFO - 1499813968176 Marionette TRACE 394 <- [1,5,null,{}]
15:59:28 INFO - 1499813968177 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"f5388012-f8f7-4575-aef5-2080bc780e49"}]
15:59:28 INFO - 1499813968198 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
15:59:28 INFO - 1499813968200 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:59:28 INFO - 1499813968205 Marionette TRACE 394 <- [1,7,null,{"value":false}]
15:59:28 INFO - 1499813968207 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}]
15:59:28 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499813968211 Marionette TRACE 394 <- [1,8,null,{"value":null}]
15:59:28 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 417ms
15:59:28 INFO - 1499813968212 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
15:59:28 INFO - 1499813968214 Marionette TRACE 394 <- [1,9,null,{}]
15:59:28 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:59:28 INFO - 1499813968216 Marionette DEBUG Closed connection 394
15:59:28 INFO - 1499813968218 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49866
15:59:28 INFO - 1499813968219 Marionette DEBUG Closed connection 395
15:59:28 INFO - 1499813968220 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49867
15:59:28 INFO - 1499813968221 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:28 INFO - 1499813968222 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:28 INFO - 1499813968225 Marionette DEBUG Register listener.js for window 4294967297
15:59:28 INFO - 1499813968229 Marionette TRACE 396 <- [1,1,null,{"sessionId":"83e47fba-e56f-4bc6-a96e-26e39719d4a8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:28 INFO - 1499813968231 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
15:59:28 INFO - 1499813968232 Marionette TRACE 396 <- [1,2,null,{}]
15:59:28 INFO - 1499813968233 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:59:28 INFO - 1499813968233 Marionette TRACE 396 <- [1,3,null,{}]
15:59:28 INFO - 1499813968234 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
15:59:28 INFO - 1499813968235 Marionette TRACE 396 <- [1,4,null,{}]
15:59:28 INFO - 1499813968237 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}]
15:59:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499813968242 Marionette TRACE 396 <- [1,5,null,{"value":null}]
15:59:28 INFO - 1499813968243 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
15:59:28 INFO - 1499813968245 Marionette TRACE 396 <- [1,6,null,{}]
15:59:28 INFO - 1499813968246 Marionette DEBUG Closed connection 396
15:59:28 INFO - 1499813968247 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49868
15:59:28 INFO - 1499813968248 Marionette DEBUG Closed connection 397
15:59:28 INFO - 1499813968249 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49869
15:59:28 INFO - 1499813968250 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:59:28 INFO - 1499813968251 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:28 INFO - 1499813968255 Marionette DEBUG Register listener.js for window 4294967297
15:59:28 INFO - 1499813968259 Marionette TRACE 398 <- [1,1,null,{"sessionId":"7cd54f56-76c7-409c-9f89-1352b48aed50","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:28 INFO - 1499813968262 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
15:59:28 INFO - 1499813968264 Marionette TRACE 398 <- [1,2,null,{}]
15:59:28 INFO - 1499813968266 Marionette DEBUG Closed connection 398
15:59:28 INFO - 1499813968267 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49870
15:59:28 INFO - 1499813968268 Marionette DEBUG Closed connection 399
15:59:28 INFO - 1499813968269 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49871
15:59:28 INFO - 1499813968270 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:59:28 INFO - 1499813968271 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}
15:59:28 INFO - 1499813968275 Marionette DEBUG Register listener.js for window 4294967297
15:59:28 INFO - 1499813968279 Marionette TRACE 400 <- [1,1,null,{"sessionId":"773b6efc-3b56-4cc1-abb8-e30106a3d86c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":6004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkcxquw.mozrunner","moz:accessibilityChecks":false}}]
15:59:28 INFO - 1499813968280 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"}]
15:59:28 INFO - 1499813968283 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"
15:59:28 INFO - 1499813968285 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"
15:59:28 INFO - 1499813968286 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"
15:59:28 INFO - 1499813968297 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"
15:59:28 INFO - 1499813968299 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"
15:59:28 INFO - 1499813968314 Marionette TRACE 400 <- [1,2,null,{}]
15:59:28 INFO - 1499813968318 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:59:28 INFO - 1499813968325 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8530345d-f63e-44ad-a351-27ccd0b0b2e5","ELEMENT":"8530345d-f63e-44ad-a351-27ccd0b0b2e5"}}]
15:59:28 INFO - 1499813968333 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8530345d-f63e-44ad-a351-27ccd0b0b2e5"}]
15:59:28 INFO - 1499813968359 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
15:59:28 INFO - 1499813968360 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"8530345d-f63e-44ad-a351-27ccd0b0b2e5"}]
15:59:28 INFO - 1499813968382 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element