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]
13:13:33 INFO - 1500236013700 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}]
13:13:33 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500236013702 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:13:33 INFO - 1500236013703 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:13:33 INFO - 1500236013705 Marionette TRACE 352 <- [1,6,null,{}]
13:13:33 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 146ms
13:13:33 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:13:33 INFO - 1500236013707 Marionette DEBUG Closed connection 352
13:13:33 INFO - 1500236013708 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49774
13:13:33 INFO - 1500236013709 Marionette DEBUG Closed connection 353
13:13:33 INFO - 1500236013709 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49775
13:13:33 INFO - 1500236013710 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:33 INFO - 1500236013711 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:33 INFO - 1500236013714 Marionette DEBUG Register listener.js for window 15
13:13:33 INFO - 1500236013717 Marionette TRACE 354 <- [1,1,null,{"sessionId":"214481a7-dafd-4489-bbea-d66bebd6525f","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:33 INFO - 1500236013719 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:13:33 INFO - 1500236013720 Marionette TRACE 354 <- [1,2,null,{}]
13:13:33 INFO - 1500236013721 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:33 INFO - 1500236013721 Marionette TRACE 354 <- [1,3,null,{}]
13:13:33 INFO - 1500236013722 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:33 INFO - 1500236013723 Marionette TRACE 354 <- [1,4,null,{}]
13:13:33 INFO - 1500236013724 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}]
13:13:33 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500236013727 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:13:33 INFO - 1500236013727 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:13:33 INFO - 1500236013729 Marionette TRACE 354 <- [1,6,null,{}]
13:13:33 INFO - 1500236013731 Marionette DEBUG Closed connection 354
13:13:33 INFO - 1500236013732 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49776
13:13:33 INFO - 1500236013733 Marionette DEBUG Closed connection 355
13:13:33 INFO - 1500236013734 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49777
13:13:33 INFO - 1500236013735 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:33 INFO - 1500236013735 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:33 INFO - 1500236013738 Marionette DEBUG Register listener.js for window 15
13:13:33 INFO - 1500236013741 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f08cfc11-f076-4973-ba26-ecff8ea7679e","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:33 INFO - 1500236013743 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:13:33 INFO - 1500236013745 Marionette TRACE 356 <- [1,2,null,{}]
13:13:33 INFO - 1500236013747 Marionette DEBUG Closed connection 356
13:13:33 INFO - 1500236013747 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49778
13:13:33 INFO - 1500236013748 Marionette DEBUG Closed connection 357
13:13:33 INFO - 1500236013749 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49779
13:13:33 INFO - 1500236013750 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:33 INFO - 1500236013751 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:33 INFO - 1500236013753 Marionette DEBUG Register listener.js for window 15
13:13:33 INFO - 1500236013757 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b3438323-d161-451d-9eeb-a07164bd6705","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:33 INFO - 1500236013760 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49338/clicks.html"}]
13:13:33 INFO - 1500236013761 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/hidden.html"
13:13:33 INFO - 1500236013766 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/hidden.html"
13:13:33 INFO - 1500236013766 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/hidden.html"
13:13:33 INFO - 1500236013791 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49338/clicks.html"
13:13:33 INFO - 1500236013792 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49338/clicks.html"
13:13:33 INFO - 1500236013796 Marionette TRACE 358 <- [1,2,null,{}]
13:13:33 INFO - 1500236013797 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:13:33 INFO - 1500236013806 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f0a3ea9e-fe83-4f77-b89b-d59b61158a5f","ELEMENT":"f0a3ea9e-fe83-4f77-b89b-d59b61158a5f"}}]
13:13:33 INFO - 1500236013808 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f0a3ea9e-fe83-4f77-b89b-d59b61158a5f"}]
13:13:33 INFO - 1500236013861 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/clicks.html"
13:13:33 INFO - 1500236013871 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/clicks.html"
13:13:33 INFO - 1500236013871 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/clicks.html"
13:13:33 INFO - 1500236013892 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49338/test.html"
13:13:33 INFO - 1500236013893 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49338/test.html"
13:13:33 INFO - 1500236013896 Marionette TRACE 358 <- [1,4,null,{}]
13:13:33 INFO - 1500236013897 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:13:33 INFO - 1500236013905 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d5378368-b862-4251-ac8d-a10ab6bb66ad","ELEMENT":"d5378368-b862-4251-ac8d-a10ab6bb66ad"}}]
13:13:33 INFO - 1500236013907 Marionette TRACE 358 -> [0,6,"getTitle",{}]
13:13:33 INFO - 1500236013907 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
13:13:33 INFO - 1500236013909 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}]
13:13:33 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500236013912 Marionette TRACE 358 <- [1,7,null,{"value":null}]
13:13:33 INFO - 1500236013913 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
13:13:33 INFO - 1500236013914 Marionette TRACE 358 <- [1,8,null,{}]
13:13:33 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 209ms
13:13:33 INFO - TEST-START | test_click.py TestClick.test_container_element
13:13:33 INFO - 1500236013917 Marionette DEBUG Closed connection 358
13:13:33 INFO - 1500236013918 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49782
13:13:33 INFO - 1500236013919 Marionette DEBUG Closed connection 359
13:13:33 INFO - 1500236013920 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49783
13:13:33 INFO - 1500236013921 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:33 INFO - 1500236013921 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:33 INFO - 1500236013924 Marionette DEBUG Register listener.js for window 15
13:13:33 INFO - 1500236013927 Marionette TRACE 360 <- [1,1,null,{"sessionId":"c75f458c-8e11-4cf2-a293-58dac36f15ee","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:33 INFO - 1500236013929 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:13:33 INFO - 1500236013930 Marionette TRACE 360 <- [1,2,null,{}]
13:13:33 INFO - 1500236013931 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:33 INFO - 1500236013931 Marionette TRACE 360 <- [1,3,null,{}]
13:13:33 INFO - 1500236013932 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:33 INFO - 1500236013933 Marionette TRACE 360 <- [1,4,null,{}]
13:13:33 INFO - 1500236013934 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}]
13:13:33 INFO - TEST-START: test_click.py TestClick.test_container_element1500236013937 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:13:33 INFO - 1500236013938 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:13:33 INFO - 1500236013939 Marionette TRACE 360 <- [1,6,null,{}]
13:13:33 INFO - 1500236013941 Marionette DEBUG Closed connection 360
13:13:33 INFO - 1500236013942 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49784
13:13:33 INFO - 1500236013943 Marionette DEBUG Closed connection 361
13:13:33 INFO - 1500236013943 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49785
13:13:33 INFO - 1500236013944 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:33 INFO - 1500236013945 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:33 INFO - 1500236013948 Marionette DEBUG Register listener.js for window 15
13:13:33 INFO - 1500236013951 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5efb0d10-1ff2-440d-9a95-c70cb7c722cd","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:33 INFO - 1500236013953 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
13:13:33 INFO - 1500236013954 Marionette TRACE 362 <- [1,2,null,{}]
13:13:33 INFO - 1500236013956 Marionette DEBUG Closed connection 362
13:13:33 INFO - 1500236013957 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49786
13:13:33 INFO - 1500236013958 Marionette DEBUG Closed connection 363
13:13:33 INFO - 1500236013959 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49787
13:13:33 INFO - 1500236013960 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:33 INFO - 1500236013960 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:33 INFO - 1500236013963 Marionette DEBUG Register listener.js for window 15
13:13:33 INFO - 1500236013966 Marionette TRACE 364 <- [1,1,null,{"sessionId":"416728bc-bbe4-4f4f-a78b-9ae20c588bc8","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:33 INFO - 1500236013968 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"}]
13:13:33 INFO - 1500236013969 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/test.html"
13:13:33 INFO - 1500236013971 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/test.html"
13:13:33 INFO - 1500236013972 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/test.html"
13:13:33 INFO - 1500236013993 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"
13:13:34 INFO - 1500236013997 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"
13:13:34 INFO - 1500236014001 Marionette TRACE 364 <- [1,2,null,{}]
13:13:34 INFO - 1500236014008 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:13:34 INFO - 1500236014011 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cac38287-0ff6-4b9a-8fe5-8fc4f27464c9","ELEMENT":"cac38287-0ff6-4b9a-8fe5-8fc4f27464c9"}}]
13:13:34 INFO - 1500236014012 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"cac38287-0ff6-4b9a-8fe5-8fc4f27464c9"}]
13:13:34 INFO - 1500236014254 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:34 INFO - 1500236014255 Marionette TRACE 364 <- [1,4,null,{}]
13:13:34 INFO - 1500236014256 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"cac38287-0ff6-4b9a-8fe5-8fc4f27464c9","name":"selected"}]
13:13:34 INFO - 1500236014260 Marionette TRACE 364 <- [1,5,null,{"value":true}]
13:13:34 INFO - 1500236014262 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}]
13:13:34 INFO - TEST-END: test_click.py TestClick.test_container_element1500236014264 Marionette TRACE 364 <- [1,6,null,{"value":null}]
13:13:34 INFO - 1500236014265 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
13:13:34 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 352ms
13:13:34 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:13:34 INFO - 1500236014267 Marionette TRACE 364 <- [1,7,null,{}]
13:13:34 INFO - 1500236014269 Marionette DEBUG Closed connection 364
13:13:34 INFO - 1500236014270 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49788
13:13:34 INFO - 1500236014270 Marionette DEBUG Closed connection 365
13:13:34 INFO - 1500236014271 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49789
13:13:34 INFO - 1500236014272 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500236014273 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500236014275 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500236014278 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4c0aa8d1-15c5-4f54-bfc0-de6037bbefaa","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500236014280 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
13:13:34 INFO - 1500236014281 Marionette TRACE 366 <- [1,2,null,{}]
13:13:34 INFO - 1500236014282 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:34 INFO - 1500236014282 Marionette TRACE 366 <- [1,3,null,{}]
13:13:34 INFO - 1500236014284 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:34 INFO - 1500236014285 Marionette TRACE 366 <- [1,4,null,{}]
13:13:34 INFO - 1500236014287 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}]
13:13:34 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500236014289 Marionette TRACE 366 <- [1,5,null,{"value":null}]
13:13:34 INFO - 1500236014290 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
13:13:34 INFO - 1500236014292 Marionette TRACE 366 <- [1,6,null,{}]
13:13:34 INFO - 1500236014294 Marionette DEBUG Closed connection 366
13:13:34 INFO - 1500236014294 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49790
13:13:34 INFO - 1500236014295 Marionette DEBUG Closed connection 367
13:13:34 INFO - 1500236014296 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49791
13:13:34 INFO - 1500236014297 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500236014297 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500236014300 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500236014304 Marionette TRACE 368 <- [1,1,null,{"sessionId":"817bfc48-a74b-4e0a-8e7a-bbf7b243c212","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500236014306 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
13:13:34 INFO - 1500236014307 Marionette TRACE 368 <- [1,2,null,{}]
13:13:34 INFO - 1500236014309 Marionette DEBUG Closed connection 368
13:13:34 INFO - 1500236014310 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49792
13:13:34 INFO - 1500236014311 Marionette DEBUG Closed connection 369
13:13:34 INFO - 1500236014311 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49793
13:13:34 INFO - 1500236014312 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:34 INFO - 1500236014313 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500236014316 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500236014327 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c65b9f9b-994c-4e0e-b3d4-0aa7fb4e3c6b","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500236014329 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"}]
13:13:34 INFO - 1500236014331 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"
13:13:34 INFO - 1500236014333 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"
13:13:34 INFO - 1500236014333 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"
13:13:34 INFO - 1500236014353 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"
13:13:34 INFO - 1500236014355 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"
13:13:34 INFO - 1500236014359 Marionette TRACE 370 <- [1,2,null,{}]
13:13:34 INFO - 1500236014360 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:13:34 INFO - 1500236014363 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8dcc8e17-aca3-4ddf-99e2-e60fe6022266","ELEMENT":"8dcc8e17-aca3-4ddf-99e2-e60fe6022266"}}]
13:13:34 INFO - 1500236014363 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"8dcc8e17-aca3-4ddf-99e2-e60fe6022266"}]
13:13:34 INFO - 1500236014618 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:34 INFO - 1500236014619 Marionette TRACE 370 <- [1,4,null,{}]
13:13:34 INFO - 1500236014620 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"8dcc8e17-aca3-4ddf-99e2-e60fe6022266","name":"selected"}]
13:13:34 INFO - 1500236014624 Marionette TRACE 370 <- [1,5,null,{"value":true}]
13:13:34 INFO - 1500236014626 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}]
13:13:34 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500236014629 Marionette TRACE 370 <- [1,6,null,{"value":null}]
13:13:34 INFO - 1500236014630 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
13:13:34 INFO - 1500236014631 Marionette TRACE 370 <- [1,7,null,{}]
13:13:34 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 364ms
13:13:34 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:13:34 INFO - 1500236014633 Marionette DEBUG Closed connection 370
13:13:34 INFO - 1500236014634 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49794
13:13:34 INFO - 1500236014634 Marionette DEBUG Closed connection 371
13:13:34 INFO - 1500236014635 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49795
13:13:34 INFO - 1500236014636 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500236014637 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500236014639 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500236014642 Marionette TRACE 372 <- [1,1,null,{"sessionId":"55364393-bf11-4bde-a915-d03fc6d57012","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500236014644 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
13:13:34 INFO - 1500236014645 Marionette TRACE 372 <- [1,2,null,{}]
13:13:34 INFO - 1500236014646 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:34 INFO - 1500236014646 Marionette TRACE 372 <- [1,3,null,{}]
13:13:34 INFO - 1500236014647 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:34 INFO - 1500236014648 Marionette TRACE 372 <- [1,4,null,{}]
13:13:34 INFO - 1500236014649 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}]
13:13:34 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500236014651 Marionette TRACE 372 <- [1,5,null,{"value":null}]
13:13:34 INFO - 1500236014652 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
13:13:34 INFO - 1500236014653 Marionette TRACE 372 <- [1,6,null,{}]
13:13:34 INFO - 1500236014656 Marionette DEBUG Closed connection 372
13:13:34 INFO - 1500236014656 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49796
13:13:34 INFO - 1500236014657 Marionette DEBUG Closed connection 373
13:13:34 INFO - 1500236014657 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49797
13:13:34 INFO - 1500236014658 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500236014659 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500236014662 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500236014665 Marionette TRACE 374 <- [1,1,null,{"sessionId":"3942f26c-369c-4755-80cd-7453adb264d7","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500236014667 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
13:13:34 INFO - 1500236014668 Marionette TRACE 374 <- [1,2,null,{}]
13:13:34 INFO - 1500236014670 Marionette DEBUG Closed connection 374
13:13:34 INFO - 1500236014671 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49798
13:13:34 INFO - 1500236014671 Marionette DEBUG Closed connection 375
13:13:34 INFO - 1500236014672 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49799
13:13:34 INFO - 1500236014673 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:34 INFO - 1500236014674 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500236014676 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500236014679 Marionette TRACE 376 <- [1,1,null,{"sessionId":"d3738416-27e1-4223-a98d-de7f83518167","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500236014682 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"}]
13:13:34 INFO - 1500236014683 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"
13:13:34 INFO - 1500236014685 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"
13:13:34 INFO - 1500236014686 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"
13:13:34 INFO - 1500236014698 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"
13:13:34 INFO - 1500236014702 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"
13:13:34 INFO - 1500236014711 Marionette TRACE 376 <- [1,2,null,{}]
13:13:34 INFO - 1500236014715 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:13:34 INFO - 1500236014717 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"382f8a43-17d7-42c3-beb1-75f469309645","ELEMENT":"382f8a43-17d7-42c3-beb1-75f469309645"}}]
13:13:34 INFO - 1500236014718 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"382f8a43-17d7-42c3-beb1-75f469309645"}]
13:13:34 INFO - 1500236014964 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:34 INFO - 1500236014965 Marionette TRACE 376 <- [1,4,null,{}]
13:13:34 INFO - 1500236014967 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}]
13:13:34 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500236014970 Marionette TRACE 376 <- [1,5,null,{"value":null}]
13:13:34 INFO - 1500236014971 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
13:13:34 INFO - 1500236014972 Marionette TRACE 376 <- [1,6,null,{}]
13:13:34 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 341ms
13:13:34 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:13:34 INFO - 1500236014974 Marionette DEBUG Closed connection 376
13:13:34 INFO - 1500236014975 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49800
13:13:34 INFO - 1500236014976 Marionette DEBUG Closed connection 377
13:13:34 INFO - 1500236014976 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49801
13:13:34 INFO - 1500236014977 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500236014978 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500236014980 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500236014984 Marionette TRACE 378 <- [1,1,null,{"sessionId":"2f4ab96a-82f0-4d2d-b74b-1fdb60f41beb","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500236014986 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
13:13:34 INFO - 1500236014986 Marionette TRACE 378 <- [1,2,null,{}]
13:13:34 INFO - 1500236014987 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:34 INFO - 1500236014987 Marionette TRACE 378 <- [1,3,null,{}]
13:13:34 INFO - 1500236014988 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:34 INFO - 1500236014989 Marionette TRACE 378 <- [1,4,null,{}]
13:13:34 INFO - 1500236014990 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}]
13:13:34 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500236014993 Marionette TRACE 378 <- [1,5,null,{"value":null}]
13:13:34 INFO - 1500236014994 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
13:13:34 INFO - 1500236014995 Marionette TRACE 378 <- [1,6,null,{}]
13:13:34 INFO - 1500236014997 Marionette DEBUG Closed connection 378
13:13:35 INFO - 1500236014998 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49802
13:13:35 INFO - 1500236014999 Marionette DEBUG Closed connection 379
13:13:35 INFO - 1500236014999 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49803
13:13:35 INFO - 1500236015001 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500236015001 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015004 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015007 Marionette TRACE 380 <- [1,1,null,{"sessionId":"e3ef91c4-2339-4651-92f5-675eeb8648f8","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015009 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
13:13:35 INFO - 1500236015010 Marionette TRACE 380 <- [1,2,null,{}]
13:13:35 INFO - 1500236015012 Marionette DEBUG Closed connection 380
13:13:35 INFO - 1500236015012 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49804
13:13:35 INFO - 1500236015013 Marionette DEBUG Closed connection 381
13:13:35 INFO - 1500236015014 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49805
13:13:35 INFO - 1500236015015 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:35 INFO - 1500236015015 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015018 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015021 Marionette TRACE 382 <- [1,1,null,{"sessionId":"309a0486-d5e1-498d-8286-e51c88d0fb0c","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015023 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"}]
13:13:35 INFO - 1500236015025 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"
13:13:35 INFO - 1500236015027 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"
13:13:35 INFO - 1500236015027 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"
13:13:35 INFO - 1500236015046 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"
13:13:35 INFO - 1500236015050 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"
13:13:35 INFO - 1500236015058 Marionette TRACE 382 <- [1,2,null,{}]
13:13:35 INFO - 1500236015060 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:13:35 INFO - 1500236015062 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8bcbaf66-4dad-42c3-ac22-b570a722e901","ELEMENT":"8bcbaf66-4dad-42c3-ac22-b570a722e901"}}]
13:13:35 INFO - 1500236015063 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"8bcbaf66-4dad-42c3-ac22-b570a722e901"}]
13:13:35 INFO - 1500236015306 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:35 INFO - 1500236015307 Marionette TRACE 382 <- [1,4,null,{}]
13:13:35 INFO - 1500236015309 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}]
13:13:35 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500236015311 Marionette TRACE 382 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500236015312 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
13:13:35 INFO - 1500236015314 Marionette TRACE 382 <- [1,6,null,{}]
13:13:35 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 342ms
13:13:35 INFO - TEST-START | test_click.py TestClick.test_input_file
13:13:35 INFO - 1500236015316 Marionette DEBUG Closed connection 382
13:13:35 INFO - 1500236015317 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49806
13:13:35 INFO - 1500236015318 Marionette DEBUG Closed connection 383
13:13:35 INFO - 1500236015318 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49807
13:13:35 INFO - 1500236015319 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500236015320 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015323 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015326 Marionette TRACE 384 <- [1,1,null,{"sessionId":"174b03ae-fa0f-420c-9129-f7e3cded0292","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015328 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
13:13:35 INFO - 1500236015328 Marionette TRACE 384 <- [1,2,null,{}]
13:13:35 INFO - 1500236015329 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:35 INFO - 1500236015330 Marionette TRACE 384 <- [1,3,null,{}]
13:13:35 INFO - 1500236015331 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:35 INFO - 1500236015331 Marionette TRACE 384 <- [1,4,null,{}]
13:13:35 INFO - 1500236015333 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}]
13:13:35 INFO - TEST-START: test_click.py TestClick.test_input_file1500236015335 Marionette TRACE 384 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500236015336 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
13:13:35 INFO - 1500236015338 Marionette TRACE 384 <- [1,6,null,{}]
13:13:35 INFO - 1500236015340 Marionette DEBUG Closed connection 384
13:13:35 INFO - 1500236015340 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49808
13:13:35 INFO - 1500236015341 Marionette DEBUG Closed connection 385
13:13:35 INFO - 1500236015342 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49809
13:13:35 INFO - 1500236015343 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500236015343 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015346 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015349 Marionette TRACE 386 <- [1,1,null,{"sessionId":"86112426-9ffe-48d8-801b-0aeee9b6f248","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015351 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
13:13:35 INFO - 1500236015352 Marionette TRACE 386 <- [1,2,null,{}]
13:13:35 INFO - 1500236015354 Marionette DEBUG Closed connection 386
13:13:35 INFO - 1500236015355 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49810
13:13:35 INFO - 1500236015356 Marionette DEBUG Closed connection 387
13:13:35 INFO - 1500236015356 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49811
13:13:35 INFO - 1500236015357 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:35 INFO - 1500236015358 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015360 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015363 Marionette TRACE 388 <- [1,1,null,{"sessionId":"b51066aa-f396-4f01-8d4c-62662238f668","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015365 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:13:35 INFO - 1500236015366 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"
13:13:35 INFO - 1500236015369 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"
13:13:35 INFO - 1500236015369 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"
13:13:35 INFO - 1500236015380 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:35 INFO - 1500236015391 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:35 INFO - 1500236015394 Marionette TRACE 388 <- [1,2,null,{}]
13:13:35 INFO - 1500236015396 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:13:35 INFO - 1500236015399 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f096330-14fa-456e-a40b-75093378d939","ELEMENT":"6f096330-14fa-456e-a40b-75093378d939"}}]
13:13:35 INFO - 1500236015405 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6f096330-14fa-456e-a40b-75093378d939"}]
13:13:35 INFO - 1500236015440 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]
13:13:35 INFO - 1500236015442 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}]
13:13:35 INFO - TEST-END: test_click.py TestClick.test_input_file1500236015444 Marionette TRACE 388 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500236015446 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
13:13:35 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 133ms
13:13:35 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:13:35 INFO - 1500236015447 Marionette TRACE 388 <- [1,6,null,{}]
13:13:35 INFO - 1500236015449 Marionette DEBUG Closed connection 388
13:13:35 INFO - 1500236015450 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49812
13:13:35 INFO - 1500236015451 Marionette DEBUG Closed connection 389
13:13:35 INFO - 1500236015452 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49813
13:13:35 INFO - 1500236015453 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500236015453 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015456 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015459 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d19ace36-5f3a-4af2-a130-8262d7d8950a","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015461 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
13:13:35 INFO - 1500236015462 Marionette TRACE 390 <- [1,2,null,{}]
13:13:35 INFO - 1500236015463 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:35 INFO - 1500236015463 Marionette TRACE 390 <- [1,3,null,{}]
13:13:35 INFO - 1500236015464 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:35 INFO - 1500236015464 Marionette TRACE 390 <- [1,4,null,{}]
13:13:35 INFO - 1500236015466 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}]
13:13:35 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500236015469 Marionette TRACE 390 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500236015470 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
13:13:35 INFO - 1500236015471 Marionette TRACE 390 <- [1,6,null,{}]
13:13:35 INFO - 1500236015473 Marionette DEBUG Closed connection 390
13:13:35 INFO - 1500236015474 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49814
13:13:35 INFO - 1500236015475 Marionette DEBUG Closed connection 391
13:13:35 INFO - 1500236015475 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49815
13:13:35 INFO - 1500236015476 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500236015477 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015479 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015483 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d3ff0be4-fa6f-4cfb-849c-f96368305dbc","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015485 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
13:13:35 INFO - 1500236015486 Marionette TRACE 392 <- [1,2,null,{}]
13:13:35 INFO - 1500236015488 Marionette DEBUG Closed connection 392
13:13:35 INFO - 1500236015489 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49816
13:13:35 INFO - 1500236015489 Marionette DEBUG Closed connection 393
13:13:35 INFO - 1500236015490 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49817
13:13:35 INFO - 1500236015491 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:35 INFO - 1500236015492 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015494 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015497 Marionette TRACE 394 <- [1,1,null,{"sessionId":"be16b992-beb9-4aed-b326-0b1472178404","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015499 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"}]
13:13:35 INFO - 1500236015500 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:35 INFO - 1500236015503 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:35 INFO - 1500236015503 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:35 INFO - 1500236015514 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"
13:13:35 INFO - 1500236015518 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"
13:13:35 INFO - 1500236015527 Marionette TRACE 394 <- [1,2,null,{}]
13:13:35 INFO - 1500236015529 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:13:35 INFO - 1500236015531 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5b54d6d6-d558-4a47-bb65-6b3438af5174","ELEMENT":"5b54d6d6-d558-4a47-bb65-6b3438af5174"}}]
13:13:35 INFO - 1500236015533 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:13:35 INFO - 1500236015535 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"38a8287f-9d1a-478e-a71d-98b8d4034141","ELEMENT":"38a8287f-9d1a-478e-a71d-98b8d4034141"}}]
13:13:35 INFO - 1500236015537 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"5b54d6d6-d558-4a47-bb65-6b3438af5174"}]
13:13:35 INFO - 1500236015793 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:35 INFO - 1500236015794 Marionette TRACE 394 <- [1,5,null,{}]
13:13:35 INFO - 1500236015796 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"38a8287f-9d1a-478e-a71d-98b8d4034141"}]
13:13:35 INFO - 1500236015816 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:13:35 INFO - 1500236015818 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:13:35 INFO - 1500236015823 Marionette TRACE 394 <- [1,7,null,{"value":false}]
13:13:35 INFO - 1500236015825 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}]
13:13:35 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500236015828 Marionette TRACE 394 <- [1,8,null,{"value":null}]
13:13:35 INFO - 1500236015830 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
13:13:35 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 385ms
13:13:35 INFO - 1500236015833 Marionette TRACE 394 <- [1,9,null,{}]
13:13:35 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:13:35 INFO - 1500236015909 Marionette DEBUG Closed connection 394
13:13:35 INFO - 1500236015910 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49818
13:13:35 INFO - 1500236015912 Marionette DEBUG Closed connection 395
13:13:35 INFO - 1500236015913 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49819
13:13:35 INFO - 1500236015914 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500236015918 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015921 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015929 Marionette TRACE 396 <- [1,1,null,{"sessionId":"4c81cea2-02ed-4a18-9252-46d90a65bae7","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015947 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
13:13:35 INFO - 1500236015948 Marionette TRACE 396 <- [1,2,null,{}]
13:13:35 INFO - 1500236015950 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:35 INFO - 1500236015951 Marionette TRACE 396 <- [1,3,null,{}]
13:13:35 INFO - 1500236015953 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:35 INFO - 1500236015954 Marionette TRACE 396 <- [1,4,null,{}]
13:13:35 INFO - 1500236015956 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}]
13:13:35 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500236015960 Marionette TRACE 396 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500236015961 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
13:13:35 INFO - 1500236015964 Marionette TRACE 396 <- [1,6,null,{}]
13:13:35 INFO - 1500236015968 Marionette DEBUG Closed connection 396
13:13:35 INFO - 1500236015969 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49820
13:13:35 INFO - 1500236015970 Marionette DEBUG Closed connection 397
13:13:35 INFO - 1500236015971 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49821
13:13:35 INFO - 1500236015973 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500236015974 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500236015977 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500236015982 Marionette TRACE 398 <- [1,1,null,{"sessionId":"52099999-1d4a-4193-88ea-5736febe2e14","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500236015989 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
13:13:35 INFO - 1500236015991 Marionette TRACE 398 <- [1,2,null,{}]
13:13:35 INFO - 1500236015995 Marionette DEBUG Closed connection 398
13:13:35 INFO - 1500236015995 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49822
13:13:35 INFO - 1500236015996 Marionette DEBUG Closed connection 399
13:13:36 INFO - 1500236015997 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49823
13:13:36 INFO - 1500236015998 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:36 INFO - 1500236016000 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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500236016002 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500236016007 Marionette TRACE 400 <- [1,1,null,{"sessionId":"92a8c924-13ac-4241-bebf-4ae2cd54b7d8","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":2964,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-127.001\\AppData\\Local\\Temp\\tmp7rfx4h.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500236016010 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"}]
13:13:36 INFO - 1500236016012 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"
13:13:36 INFO - 1500236016017 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"
13:13:36 INFO - 1500236016017 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"
13:13:36 INFO - 1500236016043 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"
13:13:36 INFO - 1500236016046 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"
13:13:36 INFO - 1500236016051 Marionette TRACE 400 <- [1,2,null,{}]
13:13:36 INFO - 1500236016066 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:13:36 INFO - 1500236016071 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"33051869-ec4e-477f-86e2-823600134bfa","ELEMENT":"33051869-ec4e-477f-86e2-823600134bfa"}}]
13:13:36 INFO - 1500236016072 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"33051869-ec4e-477f-86e2-823600134bfa"}]
13:13:36 INFO - 1500236016100 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
13:13:36 INFO - 1500236016102 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"33051869-ec4e-477f-86e2-823600134bfa"}]
13:13:36 INFO - 1500236016122 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element